The following are debug logs from a 7140 (12.2(13)T5) serving as a LNS.
The first call comes from a 3640 LAC with 12.1.11 and the second one from a 3640 LAC with 12.1.17, both with the same configuration.
The same hardware/software setup is also used in both cases in customer's side.
Any idea what might have changed in the 2nd ios in order to make the ppp timeout?
I though only the LNS was important is this phase of ppp, am i wrong? Does the LAC have any part too?
12.1(11)
--------
Aug 1 18:28:41.303: ppp400 PPP: Authorization required
Aug 1 18:28:41.303: ppp400 PPP: Phase is ESTABLISHING
Aug 1 18:28:41.303: ppp400 PPP: Authorization required
Aug 1 18:28:41.303: ppp400 LCP: O CONFREQ [Closed] id 1 len 24
Aug 1 18:28:41.303: ppp400 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:28:41.303: ppp400 LCP: MagicNumber 0x5CDF6F56 (0x05065CDF6F56)
Aug 1 18:28:41.307: ppp400 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:28:41.307: ppp400 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:28:42.115: ppp400 LCP: I CONFREQ [REQsent] id 6 len 25
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x141C6BEB (0x0506141C6BEB)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 4 Magic (0x130B045EA2F0B01B67E35A)
Aug 1 18:28:42.115: ppp400 LCP: O CONFACK [REQsent] id 6 len 25
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x141C6BEB (0x0506141C6BEB)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 4 Magic (0x130B045EA2F0B01B67E35A)
Aug 1 18:28:42.115: ppp400 LCP: I CONFACK [ACKsent] id 1 len 24
Aug 1 18:28:42.115: ppp400 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x5CDF6F56 (0x05065CDF6F56)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:28:42.115: ppp400 LCP: State is Open
12.1(17)
--------
Aug 1 18:21:59.170: ppp2258 PPP: Authorization required
Aug 1 18:21:59.170: ppp2258 PPP: Phase is ESTABLISHING
Aug 1 18:21:59.170: ppp2258 PPP: Authorization required
Aug 1 18:21:59.170: ppp2258 LCP: O CONFREQ [Closed] id 1 len 24
Aug 1 18:21:59.170: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:21:59.170: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)
Aug 1 18:21:59.170: ppp2258 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:21:59.170: ppp2258 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:21:59.438: ppp2258 LCP: I CONFREQ [REQsent] id 6 len 25
Aug 1 18:21:59.438: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:21:59.438: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:21:59.438: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0404941AA5176148FC)
Aug 1 18:21:59.438: ppp2258 LCP: O CONFACK [REQsent] id 6 len 25
Aug 1 18:21:59.438: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:21:59.438: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:21:59.438: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0404941AA5176148FC)
Aug 1 18:22:01.170: ppp2258 LCP: TIMEout: State ACKsent
Aug 1 18:22:01.170: ppp2258 LCP: O CONFREQ [ACKsent] id 2 len 24
Aug 1 18:22:01.170: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:22:01.170: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)
Aug 1 18:22:01.170: ppp2258 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:22:01.170: ppp2258 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:22:01.490: ppp2258 LCP: I CONFREQ [ACKsent] id 9 len 25
Aug 1 18:22:01.490: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:22:01.490: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:22:01.490: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0473FEA46152DA17C5)
Aug 1 18:22:01.494: ppp2258 LCP: O CONFACK [ACKsent] id 9 len 25
Aug 1 18:22:01.494: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:22:01.494: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:22:01.494: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0473FEA46152DA17C5)
Aug 1 18:22:03.187: ppp2258 LCP: TIMEout: State ACKsent
Aug 1 18:22:03.187: ppp2258 LCP: O CONFREQ [ACKsent] id 3 len 24
Aug 1 18:22:03.187: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:22:03.187: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)
The first call comes from a 3640 LAC with 12.1.11 and the second one from a 3640 LAC with 12.1.17, both with the same configuration.
The same hardware/software setup is also used in both cases in customer's side.
Any idea what might have changed in the 2nd ios in order to make the ppp timeout?
I though only the LNS was important is this phase of ppp, am i wrong? Does the LAC have any part too?
12.1(11)
--------
Aug 1 18:28:41.303: ppp400 PPP: Authorization required
Aug 1 18:28:41.303: ppp400 PPP: Phase is ESTABLISHING
Aug 1 18:28:41.303: ppp400 PPP: Authorization required
Aug 1 18:28:41.303: ppp400 LCP: O CONFREQ [Closed] id 1 len 24
Aug 1 18:28:41.303: ppp400 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:28:41.303: ppp400 LCP: MagicNumber 0x5CDF6F56 (0x05065CDF6F56)
Aug 1 18:28:41.307: ppp400 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:28:41.307: ppp400 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:28:42.115: ppp400 LCP: I CONFREQ [REQsent] id 6 len 25
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x141C6BEB (0x0506141C6BEB)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 4 Magic (0x130B045EA2F0B01B67E35A)
Aug 1 18:28:42.115: ppp400 LCP: O CONFACK [REQsent] id 6 len 25
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x141C6BEB (0x0506141C6BEB)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 4 Magic (0x130B045EA2F0B01B67E35A)
Aug 1 18:28:42.115: ppp400 LCP: I CONFACK [ACKsent] id 1 len 24
Aug 1 18:28:42.115: ppp400 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:28:42.115: ppp400 LCP: MagicNumber 0x5CDF6F56 (0x05065CDF6F56)
Aug 1 18:28:42.115: ppp400 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:28:42.115: ppp400 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:28:42.115: ppp400 LCP: State is Open
12.1(17)
--------
Aug 1 18:21:59.170: ppp2258 PPP: Authorization required
Aug 1 18:21:59.170: ppp2258 PPP: Phase is ESTABLISHING
Aug 1 18:21:59.170: ppp2258 PPP: Authorization required
Aug 1 18:21:59.170: ppp2258 LCP: O CONFREQ [Closed] id 1 len 24
Aug 1 18:21:59.170: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:21:59.170: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)
Aug 1 18:21:59.170: ppp2258 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:21:59.170: ppp2258 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:21:59.438: ppp2258 LCP: I CONFREQ [REQsent] id 6 len 25
Aug 1 18:21:59.438: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:21:59.438: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:21:59.438: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0404941AA5176148FC)
Aug 1 18:21:59.438: ppp2258 LCP: O CONFACK [REQsent] id 6 len 25
Aug 1 18:21:59.438: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:21:59.438: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:21:59.438: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0404941AA5176148FC)
Aug 1 18:22:01.170: ppp2258 LCP: TIMEout: State ACKsent
Aug 1 18:22:01.170: ppp2258 LCP: O CONFREQ [ACKsent] id 2 len 24
Aug 1 18:22:01.170: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:22:01.170: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)
Aug 1 18:22:01.170: ppp2258 LCP: MRRU 1524 (0x110405F4)
Aug 1 18:22:01.170: ppp2258 LCP: EndpointDisc 1 mmp (0x1306016D6D70)
Aug 1 18:22:01.490: ppp2258 LCP: I CONFREQ [ACKsent] id 9 len 25
Aug 1 18:22:01.490: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:22:01.490: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:22:01.490: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0473FEA46152DA17C5)
Aug 1 18:22:01.494: ppp2258 LCP: O CONFACK [ACKsent] id 9 len 25
Aug 1 18:22:01.494: ppp2258 LCP: MagicNumber 0x0EB5782D (0x05060EB5782D)
Aug 1 18:22:01.494: ppp2258 LCP: MRRU 1506 (0x110405E2)
Aug 1 18:22:01.494: ppp2258 LCP: EndpointDisc 4 Magic (0x130B0473FEA46152DA17C5)
Aug 1 18:22:03.187: ppp2258 LCP: TIMEout: State ACKsent
Aug 1 18:22:03.187: ppp2258 LCP: O CONFREQ [ACKsent] id 3 len 24
Aug 1 18:22:03.187: ppp2258 LCP: AuthProto PAP (0x0304C023)
Aug 1 18:22:03.187: ppp2258 LCP: MagicNumber 0x5CD94BF1 (0x05065CD94BF1)