Вот логи открытия сесси:
/var/log/messages:
Код: Выделить всё
Dec 3 22:56:40 localhost pptpd[7123]: CTRL: Client 192.168.1.4 control connection started
Dec 3 22:56:40 localhost pptpd[7123]: CTRL: Starting call (launching pppd, opening GRE)
Dec 3 22:56:40 localhost pppd[7124]: Plugin radius.so loaded.
Dec 3 22:56:40 localhost pppd[7124]: RADIUS plugin initialized.
Dec 3 22:56:40 localhost pppd[7124]: Plugin radattr.so loaded.
Dec 3 22:56:40 localhost pppd[7124]: RADATTR plugin initialized.
Dec 3 22:56:40 localhost pppd[7124]: Plugin /usr/lib/pptpd/pptpd-logwtmp.so loaded.
Dec 3 22:56:40 localhost pppd[7124]: pptpd-logwtmp: $Version$
Dec 3 22:56:40 localhost pppd[7124]: pppd 2.4.4 started by root, uid 0
Dec 3 22:56:40 localhost pppd[7124]: Using interface ppp0
Dec 3 22:56:40 localhost pppd[7124]: Connect: ppp0 <--> /dev/pts/0
Dec 3 22:56:40 localhost pptpd[7123]: GRE: Bad checksum from pppd.
Dec 3 22:56:42 localhost pptpd[7123]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Dec 3 22:56:43 localhost pppd[7124]: MPPE 128-bit stateless compression enabled
Dec 3 22:56:46 localhost pppd[7124]: local IP address 192.168.17.1
Dec 3 22:56:46 localhost pppd[7124]: remote IP address 192.168.17.4
Dec 3 22:56:46 localhost pppd[7124]: pptpd-logwtmp.so ip-up ppp0 test2 192.168.1.4
Dec 3 22:56:46 localhost pppd[7124]: remote IP address 192.168.17.4
Dec 3 22:56:46 localhost pppd[7124]: pptpd-logwtmp.so ip-up ppp0 test2 192.168.1.4
Код: Выделить всё
rad_recv: Accounting-Request packet from host 127.0.0.1:52099, id=2, length=139
Acct-Session-Id = "4B1815E4171A00"
User-Name = "test2"
Acct-Status-Type = Stop
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 500
Acct-Output-Octets = 0
Acct-Input-Octets = 3283
Acct-Output-Packets = 0
Acct-Input-Packets = 28
Calling-Station-Id = ".291"
NAS-Port-Type = Async
Acct-Terminate-Cause = User-Request
Framed-IP-Address = 192.168.17.86
NAS-IP-Address = 127.0.0.1
NAS-Port = 0
Acct-Delay-Time = 0
Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 0
modcall[preacct]: module "preprocess" returns noop for request 0
rlm_acct_unique: Hashing 'NAS-Port = 0,Client-IP-Address = 127.0.0.1,NAS-IP-Address = 127.0.0.1,Acct-Session-Id = "4B1815E4171A00",User-Name = "test2"'
rlm_acct_unique: Acct-Unique-Session-ID = "9b08405148d15b30".
modcall[preacct]: module "acct_unique" returns ok for request 0
rlm_realm: No '@' in User-Name = "test2", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 0
acct_users: Matched entry DEFAULT at line 23
modcall[preacct]: module "files" returns ok for request 0
modcall: leaving group preacct (returns ok) for request 0
Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 0
radius_xlat: '/var/log/radius/radacct/127.0.0.1/detail-20091203'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/127.0.0.1/detail-20091203
modcall[accounting]: module "detail" returns ok for request 0
modcall[accounting]: module "unix" returns ok for request 0
radius_xlat: '/var/log/radius/radutmp'
radius_xlat: 'test2'
modcall[accounting]: module "radutmp" returns ok for request 0
modcall: leaving group accounting (returns ok) for request 0
radius_xlat: '/usr/abills/libexec/racct.pl'
Exec-Program: /usr/abills/libexec/racct.pl
Exec-Program output:
Exec-Program: returned: 0
Sending Accounting-Response of id 2 to 127.0.0.1 port 52099
Finished request 0
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 2 with timestamp 4b1817ed
Nothing to do. Sleeping until we see a request.
rad_recv: Access-Request packet from host 127.0.0.1:47700, id=3, length=139
Service-Type = Framed-User
Framed-Protocol = PPP
User-Name = "test2"
MS-CHAP-Challenge = 0x42a106b1c3067239eb316684eaaeadb7
MS-CHAP2-Response = 0xdf005436a5d8016b40454ddf2aad98db12cf0000000000000000fc4b110dd5cb847c09d857718f9e7d0f6d0e4064fea494d5
Calling-Station-Id = ".291"
NAS-IP-Address = 127.0.0.1
NAS-Port = 0
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
radius_xlat: '/usr/abills/libexec/rauth.pl pre_auth'
Exec-Program: /usr/abills/libexec/rauth.pl pre_auth
Exec-Program output: User-Password == "123456"
Exec-Program-Wait: value-pairs: User-Password == "123456"
Exec-Program: returned: 0
modcall[authorize]: module "pre_auth" returns ok for request 1
modcall[authorize]: module "preprocess" returns ok for request 1
rlm_mschap: Found MS-CHAP attributes. Setting 'Auth-Type = mschap'
modcall[authorize]: module "mschap" returns ok for request 1
rlm_realm: No '@' in User-Name = "test2", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 1
users: Matched entry DEFAULT at line 154
modcall[authorize]: module "files" returns ok for request 1
modcall: leaving group authorize (returns ok) for request 1
rad_check_password: Found Auth-Type MS-CHAP
auth: type "MS-CHAP"
Processing the authenticate section of radiusd.conf
modcall: entering group MS-CHAP for request 1
rlm_mschap: Told to do MS-CHAPv2 for test2 with NT-Password
rlm_mschap: adding MS-CHAPv2 MPPE keys
modcall[authenticate]: module "mschap" returns ok for request 1
modcall: leaving group MS-CHAP (returns ok) for request 1
radius_xlat: '/usr/abills/libexec/rauth.pl'
Exec-Program: /usr/abills/libexec/rauth.pl
Exec-Program output: Acct-Interim-Interval = 60, Session-Timeout = 2422997, Filter-Id = 2222, Framed-IP-Address = 192.168.17.4, Framed-IP-Netmask = 255.255.255.255,
Exec-Program-Wait: value-pairs: Acct-Interim-Interval = 60, Session-Timeout = 2422997, Filter-Id = 2222, Framed-IP-Address = 192.168.17.4, Framed-IP-Netmask = 255.255.255.255,
Exec-Program: returned: 0
Sending Access-Accept of id 3 to 127.0.0.1 port 47700
MS-CHAP2-Success = 0xdf533d42443144393437323732363037374646354139393832374543373434333531344344443138373030
MS-MPPE-Recv-Key = 0xf1bbe180b142d8ff2bed4601c456d72e
MS-MPPE-Send-Key = 0x642df86a267b925ea73662b96590563e
MS-MPPE-Encryption-Policy = 0x00000001
MS-MPPE-Encryption-Types = 0x00000006
Acct-Interim-Interval = 60
Session-Timeout = 2422997
Filter-Id = "2222"
Framed-IP-Address = 192.168.17.4
Framed-IP-Netmask = 255.255.255.255
Finished request 1
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 127.0.0.1:52251, id=4, length=103
Acct-Session-Id = "4B1817FE1BD400"
User-Name = "test2"
Acct-Status-Type = Start
Service-Type = Framed-User
Framed-Protocol = PPP
Calling-Station-Id = ".291"
Acct-Authentic = RADIUS
NAS-Port-Type = Async
Framed-IP-Address = 192.168.17.4
NAS-IP-Address = 127.0.0.1
NAS-Port = 0
Acct-Delay-Time = 0
Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 2
modcall[preacct]: module "preprocess" returns noop for request 2
rlm_acct_unique: Hashing 'NAS-Port = 0,Client-IP-Address = 127.0.0.1,NAS-IP-Address = 127.0.0.1,Acct-Session-Id = "4B1817FE1BD400",User-Name = "test2"'
rlm_acct_unique: Acct-Unique-Session-ID = "c8c3532d90d8c57b".
modcall[preacct]: module "acct_unique" returns ok for request 2
rlm_realm: No '@' in User-Name = "test2", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 2
acct_users: Matched entry DEFAULT at line 17
modcall[preacct]: module "files" returns ok for request 2
modcall: leaving group preacct (returns ok) for request 2
Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 2
radius_xlat: '/var/log/radius/radacct/127.0.0.1/detail-20091203'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/127.0.0.1/detail-20091203
modcall[accounting]: module "detail" returns ok for request 2
modcall[accounting]: module "unix" returns ok for request 2
radius_xlat: '/var/log/radius/radutmp'
radius_xlat: 'test2'
modcall[accounting]: module "radutmp" returns ok for request 2
modcall: leaving group accounting (returns ok) for request 2
radius_xlat: '/usr/abills/libexec/racct.pl'
Exec-Program: /usr/abills/libexec/racct.pl
Exec-Program output:
Exec-Program: returned: 0
Sending Accounting-Response of id 4 to 127.0.0.1 port 52251
Finished request 2
Going to the next request
--- Walking the entire request list ---
Waking up in 2 seconds...
--- Walking the entire request list ---
Cleaning up request 1 ID 3 with timestamp 4b1817fa
Waking up in 4 seconds...
--- Walking the entire request list ---
Cleaning up request 2 ID 4 with timestamp 4b1817fe
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 127.0.0.1:33037, id=5, length=133
Acct-Session-Id = "4B1817FE1BD400"
User-Name = "test2"
Acct-Status-Type = Interim-Update
Service-Type = Framed-User
Framed-Protocol = PPP
Acct-Authentic = RADIUS
Acct-Session-Time = 62
Acct-Output-Octets = 0
Acct-Input-Octets = 1746
Acct-Output-Packets = 0
Acct-Input-Packets = 15
Calling-Station-Id = ".291"
NAS-Port-Type = Async
Framed-IP-Address = 192.168.17.4
NAS-IP-Address = 127.0.0.1
NAS-Port = 0
Acct-Delay-Time = 0
Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 3
modcall[preacct]: module "preprocess" returns noop for request 3
rlm_acct_unique: Hashing 'NAS-Port = 0,Client-IP-Address = 127.0.0.1,NAS-IP-Address = 127.0.0.1,Acct-Session-Id = "4B1817FE1BD400",User-Name = "test2"'
rlm_acct_unique: Acct-Unique-Session-ID = "c8c3532d90d8c57b".
modcall[preacct]: module "acct_unique" returns ok for request 3
rlm_realm: No '@' in User-Name = "test2", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 3
acct_users: Matched entry DEFAULT at line 20
modcall[preacct]: module "files" returns ok for request 3
modcall: leaving group preacct (returns ok) for request 3
Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 3
radius_xlat: '/var/log/radius/radacct/127.0.0.1/detail-20091203'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/127.0.0.1/detail-20091203
modcall[accounting]: module "detail" returns ok for request 3
modcall[accounting]: module "unix" returns noop for request 3
radius_xlat: '/var/log/radius/radutmp'
radius_xlat: 'test2'
modcall[accounting]: module "radutmp" returns ok for request 3
modcall: leaving group accounting (returns ok) for request 3
radius_xlat: '/usr/abills/libexec/racct.pl'
Exec-Program: /usr/abills/libexec/racct.pl
Exec-Program output:
Exec-Program: returned: 0
Sending Accounting-Response of id 5 to 127.0.0.1 port 33037
Finished request 3
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...Код: Выделить всё
MS-CHAP2-Success \337S=BD1D9472726077FF5A99827EC7443514CDD18700
MS-MPPE-Recv-Key \206 (\250\267\021\337\2668Tm\034\336z7q*)\341\345n\251e;\322\316\363\011xtc:]V
MS-MPPE-Send-Key \217{\306g\203\302k;\262\241\271\002\346\331\344\310\252\332\215\332\211\301%\023tQ5\377'8\210\312\274+
MS-MPPE-Encryption-Policy
MS-MPPE-Encryption-Types
Acct-Interim-Interval 60
Session-Timeout 2422997
Filter-Id 2222
Framed-IP-Address 192.168.17.4
Framed-IP-Netmask 255.255.255.255