ppp ipcp address accept - не помоглоловите дебаги
debug ppp error
000189: *Jul 2 17:09:27.051 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000190: *Jul 2 17:09:27.055 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000191: *Jul 2 17:09:27.055 MSK: Vi2 PPP: No remote authentication for call-out
000192: *Jul 2 17:09:27.067 MSK: Vi2 LCP: Sent too many CONFNAKs. Switch to CONFREJ
000193: *Jul 2 17:09:27.087 MSK: Vi2 CHAP: Unable to authenticate for peer
000194: *Jul 2 17:09:29.103 MSK: Vi2 PPP: No remote authentication for call-out
000195: *Jul 2 17:09:29.103 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000196: *Jul 2 17:09:29.107 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000197: *Jul 2 17:09:51.500 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000198: *Jul 2 17:09:51.504 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000199: *Jul 2 17:09:51.504 MSK: Vi2 PPP: No remote authentication for call-out
000200: *Jul 2 17:09:51.516 MSK: Vi2 LCP: Sent too many CONFNAKs. Switch to CONFREJ
000201: *Jul 2 17:09:51.536 MSK: Vi2 CHAP: Unable to authenticate for peer
000202: *Jul 2 17:09:53.552 MSK: Vi2 PPP: No remote authentication for call-out
000203: *Jul 2 17:09:53.552 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000204: *Jul 2 17:09:53.556 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
debug pppoe events
000232: *Jul 2 17:12:15.270 MSK: Sending PADI: Interface = GigabitEthernet0
000233: *Jul 2 17:12:15.270 MSK: PPPoE 0: I PADO R:0030.8810.f77f L:0006.f638.b487 Gi0
000234: *Jul 2 17:12:15.270 MSK: PPPoE 0: I PADO R:0030.8810.f780 L:0006.f638.b487 Gi0
000235: *Jul 2 17:12:17.318 MSK: PPPOE: we've got our pado and the pado timer went off
000236: *Jul 2 17:12:17.318 MSK: OUT PADR from PPPoE Session
000237: *Jul 2 17:12:17.430 MSK: PPPoE 4444: I PADS R:0030.8810.f77f L:0006.f638.b487 Gi0
000238: *Jul 2 17:12:17.430 MSK: IN PADS from PPPoE Session
000239: *Jul 2 17:12:17.430 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000240: *Jul 2 17:12:17.430 MSK: PPPoE: Virtual Access interface obtained.
000241: *Jul 2 17:12:17.430 MSK: PPPoE : encap string prepared
000242: *Jul 2 17:12:17.430 MSK: [0]PPPoE 4444: data path set to PPPoE Client
000243: *Jul 2 17:12:17.434 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000244: *Jul 2 17:12:19.478 MSK: PPPoE : Shutting down client session
000245: *Jul 2 17:12:19.478 MSK: [0]PPPoE 4444: O PADT R:0030.8810.f77f L:0006.f638.b487 Gi0
000246: *Jul 2 17:12:19.478 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000247: *Jul 2 17:12:19.482 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000248: *Jul 2 17:12:39.591 MSK: Sending PADI: Interface = GigabitEthernet0
000249: *Jul 2 17:12:39.591 MSK: PPPoE 0: I PADO R:0030.8810.f77f L:0006.f638.b487 Gi0
000250: *Jul 2 17:12:39.591 MSK: PPPoE 0: I PADO R:0030.8810.f780 L:0006.f638.b487 Gi0
000251: *Jul 2 17:12:41.639 MSK: PPPOE: we've got our pado and the pado timer went off
000252: *Jul 2 17:12:41.639 MSK: OUT PADR from PPPoE Session
000253: *Jul 2 17:12:41.643 MSK: PPPoE 2581: I PADS R:0030.8810.f77f L:0006.f638.b487 Gi0
000254: *Jul 2 17:12:41.643 MSK: IN PADS from PPPoE Session
000255: *Jul 2 17:12:41.643 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000256: *Jul 2 17:12:41.643 MSK: PPPoE: Virtual Access interface obtained.
000257: *Jul 2 17:12:41.643 MSK: PPPoE : encap string prepared
000258: *Jul 2 17:12:41.643 MSK: [0]PPPoE 2581: data path set to PPPoE Client
000259: *Jul 2 17:12:41.647 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000260: *Jul 2 17:12:43.703 MSK: PPPoE : Shutting down client session
000261: *Jul 2 17:12:43.703 MSK: [0]PPPoE 2581: O PADT R:0030.8810.f77f L:0006.f638.b487 Gi0
000262: *Jul 2 17:12:43.703 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000263: *Jul 2 17:12:43.707 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000264: *Jul 2 17:13:03.912 MSK: Sending PADI: Interface = GigabitEthernet0
000265: *Jul 2 17:13:03.912 MSK: PPPoE 0: I PADO R:0030.8810.f77f L:0006.f638.b487 Gi0
000266: *Jul 2 17:13:03.912 MSK: PPPoE 0: I PADO R:0030.8810.f780 L:0006.f638.b487 Gi0
000267: *Jul 2 17:13:05.960 MSK: PPPOE: we've got our pado and the pado timer went off
000268: *Jul 2 17:13:05.960 MSK: OUT PADR from PPPoE Session
000269: *Jul 2 17:13:06.032 MSK: PPPoE 15291: I PADS R:0030.8810.f77f L:0006.f638.b487 Gi0
000270: *Jul 2 17:13:06.032 MSK: IN PADS from PPPoE Session
000271: *Jul 2 17:13:06.032 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000272: *Jul 2 17:13:06.032 MSK: PPPoE: Virtual Access interface obtained.
000273: *Jul 2 17:13:06.032 MSK: PPPoE : encap string prepared
000274: *Jul 2 17:13:06.032 MSK: [0]PPPoE 15291: data path set to PPPoE Client
000275: *Jul 2 17:13:06.036 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000276: *Jul 2 17:13:08.088 MSK: PPPoE : Shutting down client session
000277: *Jul 2 17:13:08.088 MSK: [0]PPPoE 15291: O PADT R:0030.8810.f77f L:0006.f638.b487 Gi0
000278: *Jul 2 17:13:08.088 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000279: *Jul 2 17:13:08.092 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
debug pppoe paket
kam-cs1#
000300: *Jul 2 17:14:16.879 MSK: pppoe_send_padi:
contiguous pak, size 60
FF FF FF FF FF FF 00 06 F6 38 B4 87 88 63 11 09
00 00 00 10 01 01 00 00 01 03 00 08 66 00 00 01
00 00 18 72 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
000301: *Jul 2 17:14:16.879 MSK: PPPoE 0: I PADO R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 7F 88 63 11 07
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000302: *Jul 2 17:14:16.879 MSK: PPPoE 0: I PADO R:0030.8810.f780 L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 80 88 63 11 07
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000303: *Jul 2 17:14:18.927 MSK: OUT PADR from PPPoE Session
contiguous pak, size 60
00 30 88 10 F7 7F 00 06 F6 38 B4 87 88 63 11 19
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000304: *Jul 2 17:14:19.039 MSK: PPPoE 179: I PADS R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 7F 88 63 11 65
00 B3 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 01 00 00 01 02 00 0E 52 65 64 62 61 63 6B 2D
42 38 32 30 44 34 00 00 00 00 00 00
000305: *Jul 2 17:14:19.039 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000306: *Jul 2 17:14:19.043 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000307: *Jul 2 17:14:21.083 MSK: [0]PPPoE 179: O PADT R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 30 88 10 F7 7F 00 06 F6 38 B4 87 88 63 11 A7
00 B3 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
000308: *Jul 2 17:14:21.083 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000309: *Jul 2 17:14:21.087 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000310: *Jul 2 17:14:41.228 MSK: pppoe_send_padi:
contiguous pak, size 60
FF FF FF FF FF FF 00 06 F6 38 B4 87 88 63 11 09
00 00 00 10 01 01 00 00 01 03 00 08 66 00 00 01
00 00 18 72 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
000311: *Jul 2 17:14:41.228 MSK: PPPoE 0: I PADO R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 7F 88 63 11 07
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000312: *Jul 2 17:14:41.228 MSK: PPPoE 0: I PADO R:0030.8810.f780 L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 80 88 63 11 07
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000313: *Jul 2 17:14:43.276 MSK: OUT PADR from PPPoE Session
contiguous pak, size 60
00 30 88 10 F7 7F 00 06 F6 38 B4 87 88 63 11 19
00 00 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 02 00 0E 52 65 64 62 61 63 6B 2D 42 38 32 30
44 34 01 01 00 00 00 00 00 00 00 00
000314: *Jul 2 17:14:43.392 MSK: PPPoE 1221: I PADS R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 06 F6 38 B4 87 00 30 88 10 F7 7F 88 63 11 65
04 C5 00 22 01 03 00 08 66 00 00 01 00 00 18 72
01 01 00 00 01 02 00 0E 52 65 64 62 61 63 6B 2D
42 38 32 30 44 34 00 00 00 00 00 00
000315: *Jul 2 17:14:43.392 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000316: *Jul 2 17:14:43.396 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000317: *Jul 2 17:14:45.436 MSK: [0]PPPoE 1221: O PADT R:0030.8810.f77f L:0006.f638.b487 Gi0
contiguous pak, size 60
00 30 88 10 F7 7F 00 06 F6 38 B4 87 88 63 11 A7
04 C5 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
000318: *Jul 2 17:14:45.436 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000319: *Jul 2 17:14:45.440 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
debug ppp negot
000330: *Jul 2 17:15:32.018 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000331: *Jul 2 17:15:32.022 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000332: *Jul 2 17:15:32.022 MSK: Vi2 PPP: Sending cstate UP notification
000333: *Jul 2 17:15:32.022 MSK: Vi2 PPP: Processing CstateUp message
000334: *Jul 2 17:15:32.022 MSK: PPP: Alloc Context [88E02E74]
000335: *Jul 2 17:15:32.022 MSK: ppp39 PPP: Phase is ESTABLISHING
000336: *Jul 2 17:15:32.022 MSK: Vi2 PPP: Using dialer call direction
000337: *Jul 2 17:15:32.022 MSK: Vi2 PPP: Treating connection as a callout
000338: *Jul 2 17:15:32.022 MSK: Vi2 PPP: Session handle[4000027] Session id[39]
000339: *Jul 2 17:15:32.022 MSK: Vi2 LCP: Event[OPEN] State[Initial to Starting]
000340: *Jul 2 17:15:32.022 MSK: Vi2 PPP: No remote authentication for call-out
000341: *Jul 2 17:15:32.022 MSK: Vi2 LCP: O CONFREQ [Starting] id 1 len 10
000342: *Jul 2 17:15:32.022 MSK: Vi2 LCP: MagicNumber 0x0704FA25 (0x05060704FA25)
000343: *Jul 2 17:15:32.022 MSK: Vi2 LCP: Event[UP] State[Starting to REQsent]
000344: *Jul 2 17:15:32.230 MSK: Vi2 LCP: I CONFREQ [REQsent] id 110 len 19
000345: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000346: *Jul 2 17:15:32.230 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000347: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000348: *Jul 2 17:15:32.230 MSK: Vi2 LCP: O CONFNAK [REQsent] id 110 len 8
000349: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000350: *Jul 2 17:15:32.230 MSK: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
000351: *Jul 2 17:15:32.230 MSK: Vi2 LCP: I CONFACK [REQsent] id 1 len 10
000352: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MagicNumber 0x0704FA25 (0x05060704FA25)
000353: *Jul 2 17:15:32.230 MSK: Vi2 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
000354: *Jul 2 17:15:32.230 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 111 len 19
000355: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000356: *Jul 2 17:15:32.230 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000357: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000358: *Jul 2 17:15:32.230 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 111 len 8
000359: *Jul 2 17:15:32.230 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000360: *Jul 2 17:15:32.230 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000361: *Jul 2 17:15:32.234 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 112 len 19
000362: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000363: *Jul 2 17:15:32.234 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000364: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000365: *Jul 2 17:15:32.234 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 112 len 8
000366: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000367: *Jul 2 17:15:32.234 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000368: *Jul 2 17:15:32.234 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 113 len 19
000369: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000370: *Jul 2 17:15:32.234 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000371: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000372: *Jul 2 17:15:32.234 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 113 len 8
000373: *Jul 2 17:15:32.234 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000374: *Jul 2 17:15:32.234 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000375: *Jul 2 17:15:32.238 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 114 len 19
000376: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000377: *Jul 2 17:15:32.238 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000378: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000379: *Jul 2 17:15:32.238 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 114 len 8
000380: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000381: *Jul 2 17:15:32.238 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000382: *Jul 2 17:15:32.238 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 115 len 19
000383: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000384: *Jul 2 17:15:32.238 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000385: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000386: *Jul 2 17:15:32.238 MSK: Vi2 LCP: Sent too many CONFNAKs. Switch to CONFREJ
000387: *Jul 2 17:15:32.238 MSK: Vi2 LCP: O CONFREJ [ACKrcvd] id 115 len 8
000388: *Jul 2 17:15:32.238 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000389: *Jul 2 17:15:32.238 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000390: *Jul 2 17:15:32.242 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 116 len 15
000391: *Jul 2 17:15:32.242 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000392: *Jul 2 17:15:32.242 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000393: *Jul 2 17:15:32.242 MSK: Vi2 LCP: O CONFACK [ACKrcvd] id 116 len 15
000394: *Jul 2 17:15:32.242 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000395: *Jul 2 17:15:32.242 MSK: Vi2 LCP: MagicNumber 0x168A5AF6 (0x0506168A5AF6)
000396: *Jul 2 17:15:32.242 MSK: Vi2 LCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
000397: *Jul 2 17:15:32.242 MSK: Vi2 PPP: Queue CHAP code[1] id[1]
000398: *Jul 2 17:15:32.254 MSK: Vi2 PPP: Phase is AUTHENTICATING, by the peer
000399: *Jul 2 17:15:32.254 MSK: Vi2 CHAP: Redirect packet to Vi2
000400: *Jul 2 17:15:32.254 MSK: Vi2 CHAP: I CHALLENGE id 1 len 28 from "Redback"
000401: *Jul 2 17:15:32.254 MSK: Vi2 LCP: State is Open
000402: *Jul 2 17:15:32.254 MSK: Vi2 CHAP: Unable to authenticate for peer
000403: *Jul 2 17:15:32.254 MSK: Vi2 PPP DISC: User failed CHAP authentication
000404: *Jul 2 17:15:32.254 MSK: PPP: NET STOP send to AAA.
000405: *Jul 2 17:15:32.254 MSK: Vi2 LCP: O TERMREQ [Open] id 2 len 4
000406: *Jul 2 17:15:32.254 MSK: Vi2 LCP: Event[CLOSE] State[Open to Closing]
000407: *Jul 2 17:15:32.254 MSK: Vi2 PPP: Phase is TERMINATING
000408: *Jul 2 17:15:32.254 MSK: Vi2 LCP: I TERMACK [Closing] id 2 len 4
000409: *Jul 2 17:15:32.254 MSK: Vi2 PPP: No remote authentication for call-out
000410: *Jul 2 17:15:32.254 MSK: Vi2 LCP: Event[Receive TermAck] State[Closing to Closed]
000411: *Jul 2 17:15:32.254 MSK: Vi2 LCP: Event[DOWN] State[Closed to Initial]
000412: *Jul 2 17:15:32.254 MSK: Vi2 PPP: Phase is DOWN
000413: *Jul 2 17:15:32.258 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000414: *Jul 2 17:15:32.262 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000415: *Jul 2 17:15:54.563 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000416: *Jul 2 17:15:54.567 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000417: *Jul 2 17:15:54.567 MSK: Vi2 PPP: Sending cstate UP notification
000418: *Jul 2 17:15:54.567 MSK: Vi2 PPP: Processing CstateUp message
000419: *Jul 2 17:15:54.567 MSK: PPP: Alloc Context [88E02E74]
000420: *Jul 2 17:15:54.567 MSK: ppp40 PPP: Phase is ESTABLISHING
000421: *Jul 2 17:15:54.567 MSK: Vi2 PPP: Using dialer call direction
000422: *Jul 2 17:15:54.567 MSK: Vi2 PPP: Treating connection as a callout
000423: *Jul 2 17:15:54.567 MSK: Vi2 PPP: Session handle[E9000028] Session id[40]
000424: *Jul 2 17:15:54.567 MSK: Vi2 LCP: Event[OPEN] State[Initial to Starting]
000425: *Jul 2 17:15:54.567 MSK: Vi2 PPP: No remote authentication for call-out
000426: *Jul 2 17:15:54.567 MSK: Vi2 LCP: O CONFREQ [Starting] id 1 len 10
000427: *Jul 2 17:15:54.567 MSK: Vi2 LCP: MagicNumber 0x07055237 (0x050607055237)
000428: *Jul 2 17:15:54.567 MSK: Vi2 LCP: Event[UP] State[Starting to REQsent]
000429: *Jul 2 17:15:54.571 MSK: Vi2 LCP: I CONFREQ [REQsent] id 40 len 19
000430: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000431: *Jul 2 17:15:54.571 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000432: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000433: *Jul 2 17:15:54.571 MSK: Vi2 LCP: O CONFNAK [REQsent] id 40 len 8
000434: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000435: *Jul 2 17:15:54.571 MSK: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
000436: *Jul 2 17:15:54.571 MSK: Vi2 LCP: I CONFACK [REQsent] id 1 len 10
000437: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MagicNumber 0x07055237 (0x050607055237)
000438: *Jul 2 17:15:54.571 MSK: Vi2 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
000439: *Jul 2 17:15:54.571 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 41 len 19
000440: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000441: *Jul 2 17:15:54.571 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000442: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000443: *Jul 2 17:15:54.571 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 41 len 8
000444: *Jul 2 17:15:54.571 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000445: *Jul 2 17:15:54.571 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000446: *Jul 2 17:15:54.575 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 42 len 19
000447: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000448: *Jul 2 17:15:54.575 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000449: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000450: *Jul 2 17:15:54.575 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 42 len 8
000451: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000452: *Jul 2 17:15:54.575 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000453: *Jul 2 17:15:54.575 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 43 len 19
000454: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000455: *Jul 2 17:15:54.575 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000456: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000457: *Jul 2 17:15:54.575 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 43 len 8
000458: *Jul 2 17:15:54.575 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000459: *Jul 2 17:15:54.579 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000460: *Jul 2 17:15:54.579 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 44 len 19
000461: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000462: *Jul 2 17:15:54.579 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000463: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000464: *Jul 2 17:15:54.579 MSK: Vi2 LCP: O CONFNAK [ACKrcvd] id 44 len 8
000465: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MRU 1500 (0x010405DC)
000466: *Jul 2 17:15:54.579 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000467: *Jul 2 17:15:54.579 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 45 len 19
000468: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000469: *Jul 2 17:15:54.579 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000470: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000471: *Jul 2 17:15:54.579 MSK: Vi2 LCP: Sent too many CONFNAKs. Switch to CONFREJ
000472: *Jul 2 17:15:54.579 MSK: Vi2 LCP: O CONFREJ [ACKrcvd] id 45 len 8
000473: *Jul 2 17:15:54.579 MSK: Vi2 LCP: MRU 1492 (0x010405D4)
000474: *Jul 2 17:15:54.579 MSK: Vi2 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
000475: *Jul 2 17:15:54.583 MSK: Vi2 LCP: I CONFREQ [ACKrcvd] id 46 len 15
000476: *Jul 2 17:15:54.583 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000477: *Jul 2 17:15:54.583 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000478: *Jul 2 17:15:54.583 MSK: Vi2 LCP: O CONFACK [ACKrcvd] id 46 len 15
000479: *Jul 2 17:15:54.583 MSK: Vi2 LCP: AuthProto CHAP (0x0305C22305)
000480: *Jul 2 17:15:54.583 MSK: Vi2 LCP: MagicNumber 0x3261DD7C (0x05063261DD7C)
000481: *Jul 2 17:15:54.583 MSK: Vi2 LCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
000482: *Jul 2 17:15:54.583 MSK: Vi2 PPP: Queue CHAP code[1] id[1]
000483: *Jul 2 17:15:54.591 MSK: Vi2 PPP: Phase is AUTHENTICATING, by the peer
000484: *Jul 2 17:15:54.591 MSK: Vi2 CHAP: Redirect packet to Vi2
000485: *Jul 2 17:15:54.591 MSK: Vi2 CHAP: I CHALLENGE id 1 len 28 from "Redback"
000486: *Jul 2 17:15:54.591 MSK: Vi2 LCP: State is Open
000487: *Jul 2 17:15:54.591 MSK: Vi2 CHAP: Unable to authenticate for peer
000488: *Jul 2 17:15:54.591 MSK: Vi2 PPP DISC: User failed CHAP authentication
000489: *Jul 2 17:15:54.591 MSK: PPP: NET STOP send to AAA.
000490: *Jul 2 17:15:54.591 MSK: Vi2 LCP: O TERMREQ [Open] id 2 len 4
000491: *Jul 2 17:15:54.591 MSK: Vi2 LCP: Event[CLOSE] State[Open to Closing]
000492: *Jul 2 17:15:54.591 MSK: Vi2 PPP: Phase is TERMINATING
000493: *Jul 2 17:15:56.607 MSK: Vi2 LCP: O TERMREQ [Closing] id 3 len 4
000494: *Jul 2 17:15:56.607 MSK: Vi2 LCP: Event[Timeout+] State[Closing to Closing]
000495: *Jul 2 17:15:56.607 MSK: Vi2 LCP: I TERMREQ [Closing] id 47 len 4
000496: *Jul 2 17:15:56.607 MSK: Vi2 LCP: O TERMACK [Closing] id 47 len 4
000497: *Jul 2 17:15:56.607 MSK: Vi2 LCP: Event[Receive TermReq] State[Closing to Closing]
000498: *Jul 2 17:15:56.607 MSK: Vi2 LCP: I TERMACK [Closing] id 3 len 4
000499: *Jul 2 17:15:56.607 MSK: Vi2 PPP: No remote authentication for call-out
000500: *Jul 2 17:15:56.607 MSK: Vi2 LCP: Event[Receive TermAck] State[Closing to Closed]
000501: *Jul 2 17:15:56.607 MSK: Vi2 LCP: Event[DOWN] State[Closed to Initial]
000502: *Jul 2 17:15:56.607 MSK: Vi2 PPP: Phase is DOWN
000503: *Jul 2 17:15:56.607 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
debug ppp authen
000112: *Jul 2 17:06:36.828 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000113: *Jul 2 17:06:36.832 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, cha nged state to up
000114: *Jul 2 17:06:36.832 MSK: Vi2 PPP: Using dialer call direction
000115: *Jul 2 17:06:36.832 MSK: Vi2 PPP: Treating connection as a callout
000116: *Jul 2 17:06:36.832 MSK: Vi2 PPP: Session handle[44000011] Session id[1 7]
000117: *Jul 2 17:06:36.872 MSK: Vi2 PPP: No authorization without authenticati on
000118: *Jul 2 17:06:36.872 MSK: Vi2 CHAP: I CHALLENGE id 1 len 28 from "Redbac k"
000119: *Jul 2 17:06:36.872 MSK: Vi2 PPP: Sent CHAP SENDAUTH Request
000120: *Jul 2 17:06:36.872 MSK: Vi2 PPP: Received SENDAUTH Response FAIL
000121: *Jul 2 17:06:36.872 MSK: Vi2 CHAP: Unable to authenticate for peer
000122: *Jul 2 17:06:36.872 MSK: Vi2 PPP: Sending AAA radius abort
000123: *Jul 2 17:06:38.888 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from p rofile Di0
000124: *Jul 2 17:06:38.892 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, cha nged state to down
000125: *Jul 2 17:07:01.229 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000126: *Jul 2 17:07:01.233 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000127: *Jul 2 17:07:01.233 MSK: Vi2 PPP: Using dialer call direction
000128: *Jul 2 17:07:01.233 MSK: Vi2 PPP: Treating connection as a callout
000129: *Jul 2 17:07:01.233 MSK: Vi2 PPP: Session handle[55000012] Session id[18]
000130: *Jul 2 17:07:01.257 MSK: Vi2 PPP: No authorization without authentication
000131: *Jul 2 17:07:01.257 MSK: Vi2 CHAP: I CHALLENGE id 1 len 28 from "Redback"
000132: *Jul 2 17:07:01.257 MSK: Vi2 PPP: Sent CHAP SENDAUTH Request
000133: *Jul 2 17:07:01.257 MSK: Vi2 PPP: Received SENDAUTH Response FAIL
000134: *Jul 2 17:07:01.257 MSK: Vi2 CHAP: Unable to authenticate for peer
000135: *Jul 2 17:07:01.257 MSK: Vi2 PPP: Sending AAA radius abort
000136: *Jul 2 17:07:03.273 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000137: *Jul 2 17:07:03.277 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
000138: *Jul 2 17:07:25.546 MSK: %DIALER-6-BIND: Interface Vi2 bound to profile Di0
000139: *Jul 2 17:07:25.550 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
000140: *Jul 2 17:07:25.550 MSK: Vi2 PPP: Using dialer call direction
000141: *Jul 2 17:07:25.550 MSK: Vi2 PPP: Treating connection as a callout
000142: *Jul 2 17:07:25.550 MSK: Vi2 PPP: Session handle[AD000013] Session id[19]
000143: *Jul 2 17:07:25.578 MSK: Vi2 PPP: No authorization without authentication
000144: *Jul 2 17:07:25.578 MSK: Vi2 CHAP: I CHALLENGE id 1 len 28 from "Redback"
000145: *Jul 2 17:07:25.578 MSK: Vi2 PPP: Sent CHAP SENDAUTH Request
000146: *Jul 2 17:07:25.578 MSK: Vi2 PPP: Received SENDAUTH Response FAIL
000147: *Jul 2 17:07:25.578 MSK: Vi2 CHAP: Unable to authenticate for peer
000148: *Jul 2 17:07:25.578 MSK: Vi2 PPP: Sending AAA radius abort
000149: *Jul 2 17:07:27.594 MSK: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di0
000150: *Jul 2 17:07:27.598 MSK: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down