Имя пользователя клиента: videonabl.
Вот лог сервера доступа:
Код: Выделить всё
Jan 31 16:50:27 billing-srv1 pppoe-server[11485]: Session 1 created for client 1c:7e:e5:dd:df:9b (10.67.15.1) on eth2 using Service-Name ''
Jan 31 16:50:27 billing-srv1 pppd[11485]: Plugin radius.so loaded.
Jan 31 16:50:27 billing-srv1 pppd[11485]: RADIUS plugin initialized.
Jan 31 16:50:27 billing-srv1 pppd[11485]: Plugin radattr.so loaded.
Jan 31 16:50:27 billing-srv1 pppd[11485]: RADATTR plugin initialized.
Jan 31 16:50:27 billing-srv1 pppd[11485]: pppd 2.4.5 started by root, uid 0
Jan 31 16:50:27 billing-srv1 pppd[11485]: using channel 638
Jan 31 16:50:27 billing-srv1 pppd[11485]: Using interface ppp4
Jan 31 16:50:27 billing-srv1 pppd[11485]: Connect: ppp4 <--> /dev/pts/8
Jan 31 16:50:28 billing-srv1 pppd[11485]: sent [LCP ConfReq id=0x1 <auth eap> <magic 0xcb8bc440>]
Jan 31 16:50:28 billing-srv1 pppd[11485]: rcvd [LCP ConfAck id=0x1 <auth eap> <magic 0xcb8bc440>]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [LCP ConfReq id=0x11 <magic 0x96d79a96>]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [LCP ConfAck id=0x11 <magic 0x96d79a96>]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [LCP EchoReq id=0x0 magic=0xcb8bc440]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [EAP Request id=0x22 Identity <Message "Name">]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [LCP EchoReq id=0x0 magic=0x96d79a96]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [LCP EchoRep id=0x0 magic=0xcb8bc440]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [LCP EchoRep id=0x0 magic=0x96d79a96]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [EAP Response id=0x22 Identity <Name "videonabl">]
Jan 31 16:50:30 billing-srv1 pppd[11485]: EAP: unauthenticated peer name "videonabl"
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [EAP Request id=0x23 MD5-Challenge <Value e7 99 f7 85 ec c9 b5 c7 37 07 b2 5e d0 69 5e 14 8f 8e 46 41 5b 4b 8a> <Name "pppoe-server">]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [EAP Response id=0x23 MD5-Challenge <Value bf cd c0 ef c0 9c 80 63 50 aa 1a 52 d5 3b 50 1d> <Name "videonabl">]
Jan 31 16:50:30 billing-srv1 pppd[11485]: EAP: no MD5 secret for auth of videonabl
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [EAP Failure id=0x24]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [LCP TermReq id=0x2 "Authentication failed"]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [LCP TermReq id=0x12 "Failed to authenticate ourselves to peer"]
Jan 31 16:50:30 billing-srv1 pppd[11485]: sent [LCP TermAck id=0x12]
Jan 31 16:50:30 billing-srv1 pppd[11485]: rcvd [LCP TermAck id=0x2]
Jan 31 16:50:30 billing-srv1 pppd[11485]: Connection terminated.
Jan 31 16:50:30 billing-srv1 pppoe[11487]: read (asyncReadFromPPP): Session 1: Input/output error
Jan 31 16:50:30 billing-srv1 pppd[11485]: Waiting for 1 child processes...
Jan 31 16:50:30 billing-srv1 pppd[11485]: script /usr/sbin/pppoe -n -I eth2 -e 1:1c:7e:e5:dd:df:9b -S '', pid 11486
Jan 31 16:50:30 billing-srv1 pppd[11485]: Script /usr/sbin/pppoe -n -I eth2 -e 1:1c:7e:e5:dd:df:9b -S '' finished (pid 11486), status = 0x1
Jan 31 16:50:30 billing-srv1 pppd[11485]: RADATTR plugin removed file /var/run/radattr.ppp4.
Jan 31 16:50:30 billing-srv1 pppd[11485]: Exit.
Jan 31 16:50:30 billing-srv1 pppoe-server[3446]: Session 1 closed for client 1c:7e:e5:dd:df:9b (10.67.15.1) on eth2
Jan 31 16:50:30 billing-srv1 pppoe-server[3446]: Sent PADT
Jan 31 16:50:35 billing-srv1 pppoe-server[11496]: Session 2 created for client 1c:7e:e5:dd:df:9b (10.67.15.2) on eth2 using Service-Name ''
Jan 31 16:50:35 billing-srv1 pppd[11496]: Plugin radius.so loaded.
Jan 31 16:50:35 billing-srv1 pppd[11496]: RADIUS plugin initialized.
Jan 31 16:50:35 billing-srv1 pppd[11496]: Plugin radattr.so loaded.
Jan 31 16:50:35 billing-srv1 pppd[11496]: RADATTR plugin initialized.
Jan 31 16:50:35 billing-srv1 pppd[11496]: pppd 2.4.5 started by root, uid 0
Jan 31 16:50:35 billing-srv1 pppd[11496]: using channel 639
Jan 31 16:50:35 billing-srv1 pppd[11496]: Using interface ppp4
Jan 31 16:50:35 billing-srv1 pppd[11496]: Connect: ppp4 <--> /dev/pts/8
Jan 31 16:50:36 billing-srv1 pppd[11496]: sent [LCP ConfReq id=0x1 <auth eap> <magic 0x32c70154>]
Jan 31 16:50:36 billing-srv1 pppd[11496]: rcvd [LCP ConfAck id=0x1 <auth eap> <magic 0x32c70154>]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [LCP ConfReq id=0x13 <magic 0x2b97aa75>]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [LCP ConfAck id=0x13 <magic 0x2b97aa75>]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [LCP EchoReq id=0x0 magic=0x32c70154]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [EAP Request id=0xcd Identity <Message "Name">]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [LCP EchoReq id=0x0 magic=0x2b97aa75]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [LCP EchoRep id=0x0 magic=0x32c70154]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [LCP EchoRep id=0x0 magic=0x2b97aa75]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [EAP Response id=0xcd Identity <Name "videonabl">]
Jan 31 16:50:38 billing-srv1 pppd[11496]: EAP: unauthenticated peer name "videonabl"
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [EAP Request id=0xce MD5-Challenge <Value 86 18 99 a4 b1 67 0e 2b ef 11 53 9a 58 43 38 6b 73 9f 6b b7 c4 2a> <Name "pppoe-server">]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [EAP Response id=0xce MD5-Challenge <Value 67 d8 23 89 17 69 0d b9 a9 78 95 59 7d c0 88 ef> <Name "videonabl">]
Jan 31 16:50:38 billing-srv1 pppd[11496]: EAP: no MD5 secret for auth of videonabl
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [EAP Failure id=0xcf]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [LCP TermReq id=0x2 "Authentication failed"]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [LCP TermReq id=0x14 "Failed to authenticate ourselves to peer"]
Jan 31 16:50:38 billing-srv1 pppd[11496]: sent [LCP TermAck id=0x14]
Jan 31 16:50:38 billing-srv1 pppd[11496]: rcvd [LCP TermAck id=0x2]
Jan 31 16:50:38 billing-srv1 pppd[11496]: Connection terminated.
Jan 31 16:50:38 billing-srv1 pppoe[11498]: read (asyncReadFromPPP): Session 2: Input/output error
Jan 31 16:50:38 billing-srv1 pppd[11496]: Waiting for 1 child processes...
Jan 31 16:50:38 billing-srv1 pppd[11496]: script /usr/sbin/pppoe -n -I eth2 -e 2:1c:7e:e5:dd:df:9b -S '', pid 11497
Jan 31 16:50:38 billing-srv1 pppd[11496]: Script /usr/sbin/pppoe -n -I eth2 -e 2:1c:7e:e5:dd:df:9b -S '' finished (pid 11497), status = 0x1
Jan 31 16:50:38 billing-srv1 pppd[11496]: RADATTR plugin removed file /var/run/radattr.ppp4.
Jan 31 16:50:38 billing-srv1 pppd[11496]: Exit.
Jan 31 16:50:38 billing-srv1 pppoe-server[3446]: Session 2 closed for client 1c:7e:e5:dd:df:9b (10.67.15.2) on eth2
Jan 31 16:50:38 billing-srv1 pppoe-server[3446]: Sent PADT
refuse-eap
require-mschap-v2
require-mppe-128
Код: Выделить всё
Jan 31 17:46:06 billing-srv1 pppoe-server[15612]: Session 32 created for client 1c:7e:e5:dd:df:9b (10.67.15.32) on eth2 using Service-Name ''
Jan 31 17:46:06 billing-srv1 pppd[15612]: Plugin radius.so loaded.
Jan 31 17:46:06 billing-srv1 pppd[15612]: RADIUS plugin initialized.
Jan 31 17:46:06 billing-srv1 pppd[15612]: Plugin radattr.so loaded.
Jan 31 17:46:06 billing-srv1 pppd[15612]: RADATTR plugin initialized.
Jan 31 17:46:06 billing-srv1 pppd[15612]: pppd 2.4.5 started by root, uid 0
Jan 31 17:46:06 billing-srv1 pppd[15612]: using channel 928
Jan 31 17:46:06 billing-srv1 pppd[15612]: Using interface ppp0
Jan 31 17:46:06 billing-srv1 pppd[15612]: Connect: ppp0 <--> /dev/pts/0
Jan 31 17:46:07 billing-srv1 pppd[15612]: sent [LCP ConfReq id=0x1 <auth chap MS-v2> <magic 0x7d9fafce>]
Jan 31 17:46:07 billing-srv1 pppd[15612]: rcvd [LCP ConfAck id=0x1 <auth chap MS-v2> <magic 0x7d9fafce>]
Jan 31 17:46:09 billing-srv1 pppd[15612]: rcvd [LCP ConfReq id=0x15 <magic 0xca3b9a27>]
Jan 31 17:46:09 billing-srv1 pppd[15612]: sent [LCP ConfAck id=0x15 <magic 0xca3b9a27>]
Jan 31 17:46:09 billing-srv1 pppd[15612]: sent [LCP EchoReq id=0x0 magic=0x7d9fafce]
Jan 31 17:46:09 billing-srv1 pppd[15612]: sent [CHAP Challenge id=0xf3 <e5cbadc9cef946c85cff8f99735fb963>, name = "pppoe-server"]
Jan 31 17:46:09 billing-srv1 pppd[15612]: rcvd [LCP EchoReq id=0x0 magic=0xca3b9a27]
Jan 31 17:46:09 billing-srv1 pppd[15612]: sent [LCP EchoRep id=0x0 magic=0x7d9fafce]
Jan 31 17:46:09 billing-srv1 pppd[15612]: rcvd [LCP EchoRep id=0x0 magic=0xca3b9a27]
Jan 31 17:46:09 billing-srv1 pppd[15612]: rcvd [CHAP Response id=0xf3 <9a3eead23d35f7dfd731fbda7fdd1d7800000000000000005237de12e00c7fd3501234804279b700cdc427e2fbb4570000>, name = "videonabl"]
Jan 31 17:46:10 billing-srv1 pppd[15612]: RADATTR plugin wrote 8 line(s) to file /var/run/radattr.ppp0.
Jan 31 17:46:10 billing-srv1 pppd[15612]:
Jan 31 17:46:10 billing-srv1 pppd[15612]: Peer videonabl failed CHAP authentication
Jan 31 17:46:10 billing-srv1 pppd[15612]: sent [CHAP Failure id=0xf3 ""]
Jan 31 17:46:10 billing-srv1 pppd[15612]: sent [LCP TermReq id=0x2 "Authentication failed"]
Jan 31 17:46:10 billing-srv1 pppd[15612]: rcvd [LCP TermReq id=0x16 "Failed to authenticate ourselves to peer"]
Jan 31 17:46:10 billing-srv1 pppd[15612]: sent [LCP TermAck id=0x16]
Jan 31 17:46:10 billing-srv1 pppd[15612]: rcvd [LCP TermAck id=0x2]
Jan 31 17:46:10 billing-srv1 pppd[15612]: Connection terminated.
Jan 31 17:46:10 billing-srv1 pppd[15612]: Waiting for 1 child processes...
Jan 31 17:46:10 billing-srv1 pppd[15612]: script /usr/sbin/pppoe -n -I eth2 -e 32:1c:7e:e5:dd:df:9b -S '', pid 15613
Jan 31 17:46:10 billing-srv1 pppoe[15614]: read (asyncReadFromPPP): Session 32: Input/output error
Jan 31 17:46:10 billing-srv1 pppd[15612]: Script /usr/sbin/pppoe -n -I eth2 -e 32:1c:7e:e5:dd:df:9b -S '' finished (pid 15613), status = 0x1
Jan 31 17:46:10 billing-srv1 pppd[15612]: RADATTR plugin removed file /var/run/radattr.ppp0.
Jan 31 17:46:10 billing-srv1 pppd[15612]: Exit.
Jan 31 17:46:10 billing-srv1 pppoe-server[13431]: Session 32 closed for client 1c:7e:e5:dd:df:9b (10.67.15.32) on eth2
Jan 31 17:46:10 billing-srv1 pppoe-server[13431]: Sent PADT
Код: Выделить всё
Jan 1 00:14:18 pppd[933]: PPP session is 39 (0x27)
Jan 1 00:14:18 pppd[933]: Connected to 00:80:48:3f:64:99 via interface eth2.5
Jan 1 00:14:18 pppd[933]: Using interface ppp0
Jan 1 00:14:18 pppd[933]: Connect: ppp0 <--> eth2.5
Jan 1 00:14:21 pppd[933]: EAP: Identity prompt "Name"
Jan 1 00:14:21 pppd[933]: EAP: peer reports authentication failure
Jan 1 00:14:27 pppd[933]: Connection terminated.
Jan 1 00:14:27 pppd[933]: Modem hangupКод: Выделить всё
Jan 1 01:11:44 pppd[3859]: PPP session is 29 (0x1d)
Jan 1 01:11:44 pppd[3859]: Connected to 00:80:48:3f:64:99 via interface eth2.5
Jan 1 01:11:44 pppd[3859]: Using interface ppp0
Jan 1 01:11:44 pppd[3859]: Connect: ppp0 <--> eth2.5
Jan 1 01:11:47 pppd[3859]: MS-CHAP authentication failed:
Jan 1 01:11:47 pppd[3859]: CHAP authentication failed
Jan 1 01:11:53 pppd[3859]: Connection terminated.
Jan 1 01:11:53 pppd[3859]: Modem hangup
Jan 1 01:11:58 pppd[3859]: PPP session is 30 (0x1e)
Jan 1 01:11:58 pppd[3859]: Connected to 00:80:48:3f:64:99 via interface eth2.5
Jan 1 01:11:58 pppd[3859]: Using interface ppp0
Jan 1 01:11:58 pppd[3859]: Connect: ppp0 <--> eth2.5
Jan 1 01:12:02 pppd[3859]: MS-CHAP authentication failed:
Jan 1 01:12:02 pppd[3859]: CHAP authentication failed
Jan 1 01:12:08 pppd[3859]: Connection terminated.
Jan 1 01:12:08 pppd[3859]: Modem hangupКод: Выделить всё
rad_recv: Access-Request packet from host 127.0.0.1:51736, id=91, length=137
Service-Type = Framed-User
Framed-Protocol = PPP
User-Name = "videonabl"
MS-CHAP-Challenge = 0xd3983611aed96fa4cbc71f0bc26307b2
MS-CHAP2-Response = 0xf8005c636471df01014198aa674daff238a900000000000000000ebd72f183aea27dfabb83fccac19be979812fc4c59ed127
NAS-IP-Address = 192.168.0.3
NAS-Port = 0
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 111
modcall[authorize]: module "preprocess" returns ok for request 111
rlm_realm: No '@' in User-Name = "videonabl", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 111
users: Matched entry DEFAULT at line 157
modcall[authorize]: module "files" returns ok for request 111
modcall: leaving group authorize (returns ok) for request 111
rad_check_password: Found Auth-Type Accept
rad_check_password: Auth-Type = Accept, accepting the user
Exec-Program output: Acct-Interim-Interval = 60, Session-Timeout = 26253, PPPD-Upstream-Speed-Limit = 512, Octets-Direction = 0, Framed-IP-Address = 192.168.93.116, Session-Octets-Limit = 2146435072, Framed-IP-Netmask = 255.255.255.255, PPPD-Downstream-Speed-Limit = 512,
Exec-Program-Wait: value-pairs: Acct-Interim-Interval = 60, Session-Timeout = 26253, PPPD-Upstream-Speed-Limit = 512, Octets-Direction = 0, Framed-IP-Address = 192.168.93.116, Session-Octets-Limit = 2146435072, Framed-IP-Netmask = 255.255.255.255, PPPD-Downstream-Speed-Limit = 512,
Exec-Program: returned: 0
Sending Access-Accept of id 91 to 127.0.0.1 port 51736
Acct-Interim-Interval = 60
Session-Timeout = 26253
PPPD-Upstream-Speed-Limit = 512
Octets-Direction = Route-IP-No
Framed-IP-Address = 192.168.93.116
Session-Octets-Limit = 2146435072
Framed-IP-Netmask = 255.255.255.255
PPPD-Downstream-Speed-Limit = 512
Finished request 111
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 111 ID 91 with timestamp 4f27efc2
Nothing to do. Sleeping until we see a request.