FreeBSD 8, freeradius, mpd5
Почти всегда при попытке подключения выбивает ошибку 734. Иногда достаточно одного повторного звонка а иногда приходится повторять по 3-5 раз.
В чём может быть проблема?
Ошибка 734 в 99% случаях подключения PPPoE
-
~AsmodeuS~
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Ошибка 734 в 99% случаях подключения PPPoE
сморите логи сервера доступа
Re: Ошибка 734 в 99% случаях подключения PPPoE
mpd.log
Пользователь user188 пытался неоднократно подлкючиться
Пользователь user188 пытался неоднократно подлкючиться
Код: Выделить всё
Jul 1 20:29:28 zero mpd: [em1-42] Name: "user188"
Jul 1 20:29:28 zero mpd: [em1-42] AUTH: Trying RADIUS
Jul 1 20:29:28 zero mpd: [em1-42] RADIUS: Authenticating user 'user188'
Jul 1 20:29:29 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCESS_ACCEPT for user 'user188'
Jul 1 20:29:29 zero mpd: [em1-42] AUTH: RADIUS returned: authenticated
Jul 1 20:29:29 zero mpd: [em1-42] CHAP: Auth return status: authenticated
Jul 1 20:29:29 zero mpd: [em1-42] CHAP: Reply message: S=EA01917D1F70EC63723705530E2E8DF2896CD287
Jul 1 20:29:29 zero mpd: [em1-42] CHAP: sending SUCCESS #1 len: 46
Jul 1 20:29:29 zero mpd: [em1-42] LCP: authorization successful
Jul 1 20:29:29 zero mpd: [em1-42] Link: Matched action 'bundle "B2" ""'
Jul 1 20:29:29 zero mpd: [em1-42] Creating new bundle using template "B2".
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: Interface ng39 created
Jul 1 20:29:29 zero mpd: [em1-42] Link: Join bundle "B2-41"
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: Open event
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Initial --> Starting
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: LayerStart
Jul 1 20:29:29 zero mpd: [B2-41] CCP: Open event
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Initial --> Starting
Jul 1 20:29:29 zero mpd: [B2-41] CCP: LayerStart
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: Up event
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Starting --> Req-Sent
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendConfigReq #1
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:29 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:29 zero mpd: [B2-41] CCP: Up event
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Starting --> Req-Sent
Jul 1 20:29:29 zero mpd: [B2-41] CCP: SendConfigReq #1
Jul 1 20:29:29 zero mpd: [em1-42] RADIUS: Accounting user 'user188' (Type: 1)
Jul 1 20:29:29 zero mpd: [em1-42] rec'd unexpected protocol IPV6CP, rejecting
Jul 1 20:29:29 zero mpd: [B2-41] CCP: rec'd Configure Request #6 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] MPPC
Jul 1 20:29:29 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:29 zero mpd: [B2-41] CCP: SendConfigRej #6
Jul 1 20:29:29 zero mpd: [B2-41] MPPC
Jul 1 20:29:29 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Configure Request #7 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] NAKing with 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:29 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendConfigRej #7
Jul 1 20:29:29 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendConfigReq #2
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:29 zero mpd: [B2-41] CCP: rec'd Configure Ack #1 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:29 zero mpd: [B2-41] CCP: rec'd Terminate Request #8 (Ack-Rcvd)
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Ack-Rcvd --> Req-Sent
Jul 1 20:29:29 zero mpd: [B2-41] CCP: SendTerminateAck #2
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Configure Request #9 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] NAKing with 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:29 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendConfigNak #9
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Configure Request #10 (Ack-Rcvd)
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] 10.0.10.19 is OK
Jul 1 20:29:29 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendConfigAck #10
Jul 1 20:29:29 zero mpd: [B2-41] IPADDR 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Ack-Rcvd --> Opened
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: LayerUp
Jul 1 20:29:29 zero mpd: [B2-41] 10.0.10.1 -> 10.0.10.19
Jul 1 20:29:29 zero mpd: [B2-41] IFACE: Adding IPv4 address to ng39 failed: File exists
Jul 1 20:29:29 zero mpd: [B2-41] IFACE: IfaceChangeAddr() error, closing IPCP
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: parameter negotiation failed
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Opened --> Stopping
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: SendTerminateReq #3
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: LayerDown
Jul 1 20:29:29 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCOUNTING_RESPONSE for user 'user188'
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: rec'd Terminate Ack #3 (Stopping)
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Stopping --> Stopped
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: LayerFinish
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: No NCPs left. Closing links...
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: closing link "em1-42"...
Jul 1 20:29:29 zero mpd: [em1-42] Link: CLOSE event
Jul 1 20:29:29 zero mpd: [em1-42] LCP: Close event
Jul 1 20:29:29 zero mpd: [em1-42] LCP: state change Opened --> Closing
Jul 1 20:29:29 zero mpd: [em1-42] Link: Leave bundle "B2-41"
Jul 1 20:29:29 zero mpd: [em1-42] RADIUS: Accounting user 'user188' (Type: 2)
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: Close event
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Stopped --> Closed
Jul 1 20:29:29 zero mpd: [B2-41] CCP: Close event
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Req-Sent --> Closing
Jul 1 20:29:29 zero mpd: [B2-41] CCP: SendTerminateReq #3
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: Down event
Jul 1 20:29:29 zero mpd: [B2-41] IPCP: state change Closed --> Initial
Jul 1 20:29:29 zero mpd: [B2-41] CCP: Down event
Jul 1 20:29:29 zero mpd: [B2-41] CCP: LayerFinish
Jul 1 20:29:29 zero mpd: [B2-41] CCP: state change Closing --> Initial
Jul 1 20:29:29 zero mpd: [B2-41] Bundle: Shutdown
Jul 1 20:29:29 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCOUNTING_RESPONSE for user 'user188'
Jul 1 20:29:29 zero mpd: [em1-42] LCP: SendTerminateReq #4
Jul 1 20:29:29 zero mpd: [em1-42] LCP: LayerDown
Jul 1 20:29:29 zero mpd: [em1-42] LCP: rec'd Terminate Ack #4 (Closing)
Jul 1 20:29:29 zero mpd: [em1-42] LCP: state change Closing --> Closed
Jul 1 20:29:29 zero mpd: [em1-42] LCP: LayerFinish
Jul 1 20:29:29 zero mpd: [em1-42] Link: DOWN event
Jul 1 20:29:29 zero mpd: [em1-42] LCP: Down event
Jul 1 20:29:29 zero mpd: [em1-42] LCP: state change Closed --> Initial
Jul 1 20:29:30 zero mpd: [em1-42] Link: SHUTDOWN event
Jul 1 20:29:30 zero mpd: [em1-42] Link: Shutdown
Jul 1 20:29:30 zero mpd: [B2-40] CCP: SendConfigReq #7
Jul 1 20:29:30 zero mpd: [B2-40] CCP: rec'd Terminate Ack #7 (Req-Sent)
Jul 1 20:29:32 zero mpd: [B2-40] CCP: SendConfigReq #8
Jul 1 20:29:32 zero mpd: [B2-40] CCP: rec'd Terminate Ack #8 (Req-Sent)
Jul 1 20:29:34 zero mpd: [B2-40] CCP: SendConfigReq #9
Jul 1 20:29:34 zero mpd: [B2-40] CCP: rec'd Terminate Ack #9 (Req-Sent)
Jul 1 20:29:34 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:24:21:b5:c2:9f
Jul 1 20:29:34 zero mpd: [em1-42] Accepting PPPoE connection
Jul 1 20:29:34 zero mpd: [em1-42] Link: OPEN event
Jul 1 20:29:34 zero mpd: [em1-42] LCP: Open event
Jul 1 20:29:34 zero mpd: [em1-42] LCP: state change Initial --> Starting
Jul 1 20:29:34 zero mpd: [em1-42] LCP: LayerStart
Jul 1 20:29:34 zero mpd: [em1-42] PPPoE: connection successful
Jul 1 20:29:34 zero mpd: [em1-42] Link: UP event
Jul 1 20:29:34 zero mpd: [em1-42] LCP: Up event
Jul 1 20:29:34 zero mpd: [em1-42] LCP: state change Starting --> Req-Sent
Jul 1 20:29:34 zero mpd: [em1-42] LCP: SendConfigReq #1
Jul 1 20:29:34 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:34 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:34 zero mpd: [em1-42] MAGICNUM 93dcd5a8
Jul 1 20:29:34 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:34 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:34 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:34 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:34 zero mpd: [em1-42] LCP: rec'd Configure Request #0 (Req-Sent)
Jul 1 20:29:34 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:34 zero mpd: [em1-42] MAGICNUM 20094032
Jul 1 20:29:34 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:34 zero mpd: [em1-42] ACFCOMP
Jul 1 20:29:34 zero mpd: [em1-42] CALLBACK 6
Jul 1 20:29:34 zero mpd: [em1-42] LCP: SendConfigRej #0
Jul 1 20:29:34 zero mpd: [em1-42] ACFCOMP
Jul 1 20:29:34 zero mpd: [em1-42] CALLBACK 6
Jul 1 20:29:34 zero mpd: [em1-42] LCP: rec'd Configure Request #1 (Req-Sent)
Jul 1 20:29:34 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:34 zero mpd: [em1-42] MAGICNUM 20094032
Jul 1 20:29:34 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:34 zero mpd: [em1-42] LCP: SendConfigAck #1
Jul 1 20:29:34 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:34 zero mpd: [em1-42] MAGICNUM 20094032
Jul 1 20:29:34 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:34 zero mpd: [em1-42] LCP: state change Req-Sent --> Ack-Sent
Jul 1 20:29:36 zero mpd: [B2-40] CCP: SendConfigReq #10
Jul 1 20:29:36 zero mpd: [B2-40] CCP: rec'd Terminate Ack #10 (Req-Sent)
Jul 1 20:29:36 zero mpd: [em1-42] LCP: SendConfigReq #2
Jul 1 20:29:36 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:36 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:36 zero mpd: [em1-42] MAGICNUM 93dcd5a8
Jul 1 20:29:36 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:36 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:36 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:36 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:36 zero mpd: [em1-42] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jul 1 20:29:36 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:36 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:36 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:36 zero mpd: [em1-42] LCP: SendConfigReq #3
Jul 1 20:29:36 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:36 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:36 zero mpd: [em1-42] MAGICNUM 93dcd5a8
Jul 1 20:29:36 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:36 zero mpd: [em1-42] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jul 1 20:29:36 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:36 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:36 zero mpd: [em1-42] MAGICNUM 93dcd5a8
Jul 1 20:29:36 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:36 zero mpd: [em1-42] LCP: state change Ack-Sent --> Opened
Jul 1 20:29:36 zero mpd: [em1-42] LCP: auth: peer wants nothing, I want CHAP
Jul 1 20:29:36 zero mpd: [em1-42] CHAP: sending CHALLENGE #1 len: 21
Jul 1 20:29:36 zero mpd: [em1-42] LCP: LayerUp
Jul 1 20:29:36 zero mpd: [em1-42] LCP: rec'd Ident #2 (Opened)
Jul 1 20:29:36 zero mpd: [em1-42] MESG: MSRASV5.20
Jul 1 20:29:36 zero mpd: [em1-42] LCP: rec'd Ident #3 (Opened)
Jul 1 20:29:36 zero mpd: [em1-42] MESG: MSRAS-0-M-^WM-^@M-^QM-^RM-^MM-^HM- -M-^OM-
Jul 1 20:29:36 zero mpd: [em1-42] LCP: rec'd Ident #4 (Opened)
Jul 1 20:29:36 zero mpd: [em1-42] MESG: M-^@JÚUmÿbC¨^BDþ2¥ÄC
Jul 1 20:29:36 zero mpd: [em1-42] CHAP: rec'd RESPONSE #1 len: 62
Jul 1 20:29:36 zero mpd: [em1-42] Name: "user188"
Jul 1 20:29:36 zero mpd: [em1-42] AUTH: Trying RADIUS
Jul 1 20:29:36 zero mpd: [em1-42] RADIUS: Authenticating user 'user188'
Jul 1 20:29:37 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCESS_ACCEPT for user 'user188'
Jul 1 20:29:37 zero mpd: [em1-42] AUTH: RADIUS returned: authenticated
Jul 1 20:29:37 zero mpd: [em1-42] CHAP: Auth return status: authenticated
Jul 1 20:29:37 zero mpd: [em1-42] CHAP: Reply message: S=D674641F401052FBB44CF335EB524E98AA954548
Jul 1 20:29:37 zero mpd: [em1-42] CHAP: sending SUCCESS #1 len: 46
Jul 1 20:29:37 zero mpd: [em1-42] LCP: authorization successful
Jul 1 20:29:37 zero mpd: [em1-42] Link: Matched action 'bundle "B2" ""'
Jul 1 20:29:37 zero mpd: [em1-42] Creating new bundle using template "B2".
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: Interface ng39 created
Jul 1 20:29:37 zero mpd: [em1-42] Link: Join bundle "B2-41"
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: Open event
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Initial --> Starting
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: LayerStart
Jul 1 20:29:37 zero mpd: [B2-41] CCP: Open event
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Initial --> Starting
Jul 1 20:29:37 zero mpd: [B2-41] CCP: LayerStart
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: Up event
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Starting --> Req-Sent
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendConfigReq #1
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:37 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:37 zero mpd: [B2-41] CCP: Up event
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Starting --> Req-Sent
Jul 1 20:29:37 zero mpd: [B2-41] CCP: SendConfigReq #1
Jul 1 20:29:37 zero mpd: [em1-42] RADIUS: Accounting user 'user188' (Type: 1)
Jul 1 20:29:37 zero mpd: [em1-42] rec'd unexpected protocol IPV6CP, rejecting
Jul 1 20:29:37 zero mpd: [B2-41] CCP: rec'd Configure Request #6 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] MPPC
Jul 1 20:29:37 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:37 zero mpd: [B2-41] CCP: SendConfigRej #6
Jul 1 20:29:37 zero mpd: [B2-41] MPPC
Jul 1 20:29:37 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Configure Request #7 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] NAKing with 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:37 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendConfigRej #7
Jul 1 20:29:37 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendConfigReq #2
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:37 zero mpd: [B2-41] CCP: rec'd Configure Ack #1 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:37 zero mpd: [B2-41] CCP: rec'd Terminate Request #8 (Ack-Rcvd)
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Ack-Rcvd --> Req-Sent
Jul 1 20:29:37 zero mpd: [B2-41] CCP: SendTerminateAck #2
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Configure Request #9 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] NAKing with 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:37 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendConfigNak #9
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Configure Request #10 (Ack-Rcvd)
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] 10.0.10.176 is OK
Jul 1 20:29:37 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendConfigAck #10
Jul 1 20:29:37 zero mpd: [B2-41] IPADDR 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Ack-Rcvd --> Opened
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: LayerUp
Jul 1 20:29:37 zero mpd: [B2-41] 10.0.10.1 -> 10.0.10.176
Jul 1 20:29:37 zero mpd: [B2-41] IFACE: Adding IPv4 address to ng39 failed: File exists
Jul 1 20:29:37 zero mpd: [B2-41] IFACE: IfaceChangeAddr() error, closing IPCP
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: parameter negotiation failed
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Opened --> Stopping
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: SendTerminateReq #3
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: LayerDown
Jul 1 20:29:37 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCOUNTING_RESPONSE for user 'user188'
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: rec'd Terminate Ack #3 (Stopping)
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Stopping --> Stopped
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: LayerFinish
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: No NCPs left. Closing links...
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: closing link "em1-42"...
Jul 1 20:29:37 zero mpd: [em1-42] Link: CLOSE event
Jul 1 20:29:37 zero mpd: [em1-42] LCP: Close event
Jul 1 20:29:37 zero mpd: [em1-42] LCP: state change Opened --> Closing
Jul 1 20:29:37 zero mpd: [em1-42] Link: Leave bundle "B2-41"
Jul 1 20:29:37 zero mpd: [em1-42] RADIUS: Accounting user 'user188' (Type: 2)
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: Close event
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Stopped --> Closed
Jul 1 20:29:37 zero mpd: [B2-41] CCP: Close event
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Req-Sent --> Closing
Jul 1 20:29:37 zero mpd: [B2-41] CCP: SendTerminateReq #3
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: Down event
Jul 1 20:29:37 zero mpd: [B2-41] IPCP: state change Closed --> Initial
Jul 1 20:29:37 zero mpd: [B2-41] CCP: Down event
Jul 1 20:29:37 zero mpd: [B2-41] CCP: LayerFinish
Jul 1 20:29:37 zero mpd: [B2-41] CCP: state change Closing --> Initial
Jul 1 20:29:37 zero mpd: [B2-41] Bundle: Shutdown
Jul 1 20:29:37 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCOUNTING_RESPONSE for user 'user188'
Jul 1 20:29:37 zero mpd: [em1-42] LCP: SendTerminateReq #4
Jul 1 20:29:37 zero mpd: [em1-42] LCP: LayerDown
Jul 1 20:29:37 zero mpd: [em1-42] LCP: rec'd Terminate Ack #4 (Closing)
Jul 1 20:29:37 zero mpd: [em1-42] LCP: state change Closing --> Closed
Jul 1 20:29:37 zero mpd: [em1-42] LCP: LayerFinish
Jul 1 20:29:37 zero mpd: [em1-42] Link: DOWN event
Jul 1 20:29:37 zero mpd: [em1-42] LCP: Down event
Jul 1 20:29:37 zero mpd: [em1-42] LCP: state change Closed --> Initial
Jul 1 20:29:38 zero mpd: [em1-42] Link: SHUTDOWN event
Jul 1 20:29:38 zero mpd: [em1-42] Link: Shutdown
Jul 1 20:29:38 zero mpd: [B2-40] CCP: SendConfigReq #11
Jul 1 20:29:38 zero mpd: [B2-40] CCP: rec'd Terminate Ack #11 (Req-Sent)
Jul 1 20:29:39 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:24:21:b5:c2:9f
Jul 1 20:29:39 zero mpd: [em1-42] Accepting PPPoE connection
Jul 1 20:29:39 zero mpd: [em1-42] Link: OPEN event
Jul 1 20:29:39 zero mpd: [em1-42] LCP: Open event
Jul 1 20:29:39 zero mpd: [em1-42] LCP: state change Initial --> Starting
Jul 1 20:29:39 zero mpd: [em1-42] LCP: LayerStart
Jul 1 20:29:39 zero mpd: [em1-42] PPPoE: connection successful
Jul 1 20:29:39 zero mpd: [em1-42] Link: UP event
Jul 1 20:29:39 zero mpd: [em1-42] LCP: Up event
Jul 1 20:29:39 zero mpd: [em1-42] LCP: state change Starting --> Req-Sent
Jul 1 20:29:39 zero mpd: [em1-42] LCP: SendConfigReq #1
Jul 1 20:29:39 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:39 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:39 zero mpd: [em1-42] MAGICNUM ba38f6f0
Jul 1 20:29:39 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:39 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:39 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:39 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:39 zero mpd: [em1-42] LCP: rec'd Configure Request #0 (Req-Sent)
Jul 1 20:29:39 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:39 zero mpd: [em1-42] MAGICNUM 5e6d698a
Jul 1 20:29:39 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:39 zero mpd: [em1-42] ACFCOMP
Jul 1 20:29:39 zero mpd: [em1-42] CALLBACK 6
Jul 1 20:29:39 zero mpd: [em1-42] LCP: SendConfigRej #0
Jul 1 20:29:39 zero mpd: [em1-42] ACFCOMP
Jul 1 20:29:39 zero mpd: [em1-42] CALLBACK 6
Jul 1 20:29:39 zero mpd: [em1-42] LCP: rec'd Configure Request #1 (Req-Sent)
Jul 1 20:29:39 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:39 zero mpd: [em1-42] MAGICNUM 5e6d698a
Jul 1 20:29:39 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:39 zero mpd: [em1-42] LCP: SendConfigAck #1
Jul 1 20:29:39 zero mpd: [em1-42] MRU 1480
Jul 1 20:29:39 zero mpd: [em1-42] MAGICNUM 5e6d698a
Jul 1 20:29:39 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:39 zero mpd: [em1-42] LCP: state change Req-Sent --> Ack-Sent
Jul 1 20:29:40 zero mpd: [B2-40] CCP: SendConfigReq #12
Jul 1 20:29:40 zero mpd: [B2-40] CCP: rec'd Terminate Ack #12 (Req-Sent)
Jul 1 20:29:41 zero mpd: [em1-42] LCP: SendConfigReq #2
Jul 1 20:29:41 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:41 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:41 zero mpd: [em1-42] MAGICNUM ba38f6f0
Jul 1 20:29:41 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:41 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:41 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:41 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:41 zero mpd: [em1-42] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jul 1 20:29:41 zero mpd: [em1-42] MP MRRU 2048
Jul 1 20:29:41 zero mpd: [em1-42] MP SHORTSEQ
Jul 1 20:29:41 zero mpd: [em1-42] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:29:41 zero mpd: [em1-42] LCP: SendConfigReq #3
Jul 1 20:29:41 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:41 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:41 zero mpd: [em1-42] MAGICNUM ba38f6f0
Jul 1 20:29:41 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:41 zero mpd: [em1-42] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jul 1 20:29:41 zero mpd: [em1-42] PROTOCOMP
Jul 1 20:29:41 zero mpd: [em1-42] MRU 1492
Jul 1 20:29:41 zero mpd: [em1-42] MAGICNUM ba38f6f0
Jul 1 20:29:41 zero mpd: [em1-42] AUTHPROTO CHAP MSOFTv2
Jul 1 20:29:41 zero mpd: [em1-42] LCP: state change Ack-Sent --> Opened
Jul 1 20:29:41 zero mpd: [em1-42] LCP: auth: peer wants nothing, I want CHAP
Jul 1 20:29:41 zero mpd: [em1-42] CHAP: sending CHALLENGE #1 len: 21
Jul 1 20:29:41 zero mpd: [em1-42] LCP: LayerUp
Jul 1 20:29:41 zero mpd: [em1-42] LCP: rec'd Ident #2 (Opened)
Jul 1 20:29:41 zero mpd: [em1-42] MESG: MSRASV5.20
Jul 1 20:29:41 zero mpd: [em1-42] LCP: rec'd Ident #3 (Opened)
Jul 1 20:29:41 zero mpd: [em1-42] MESG: MSRAS-0-M-^WM-^@M-^QM-^RM-^MM-^HM- -M-^OM-
Jul 1 20:29:41 zero mpd: [em1-42] LCP: rec'd Ident #4 (Opened)
Jul 1 20:29:41 zero mpd: [em1-42] MESG: éÆM-^N0¥
Jul 1 20:29:41 zero mpd: [em1-42] MESG: Jª^EÅM-^OÒEÍâ
Jul 1 20:29:41 zero mpd: [em1-42] CHAP: rec'd RESPONSE #1 len: 62
Jul 1 20:29:41 zero mpd: [em1-42] Name: "user188"
Jul 1 20:29:41 zero mpd: [em1-42] AUTH: Trying RADIUS
Jul 1 20:29:41 zero mpd: [em1-42] RADIUS: Authenticating user 'user188'
Jul 1 20:29:42 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCESS_ACCEPT for user 'user188'
Jul 1 20:29:42 zero mpd: [em1-42] AUTH: RADIUS returned: authenticated
Jul 1 20:29:42 zero mpd: [em1-42] CHAP: Auth return status: authenticated
Jul 1 20:29:42 zero mpd: [em1-42] CHAP: Reply message: S=163B2B3184C3DE1BD897B3462AA255083B233421
Jul 1 20:29:42 zero mpd: [em1-42] CHAP: sending SUCCESS #1 len: 46
Jul 1 20:29:42 zero mpd: [em1-42] LCP: authorization successful
Jul 1 20:29:42 zero mpd: [em1-42] Link: Matched action 'bundle "B2" ""'
Jul 1 20:29:42 zero mpd: [em1-42] Creating new bundle using template "B2".
Jul 1 20:29:42 zero mpd: [B2-41] Bundle: Interface ng39 created
Jul 1 20:29:42 zero mpd: [em1-42] Link: Join bundle "B2-41"
Jul 1 20:29:42 zero mpd: [B2-41] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: Open event
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: state change Initial --> Starting
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: LayerStart
Jul 1 20:29:42 zero mpd: [B2-41] CCP: Open event
Jul 1 20:29:42 zero mpd: [B2-41] CCP: state change Initial --> Starting
Jul 1 20:29:42 zero mpd: [B2-41] CCP: LayerStart
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: Up event
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: state change Starting --> Req-Sent
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: SendConfigReq #1
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:42 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:42 zero mpd: [B2-41] CCP: Up event
Jul 1 20:29:42 zero mpd: [B2-41] CCP: state change Starting --> Req-Sent
Jul 1 20:29:42 zero mpd: [B2-41] CCP: SendConfigReq #1
Jul 1 20:29:42 zero mpd: [em1-42] RADIUS: Accounting user 'user188' (Type: 1)
Jul 1 20:29:42 zero mpd: [em1-42] RADIUS: Rec'd RAD_ACCOUNTING_RESPONSE for user 'user188'
Jul 1 20:29:42 zero mpd: [em1-42] rec'd unexpected protocol IPV6CP, rejecting
Jul 1 20:29:42 zero mpd: [B2-41] CCP: rec'd Configure Request #6 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] MPPC
Jul 1 20:29:42 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:42 zero mpd: [B2-41] CCP: SendConfigRej #6
Jul 1 20:29:42 zero mpd: [B2-41] MPPC
Jul 1 20:29:42 zero mpd: [B2-41] 0x00000000:
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: rec'd Configure Request #7 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] NAKing with 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:42 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: SendConfigRej #7
Jul 1 20:29:42 zero mpd: [B2-41] PRINBNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] SECDNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] SECNBNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: SendConfigReq #2
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:42 zero mpd: [B2-41] CCP: rec'd Configure Ack #1 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] CCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:42 zero mpd: [B2-41] CCP: rec'd Terminate Request #8 (Ack-Rcvd)
Jul 1 20:29:42 zero mpd: [B2-41] CCP: state change Ack-Rcvd --> Req-Sent
Jul 1 20:29:42 zero mpd: [B2-41] CCP: SendTerminateAck #2
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: rec'd Configure Request #9 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] NAKing with 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] PRIDNS 0.0.0.0
Jul 1 20:29:42 zero mpd: [B2-41] NAKing with 10.0.0.1
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: SendConfigNak #9
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.1
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: state change Req-Sent --> Ack-Rcvd
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: rec'd Configure Request #10 (Ack-Rcvd)
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] 10.0.10.178 is OK
Jul 1 20:29:42 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: SendConfigAck #10
Jul 1 20:29:42 zero mpd: [B2-41] IPADDR 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] PRIDNS 10.0.0.1
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: state change Ack-Rcvd --> Opened
Jul 1 20:29:42 zero mpd: [B2-41] IPCP: LayerUp
Jul 1 20:29:42 zero mpd: [B2-41] 10.0.10.1 -> 10.0.10.178
Jul 1 20:29:42 zero mpd: [B2-41] IFACE: Up event
Jul 1 20:29:42 zero mpd: [B2-41] IFACE: session-timeout limited to 1952257 seconds
Jul 1 20:29:42 zero mpd: [B2-40] CCP: SendConfigReq #13
Jul 1 20:29:42 zero mpd: [B2-40] CCP: rec'd Terminate Ack #13 (Req-Sent)
Jul 1 20:29:43 zero mpd: [B2-41] CCP: rec'd Terminate Request #11 (Req-Sent)
Jul 1 20:29:43 zero mpd: [B2-41] CCP: SendTerminateAck #3
Jul 1 20:29:44 zero mpd: [B2-41] CCP: SendConfigReq #4
Jul 1 20:29:44 zero mpd: [B2-40] CCP: parameter negotiation failed
Jul 1 20:29:44 zero mpd: [B2-40] CCP: state change Req-Sent --> Stopped
Jul 1 20:29:44 zero mpd: [B2-40] CCP: LayerFinish
Jul 1 20:29:46 zero mpd: [B2-41] CCP: SendConfigReq #5
Jul 1 20:29:46 zero mpd: [B2-41] CCP: rec'd Terminate Ack #5 (Req-Sent)
Jul 1 20:29:47 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:29:47 zero mpd: [em1-43] Accepting PPPoE connection
Jul 1 20:29:47 zero mpd: [em1-43] Link: OPEN event
Jul 1 20:29:47 zero mpd: [em1-43] LCP: Open event
Jul 1 20:29:47 zero mpd: [em1-43] LCP: state change Initial --> Starting
Jul 1 20:29:47 zero mpd: [em1-43] LCP: LayerStart
Jul 1 20:29:48 zero mpd: [B2-41] CCP: SendConfigReq #6
Jul 1 20:29:48 zero mpd: [B2-41] CCP: rec'd Terminate Ack #6 (Req-Sent)
Jul 1 20:29:49 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:29:49 zero mpd: [em1-44] Accepting PPPoE connection
Jul 1 20:29:49 zero mpd: [em1-44] Link: OPEN event
Jul 1 20:29:49 zero mpd: [em1-44] LCP: Open event
Jul 1 20:29:49 zero mpd: [em1-44] LCP: state change Initial --> Starting
Jul 1 20:29:49 zero mpd: [em1-44] LCP: LayerStart
Jul 1 20:29:50 zero mpd: [B2-41] CCP: SendConfigReq #7
Jul 1 20:29:50 zero mpd: [B2-41] CCP: rec'd Terminate Ack #7 (Req-Sent)
Jul 1 20:29:52 zero mpd: [B2-41] CCP: SendConfigReq #8
Jul 1 20:29:52 zero mpd: [B2-41] CCP: rec'd Terminate Ack #8 (Req-Sent)
Jul 1 20:29:53 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:29:53 zero mpd: [em1-45] Accepting PPPoE connection
Jul 1 20:29:53 zero mpd: [em1-45] Link: OPEN event
Jul 1 20:29:53 zero mpd: [em1-45] LCP: Open event
Jul 1 20:29:53 zero mpd: [em1-45] LCP: state change Initial --> Starting
Jul 1 20:29:53 zero mpd: [em1-45] LCP: LayerStart
Jul 1 20:29:54 zero mpd: [B2-41] CCP: SendConfigReq #9
Jul 1 20:29:54 zero mpd: [B2-41] CCP: rec'd Terminate Ack #9 (Req-Sent)
Jul 1 20:29:56 zero mpd: [em1-43] PPPoE connection timeout after 9 seconds
Jul 1 20:29:56 zero mpd: [em1-43] Link: DOWN event
Jul 1 20:29:56 zero mpd: [em1-43] LCP: Close event
Jul 1 20:29:56 zero mpd: [em1-43] LCP: state change Starting --> Initial
Jul 1 20:29:56 zero mpd: [em1-43] LCP: LayerFinish
Jul 1 20:29:56 zero mpd: [em1-43] LCP: Down event
Jul 1 20:29:56 zero mpd: [em1-43] Link: SHUTDOWN event
Jul 1 20:29:56 zero mpd: [em1-43] Link: Shutdown
Jul 1 20:29:56 zero mpd: PPPoE: message 7 from unexisting link "43"
Jul 1 20:29:56 zero mpd: [B2-41] CCP: SendConfigReq #10
Jul 1 20:29:56 zero mpd: [B2-41] CCP: rec'd Terminate Ack #10 (Req-Sent)
Jul 1 20:29:58 zero mpd: [em1-44] PPPoE connection timeout after 9 seconds
Jul 1 20:29:58 zero mpd: [em1-44] Link: DOWN event
Jul 1 20:29:58 zero mpd: [em1-44] LCP: Close event
Jul 1 20:29:58 zero mpd: [em1-44] LCP: state change Starting --> Initial
Jul 1 20:29:58 zero mpd: [em1-44] LCP: LayerFinish
Jul 1 20:29:58 zero mpd: [em1-44] LCP: Down event
Jul 1 20:29:58 zero mpd: [em1-44] Link: SHUTDOWN event
Jul 1 20:29:58 zero mpd: [em1-44] Link: Shutdown
Jul 1 20:29:58 zero mpd: [B2-41] CCP: SendConfigReq #11
Jul 1 20:29:58 zero mpd: [B2-41] CCP: rec'd Terminate Ack #11 (Req-Sent)
Jul 1 20:30:00 zero mpd: [B2-41] CCP: SendConfigReq #12
Jul 1 20:30:00 zero mpd: [B2-41] CCP: rec'd Terminate Ack #12 (Req-Sent)
Jul 1 20:30:01 zero mpd: CONSOLE: Connect
Jul 1 20:30:01 zero mpd: CONSOLE: Allocated new console session 0x2883c008 from 127.0.0.1
Jul 1 20:30:01 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:30:01 zero mpd: [em1-43] Accepting PPPoE connection
Jul 1 20:30:01 zero mpd: [em1-43] Link: OPEN event
Jul 1 20:30:01 zero mpd: [em1-43] LCP: Open event
Jul 1 20:30:01 zero mpd: [em1-43] LCP: state change Initial --> Starting
Jul 1 20:30:01 zero mpd: [em1-43] LCP: LayerStart
Jul 1 20:30:01 zero mpd: CONSOLE: Failed login attempt from 127.0.0.1
Jul 1 20:30:02 zero mpd: [em1-45] PPPoE connection timeout after 9 seconds
Jul 1 20:30:02 zero mpd: [em1-45] Link: DOWN event
Jul 1 20:30:02 zero mpd: [em1-45] LCP: Close event
Jul 1 20:30:02 zero mpd: [em1-45] LCP: state change Starting --> Initial
Jul 1 20:30:02 zero mpd: [em1-45] LCP: LayerFinish
Jul 1 20:30:02 zero mpd: [em1-45] LCP: Down event
Jul 1 20:30:02 zero mpd: [em1-45] Link: SHUTDOWN event
Jul 1 20:30:02 zero mpd: [em1-45] Link: Shutdown
Jul 1 20:30:02 zero mpd: PPPoE: message 7 from unexisting link "45"
Jul 1 20:30:02 zero mpd: [B2-41] CCP: SendConfigReq #13
Jul 1 20:30:02 zero mpd: [B2-41] CCP: rec'd Terminate Ack #13 (Req-Sent)
Jul 1 20:30:03 zero mpd: [em1-39] LCP: no reply to 1 echo request(s)
Jul 1 20:30:04 zero mpd: [B2-41] CCP: parameter negotiation failed
Jul 1 20:30:04 zero mpd: [B2-41] CCP: state change Req-Sent --> Stopped
Jul 1 20:30:04 zero mpd: [B2-41] CCP: LayerFinish
Jul 1 20:30:06 zero mpd: CONSOLE: Connection closed by peer
Jul 1 20:30:10 zero mpd: [em1-43] PPPoE connection timeout after 9 seconds
Jul 1 20:30:10 zero mpd: [em1-43] Link: DOWN event
Jul 1 20:30:10 zero mpd: [em1-43] LCP: Close event
Jul 1 20:30:10 zero mpd: [em1-43] LCP: state change Starting --> Initial
Jul 1 20:30:10 zero mpd: [em1-43] LCP: LayerFinish
Jul 1 20:30:10 zero mpd: [em1-43] LCP: Down event
Jul 1 20:30:10 zero mpd: [em1-43] Link: SHUTDOWN event
Jul 1 20:30:10 zero mpd: [em1-43] Link: Shutdown
Jul 1 20:30:13 zero mpd: [em1-39] LCP: no reply to 2 echo request(s)
Jul 1 20:30:23 zero mpd: [em1-39] LCP: no reply to 3 echo request(s)
Jul 1 20:30:24 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:30:24 zero mpd: [em1-43] Accepting PPPoE connection
Jul 1 20:30:24 zero mpd: [em1-43] Link: OPEN event
Jul 1 20:30:24 zero mpd: [em1-43] LCP: Open event
Jul 1 20:30:24 zero mpd: [em1-43] LCP: state change Initial --> Starting
Jul 1 20:30:24 zero mpd: [em1-43] LCP: LayerStart
Jul 1 20:30:26 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:30:26 zero mpd: [em1-44] Accepting PPPoE connection
Jul 1 20:30:26 zero mpd: [em1-44] Link: OPEN event
Jul 1 20:30:26 zero mpd: [em1-44] LCP: Open event
Jul 1 20:30:26 zero mpd: [em1-44] LCP: state change Initial --> Starting
Jul 1 20:30:26 zero mpd: [em1-44] LCP: LayerStart
Jul 1 20:30:30 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:30:30 zero mpd: [em1-45] Accepting PPPoE connection
Jul 1 20:30:30 zero mpd: [em1-45] Link: OPEN event
Jul 1 20:30:30 zero mpd: [em1-45] LCP: Open event
Jul 1 20:30:30 zero mpd: [em1-45] LCP: state change Initial --> Starting
Jul 1 20:30:30 zero mpd: [em1-45] LCP: LayerStart
Jul 1 20:30:33 zero mpd: [em1-43] PPPoE connection timeout after 9 seconds
Jul 1 20:30:33 zero mpd: [em1-43] Link: DOWN event
Jul 1 20:30:33 zero mpd: [em1-43] LCP: Close event
Jul 1 20:30:33 zero mpd: [em1-43] LCP: state change Starting --> Initial
Jul 1 20:30:33 zero mpd: [em1-43] LCP: LayerFinish
Jul 1 20:30:33 zero mpd: [em1-43] LCP: Down event
Jul 1 20:30:33 zero mpd: [em1-43] Link: SHUTDOWN event
Jul 1 20:30:33 zero mpd: [em1-43] Link: Shutdown
Jul 1 20:30:33 zero mpd: PPPoE: message 7 from unexisting link "43"
Jul 1 20:30:33 zero mpd: [em1-39] LCP: no reply to 4 echo request(s)
Jul 1 20:30:35 zero mpd: [em1-44] PPPoE connection timeout after 9 seconds
Jul 1 20:30:35 zero mpd: [em1-44] Link: DOWN event
Jul 1 20:30:35 zero mpd: [em1-44] LCP: Close event
Jul 1 20:30:35 zero mpd: [em1-44] LCP: state change Starting --> Initial
Jul 1 20:30:35 zero mpd: [em1-44] LCP: LayerFinish
Jul 1 20:30:35 zero mpd: [em1-44] LCP: Down event
Jul 1 20:30:35 zero mpd: [em1-44] Link: SHUTDOWN event
Jul 1 20:30:35 zero mpd: [em1-44] Link: Shutdown
Jul 1 20:30:38 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:23:f8:af:c5:a4
Jul 1 20:30:38 zero mpd: [em1-43] Accepting PPPoE connection
Jul 1 20:30:38 zero mpd: [em1-43] Link: OPEN event
Jul 1 20:30:38 zero mpd: [em1-43] LCP: Open event
Jul 1 20:30:38 zero mpd: [em1-43] LCP: state change Initial --> Starting
Jul 1 20:30:38 zero mpd: [em1-43] LCP: LayerStart
Jul 1 20:30:39 zero mpd: Incoming PPPoE connection request via em1: for service "" from 00:26:9e:a9:3a:1a
Jul 1 20:30:39 zero mpd: [em1-44] Accepting PPPoE connection
Jul 1 20:30:39 zero mpd: [em1-44] Link: OPEN event
Jul 1 20:30:39 zero mpd: [em1-44] LCP: Open event
Jul 1 20:30:39 zero mpd: [em1-44] LCP: state change Initial --> Starting
Jul 1 20:30:39 zero mpd: [em1-44] LCP: LayerStart
Jul 1 20:30:39 zero mpd: [em1-44] PPPoE: connection successful
Jul 1 20:30:39 zero mpd: [em1-44] Link: UP event
Jul 1 20:30:39 zero mpd: [em1-44] LCP: Up event
Jul 1 20:30:39 zero mpd: [em1-44] LCP: state change Starting --> Req-Sent
Jul 1 20:30:39 zero mpd: [em1-44] LCP: SendConfigReq #1
Jul 1 20:30:39 zero mpd: [em1-44] PROTOCOMP
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1492
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM e00d338c
Jul 1 20:30:39 zero mpd: [em1-44] AUTHPROTO CHAP MSOFTv2
Jul 1 20:30:39 zero mpd: [em1-44] MP MRRU 2048
Jul 1 20:30:39 zero mpd: [em1-44] MP SHORTSEQ
Jul 1 20:30:39 zero mpd: [em1-44] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Configure Request #0 (Req-Sent)
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1480
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM 35d8365c
Jul 1 20:30:39 zero mpd: [em1-44] CALLBACK 6
Jul 1 20:30:39 zero mpd: [em1-44] LCP: SendConfigRej #0
Jul 1 20:30:39 zero mpd: [em1-44] CALLBACK 6
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Configure Reject #1 (Req-Sent)
Jul 1 20:30:39 zero mpd: [em1-44] PROTOCOMP
Jul 1 20:30:39 zero mpd: [em1-44] MP MRRU 2048
Jul 1 20:30:39 zero mpd: [em1-44] MP SHORTSEQ
Jul 1 20:30:39 zero mpd: [em1-44] ENDPOINTDISC [802.1] 00 17 31 5a 8c 05
Jul 1 20:30:39 zero mpd: [em1-44] LCP: SendConfigReq #2
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1492
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM e00d338c
Jul 1 20:30:39 zero mpd: [em1-44] AUTHPROTO CHAP MSOFTv2
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Configure Request #1 (Req-Sent)
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1480
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM 35d8365c
Jul 1 20:30:39 zero mpd: [em1-44] LCP: SendConfigAck #1
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1480
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM 35d8365c
Jul 1 20:30:39 zero mpd: [em1-44] LCP: state change Req-Sent --> Ack-Sent
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Configure Ack #2 (Ack-Sent)
Jul 1 20:30:39 zero mpd: [em1-44] MRU 1492
Jul 1 20:30:39 zero mpd: [em1-44] MAGICNUM e00d338c
Jul 1 20:30:39 zero mpd: [em1-44] AUTHPROTO CHAP MSOFTv2
Jul 1 20:30:39 zero mpd: [em1-44] LCP: state change Ack-Sent --> Opened
Jul 1 20:30:39 zero mpd: [em1-44] LCP: auth: peer wants nothing, I want CHAP
Jul 1 20:30:39 zero mpd: [em1-44] CHAP: sending CHALLENGE #1 len: 21
Jul 1 20:30:39 zero mpd: [em1-44] LCP: LayerUp
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Ident #2 (Opened)
Jul 1 20:30:39 zero mpd: [em1-44] MESG: MSRASV5.10
Jul 1 20:30:39 zero mpd: [em1-44] LCP: rec'd Ident #3 (Opened)
Jul 1 20:30:39 zero mpd: [em1-44] MESG: MSRAS-0-MICROSOF-1FE19C
Jul 1 20:30:39 zero mpd: [em1-44] CHAP: rec'd RESPONSE #1 len: 62