P340# P340# P340#sh debug Dial on demand: Dial on demand events debugging is on Dial on demand packets debugging is on PPP: PPP authentication debugging is on PPP protocol errors debugging is on PPP packet display debugging is on Chat Scripts: Chat scripts activity debugging is on P340# P340# P340#term mon P340# ----------------------------------------------------------------------------------------------------- DSL pulled, ATM goes down, Modem Dials Up vi AS1 *OK* ----------------------------------------------------------------------------------------------------- Jun 18 13:27:44.409: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down Jun 18 13:27:44.413: Vi2 PPP: Authorization required Jun 18 13:27:44.413: Vi2 PPP: No remote authentication for call-out Jun 18 13:27:44.413: Vi2 LCP: O CONFREQ [Closed] id 66 len 10 Jun 18 13:27:44.413: Vi2 LCP: MagicNumber 0x23D3E4D4 (0x050623D3E4D4) Jun 18 13:27:44.413: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di1 Jun 18 13:27:44.629: Di1 DDR: Dialer cannot nail-up the profile - dialer string not configured Jun 18 13:27:45.057: %LINK-3-UPDOWN: Interface ATM0, changed state to down Jun 18 13:27:45.489: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access2, changed state to down Jun 18 13:27:46.141: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down Jun 18 13:27:46.141: As1 DDR: rotor dialout [best] least recent failure is also most recent failure Jun 18 13:27:46.141: As1 DDR: rotor dialout [best] trying untried dialout Jun 18 13:27:46.141: As1 DDR: rotor dialout [best] also has most recent failure Jun 18 13:27:46.141: As1 DDR: rotor dialout [best] Jun 18 13:27:46.145: Di2 DDR: Nailing up the Dialer profile [attempt 1] Jun 18 13:27:46.145: Di2 DDR: Dialer dialing - persistent dialer profile Jun 18 13:27:46.145: As1 DDR: Dialing cause Persistent Dialer Profile Jun 18 13:27:46.145: As1 DDR: Attempting to dial 08456629011 Jun 18 13:27:46.145: CHAT1: Attempting async line dialer script Jun 18 13:27:46.145: CHAT1: Dialing using Modem script: DIALOUT & System script: none Jun 18 13:27:46.149: CHAT1: process started Jun 18 13:27:46.153: CHAT1: Asserting DTR Jun 18 13:27:46.153: CHAT1: Chat script DIALOUT started Jun 18 13:27:46.153: CHAT1: Sending string: ATZ Jun 18 13:27:46.153: CHAT1: Expecting string: OK Jun 18 13:27:47.101: %LINK-3-UPDOWN: Interface Dialer2, changed state to up Jun 18 13:27:48.025: CHAT1: Completed match for expect: OK Jun 18 13:27:48.025: CHAT1: Sending string: ATDT \T<08456629011> Jun 18 13:27:48.025: CHAT1: Expecting string: CONNECT Jun 18 13:27:59.417: Vi2 DDR: re-enable timeout Jun 18 13:28:12.529: CHAT1: Completed match for expect: CONNECT Jun 18 13:28:12.529: CHAT1: Sending string: \c Jun 18 13:28:12.529: CHAT1: Chat script DIALOUT finished, status = Success Jun 18 13:28:14.529: %LINK-3-UPDOWN: Interface Async1, changed state to up Jun 18 13:28:14.529: As1 DDR: Dialer statechange to up Jun 18 13:28:14.529: %DIALER-6-BIND: Interface As1 bound to profile Di2 Jun 18 13:28:14.529: As1 DDR: Dialer call has been placed Jun 18 13:28:14.533: As1 PPP: Using dialer call direction Jun 18 13:28:14.533: As1 PPP: Treating connection as a callout Jun 18 13:28:14.533: As1 PPP: Session handle[A5000001] Session id[19] Jun 18 13:28:14.533: As1 PPP: Authorization required Jun 18 13:28:14.533: As1 PPP: No remote authentication for call-out Jun 18 13:28:14.533: As1 LCP: O CONFREQ [Closed] id 1 len 20 Jun 18 13:28:14.533: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:14.537: As1 LCP: MagicNumber 0x23D45A7D (0x050623D45A7D) Jun 18 13:28:14.537: As1 LCP: PFC (0x0702) Jun 18 13:28:14.537: As1 LCP: ACFC (0x0802) Jun 18 13:28:14.801: As1 PPP: I pkt type 0xC021, datagramsize 43 link[ppp] Jun 18 13:28:14.805: As1 PPP: I pkt type 0xC021, datagramsize 43 link[ppp] Jun 18 13:28:14.805: As1 LCP: I CONFREQ [REQsent] id 196 len 39 Jun 18 13:28:14.805: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:14.805: As1 LCP: AuthProto PAP (0x0304C023) Jun 18 13:28:14.809: As1 LCP: MagicNumber 0xFF8D4B8C (0x0506FF8D4B8C) Jun 18 13:28:14.809: As1 LCP: PFC (0x0702) Jun 18 13:28:14.809: As1 LCP: ACFC (0x0802) Jun 18 13:28:14.809: As1 LCP: MRRU 1524 (0x110405F4) Jun 18 13:28:14.809: As1 PPP: I pkt type 0xC021, datagramsize 24 link[ppp] Jun 18 13:28:14.809: As1 PPP: I pkt type 0xC021, datagramsize 24 link[ppp] Jun 18 13:28:14.809: As1 LCP: EndpointDisc 1 BSHSTACK (0x130B01425348535441434B) Jun 18 13:28:14.813: As1 LCP: O CONFREJ [REQsent] id 196 len 8 Jun 18 13:28:14.813: As1 LCP: MRRU 1524 (0x110405F4) Jun 18 13:28:14.813: As1 LCP: I CONFACK [REQsent] id 1 len 20 Jun 18 13:28:14.813: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:14.813: As1 LCP: MagicNumber 0x23D45A7D (0x050623D45A7D) Jun 18 13:28:14.817: As1 LCP: PFC (0x0702) Jun 18 13:28:14.817: As1 LCP: ACFC (0x0802) Jun 18 13:28:14.921: As1 PPP: I pkt type 0xC021, datagramsize 39 link[ppp] Jun 18 13:28:14.921: As1 PPP: I pkt type 0xC021, datagramsize 39 link[ppp] Jun 18 13:28:14.921: As1 LCP: I CONFREQ [ACKrcvd] id 197 len 35 Jun 18 13:28:14.921: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:14.925: As1 LCP: AuthProto PAP (0x0304C023) Jun 18 13:28:14.925: As1 LCP: MagicNumber 0xFF8D4B8C (0x0506FF8D4B8C) Jun 18 13:28:14.925: As1 LCP: PFC (0x0702) Jun 18 13:28:14.925: As1 LCP: ACFC (0x0802) Jun 18 13:28:14.925: As1 LCP: EndpointDisc 1 BSHSTACK (0x130B01425348535441434B) Jun 18 13:28:14.925: As1 LCP: O CONFNAK [ACKrcvd] id 197 len 9 Jun 18 13:28:14.925: As1 LCP: AuthProto CHAP (0x0305C22305) Jun 18 13:28:15.041: As1 PPP: I pkt type 0xC021, datagramsize 40 link[ppp] Jun 18 13:28:15.041: As1 PPP: I pkt type 0xC021, datagramsize 40 link[ppp] Jun 18 13:28:15.045: As1 LCP: I CONFREQ [ACKrcvd] id 198 len 36 Jun 18 13:28:15.045: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:15.045: As1 LCP: AuthProto CHAP (0x0305C22305) Jun 18 13:28:15.045: As1 LCP: MagicNumber 0xFF8D4B8C (0x0506FF8D4B8C) Jun 18 13:28:15.045: As1 LCP: PFC (0x0702) Jun 18 13:28:15.049: As1 LCP: ACFC (0x0802) Jun 18 13:28:15.049: As1 LCP: EndpointDisc 1 BSHSTACK (0x130B01425348535441434B) Jun 18 13:28:15.049: As1 LCP: O CONFACK [ACKrcvd] id 198 len 36 Jun 18 13:28:15.049: As1 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 18 13:28:15.049: As1 LCP: AuthProto CHAP (0x0305C22305) Jun 18 13:28:15.049: As1 LCP: MagicNumber 0xFF8D4B8C (0x0506FF8D4B8C) Jun 18 13:28:15.053: As1 LCP: PFC (0x0702) Jun 18 13:28:15.053: As1 LCP: ACFC (0x0802) Jun 18 13:28:15.053: As1 LCP: EndpointDisc 1 BSHSTACK (0x130B01425348535441434B) Jun 18 13:28:15.053: As1 PPP: No authorization without authentication Jun 18 13:28:15.169: As1 PPP: I pkt type 0xC223, datagramsize 33 link[ppp] Jun 18 13:28:15.169: As1 PPP: I pkt type 0xC223, datagramsize 33 link[ppp] Jun 18 13:28:15.169: As1 CHAP: I CHALLENGE id 26 len 29 from "BSHSTACK" Jun 18 13:28:15.173: As1 CHAP: Using hostname from interface CHAP Jun 18 13:28:15.173: As1 CHAP: Using password from interface CHAP Jun 18 13:28:15.173: As1 CHAP: O RESPONSE id 26 len 51 from "store340_pea@vpn-connect.co.uk" Jun 18 13:28:15.373: As1 PPP: I pkt type 0xC223, datagramsize 6 link[ppp] Jun 18 13:28:15.373: As1 PPP: I pkt type 0xC223, datagramsize 6 link[ppp] Jun 18 13:28:15.373: As1 CHAP: I SUCCESS id 26 len 4 Jun 18 13:28:15.377: As1 IPCP: O CONFREQ [Closed] id 1 len 10 Jun 18 13:28:15.377: As1 IPCP: Address 0.0.0.0 (0x030600000000) Jun 18 13:28:15.385: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.385: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.385: As1 IPCP: I CONFREQ [REQsent] id 1 len 10 Jun 18 13:28:15.385: As1 IPCP: Address 10.250.38.224 (0x03060AFA26E0) Jun 18 13:28:15.385: As1 IPCP: O CONFACK [REQsent] id 1 len 10 Jun 18 13:28:15.385: As1 IPCP: Address 10.250.38.224 (0x03060AFA26E0) Jun 18 13:28:15.489: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.489: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.573: As1 IPCP: I CONFNAK [ACKsent] id 1 len 10 Jun 18 13:28:15.573: As1 IPCP: Address 10.250.101.196 (0x03060AFA65C4) P340# P340# P340# Jun 18 13:28:15.573: As1 IPCP: O CONFREQ [ACKsent] id 2 len 10 Jun 18 13:28:15.573: As1 IPCP: Address 10.250.101.196 (0x03060AFA65C4) Jun 18 13:28:15.681: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.681: As1 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:28:15.685: As1 IPCP: I CONFACK [ACKsent] id 2 len 10 Jun 18 13:28:15.685: As1 IPCP: Address 10.250.101.196 (0x03060AFA65C4) Jun 18 13:28:15.709: As1 PPP: I pkt type 0x0021, datagramsize 53 link[ip] Jun 18 13:28:15.709: As1 PPP: I pkt type 0x0021, datagramsize 53 link[ip] Jun 18 13:28:15.717: As1 PPP: I pkt type 0x0021, datagramsize 53 link[ip] Jun 18 13:28:15.717: As1 PPP: I pkt type 0x0021, datagramsize 53 link[ip] Jun 18 13:28:15.729: Di2 DDR: ip (s=10.250.101.196, d=224.0.0.9), 52 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.733: As1 DDR: dialer protocol up Jun 18 13:28:15.733: Di2 DDR: Persistent Dialer Profile nailed up successfully Jun 18 13:28:15.737: Di2 DDR: ip (s=10.250.101.196, d=224.0.0.9), 52 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.737: As1 PPP: O pkt type 0x0021, datagramsize 53 Jun 18 13:28:15.737: Di2 DDR: ip (s=10.250.101.196, d=224.0.0.9), 52 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.737: As1 PPP: O pkt type 0x0021, datagramsize 53 Jun 18 13:28:15.737: Di2 DDR: ip (s=10.250.101.196, d=224.0.0.9), 52 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.741: As1 PPP: O pkt type 0x0021, datagramsize 53 Jun 18 13:28:15.861: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 532 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.861: As1 PPP: O pkt type 0x0021, datagramsize 533 Jun 18 13:28:15.921: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 92 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.921: As1 PPP: O pkt type 0x0021, datagramsize 93 Jun 18 13:28:15.921: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:15.925: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:15.925: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 532 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.925: As1 PPP: O pkt type 0x0021, datagramsize 533 Jun 18 13:28:15.969: As1 PPP: O pkt type 0x0021, datagramsize 533 Jun 18 13:28:15.969: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 532 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.969: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:15.969: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:15.969: As1 PPP: O pkt type 0x0021, datagramsize 533 Jun 18 13:28:15.973: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 532 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.989: Di2 DDR: ip (s=10.250.101.196, d=10.250.38.224), 92 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:15.989: As1 PPP: O pkt type 0x0021, datagramsize 93 Jun 18 13:28:16.017: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:16.457: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:16.461: %LINEPROTO-5-UPDOWN: Line protocol on Interface Async1, changed state to up Jun 18 13:28:16.505: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:17.413: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] Jun 18 13:28:17.441: As1 LCP: I ECHOREQ [Open] id 1 len 12 magic 0xFF8D4B8C Jun 18 13:28:17.445: As1 LCP: O ECHOREP [Open] id 1 len 12 magic 0x23D45A7D Jun 18 13:28:17.481: As1 PPP: I pkt type 0x0021, datagramsize 533 link[ip] ----------------------------------------------------------------------------------------------------- DSL Re plugged in, ATM goes UP, Modem drops connection *OK* ----------------------------------------------------------------------------------------------------- Jun 18 13:28:26.685: As1 LCP: I ECHOREQ [Open] id 2 len 12 magic 0xFF8D4B8C Jun 18 13:28:26.685: As1 LCP: O ECHOREP [Open] id 2 len 12 magic 0x23D45A7D Jun 18 13:28:43.665: Di2 DDR: ip (s=10.250.101.196, d=224.0.0.9), 72 bytes, outgoing uninteresting (no dialer-group defined) Jun 18 13:28:43.665: As1 PPP: O pkt type 0x0021, datagramsize 73 Jun 18 13:28:47.461: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up Jun 18 13:28:47.461: Vi2 DDR: Dialer statechange to up Jun 18 13:28:47.461: %DIALER-6-BIND: Interface Vi2 bound to profile Di1 Jun 18 13:28:47.461: Vi2 PPP: Using dialer call direction Jun 18 13:28:47.461: Vi2 PPP: Treating connection as a callout Jun 18 13:28:47.465: Vi2 PPP: Session handle[6100000E] Session id[20] Jun 18 13:28:47.465: Vi2 PPP: Authorization required Jun 18 13:28:47.465: Vi2 PPP: No remote authentication for call-out Jun 18 13:28:47.465: Vi2 LCP: O CONFREQ [Closed] id 67 len 10 Jun 18 13:28:47.465: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:48.021: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 18 13:28:48.025: As1 DDR: disconnecting call Jun 18 13:28:48.025: Di2 DDR: dialer shutdown complete Jun 18 13:28:49.129: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up Jun 18 13:28:49.801: Vi2 LCP: Timeout: State REQsent Jun 18 13:28:49.801: Vi2 LCP: O CONFREQ [REQsent] id 68 len 10 Jun 18 13:28:49.801: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:50.033: %LINK-5-CHANGED: Interface Async1, changed state to reset Jun 18 13:28:50.033: As1 DDR: has total 0 call(s), dial_out 0, dial_in 0 Jun 18 13:28:50.033: %DIALER-6-UNBIND: Interface As1 unbound from profile Di2 Jun 18 13:28:50.037: %LINK-5-CHANGED: Interface Dialer2, changed state to standby mode Jun 18 13:28:51.269: %LINEPROTO-5-UPDOWN: Line protocol on Interface Async1, changed state to down Jun 18 13:28:51.701: Vi2 LCP: Timeout: State REQsent Jun 18 13:28:51.701: Vi2 LCP: O CONFREQ [REQsent] id 69 len 10 Jun 18 13:28:51.701: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:51.721: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:28:52.153: Vi2 LCP: I CONFACK [REQsent] id 69 len 10 Jun 18 13:28:52.153: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:53.649: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:28:53.653: Vi2 LCP: O CONFREQ [ACKrcvd] id 70 len 10 Jun 18 13:28:53.653: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:53.669: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:28:54.101: Vi2 LCP: I CONFACK [REQsent] id 70 len 10 Jun 18 13:28:54.101: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:55.565: %LINK-3-UPDOWN: Interface Async1, changed state to down Jun 18 13:28:55.569: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:28:55.569: Vi2 LCP: O CONFREQ [ACKrcvd] id 71 len 10 Jun 18 13:28:55.569: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:55.593: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:28:56.001: Vi2 LCP: I CONFACK [REQsent] id 71 len 10 Jun 18 13:28:56.001: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:57.861: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:28:57.861: Vi2 LCP: O CONFREQ [ACKrcvd] id 72 len 10 Jun 18 13:28:57.861: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:58.025: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:28:58.309: Vi2 LCP: I CONFACK [REQsent] id 72 len 10 Jun 18 13:28:58.309: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:59.725: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:28:59.725: Vi2 LCP: O CONFREQ [ACKrcvd] id 73 len 10 Jun 18 13:28:59.725: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:28:59.813: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:29:00.165: Vi2 LCP: I CONFACK [REQsent] id 73 len 10 Jun 18 13:29:00.165: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:29:01.565: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:29:01.569: Vi2 LCP: O CONFREQ [ACKrcvd] id 74 len 10 Jun 18 13:29:01.569: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:29:01.585: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:29:02.005: Vi2 LCP: I CONFACK [REQsent] id 74 len 10 Jun 18 13:29:02.005: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:29:03.641: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:29:03.641: Vi2 LCP: O CONFREQ [ACKrcvd] id 75 len 10 Jun 18 13:29:03.641: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:29:03.661: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:29:04.065: Vi2 LCP: I CONFACK [REQsent] id 75 len 10 Jun 18 13:29:04.065: Vi2 LCP: MagicNumber 0x23D4DB22 (0x050623D4DB22) Jun 18 13:29:04.833: Vi2 PPP: I pkt type 0xC021, datagramsize 17 link[ppp] Jun 18 13:29:05.105: Vi2 LCP: I CONFREQ [ACKrcvd] id 2 len 15 Jun 18 13:29:05.105: Vi2 LCP: AuthProto CHAP (0x0305C22305) Jun 18 13:29:05.105: Vi2 LCP: MagicNumber 0x3E9A87AA (0x05063E9A87AA) Jun 18 13:29:05.105: Vi2 LCP: O CONFACK [ACKrcvd] id 2 len 15 Jun 18 13:29:05.109: Vi2 LCP: AuthProto CHAP (0x0305C22305) Jun 18 13:29:05.109: Vi2 LCP: MagicNumber 0x3E9A87AA (0x05063E9A87AA) Jun 18 13:29:05.109: Vi2 PPP: No authorization without authentication Jun 18 13:29:05.113: As1 DDR: re-enable timeout Jun 18 13:29:05.125: Vi2 PPP: I pkt type 0xC223, datagramsize 31 link[ppp] Jun 18 13:29:05.561: Vi2 CHAP: I CHALLENGE id 1 len 29 from "he-1-dsl" Jun 18 13:29:05.565: Vi2 CHAP: Using hostname from interface CHAP Jun 18 13:29:05.565: Vi2 CHAP: Using password from interface CHAP Jun 18 13:29:05.565: Vi2 CHAP: O RESPONSE id 1 len 51 from "store340_pea@vpn-connect.co.uk" Jun 18 13:29:05.625: Vi2 PPP: I pkt type 0xC223, datagramsize 6 link[ppp] Jun 18 13:29:05.629: Vi2 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:29:06.013: Vi2 CHAP: I SUCCESS id 1 len 4 Jun 18 13:29:06.017: Vi2 IPCP: O CONFREQ [Closed] id 1 len 10 Jun 18 13:29:06.017: Vi2 IPCP: Address 0.0.0.0 (0x030600000000) Jun 18 13:29:06.021: Vi2 IPCP: I CONFREQ [REQsent] id 1 len 10 Jun 18 13:29:06.021: Vi2 IPCP: Address 10.250.38.224 (0x03060AFA26E0) Jun 18 13:29:06.021: Vi2 IPCP: O CONFACK [REQsent] id 1 len 10 Jun 18 13:29:06.021: Vi2 IPCP: Address 10.250.38.224 (0x03060AFA26E0) Jun 18 13:29:06.033: Vi2 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:29:06.461: Vi2 IPCP: I CONFNAK [ACKsent] id 1 len 10 Jun 18 13:29:06.465: Vi2 IPCP: Address 10.250.80.92 (0x03060AFA505C) Jun 18 13:29:06.465: Vi2 IPCP: O CONFREQ [ACKsent] id 2 len 10 Jun 18 13:29:06.465: Vi2 IPCP: Address 10.250.80.92 (0x03060AFA505C) Jun 18 13:29:06.517: Vi2 PPP: I pkt type 0x8021, datagramsize 12 link[ip] Jun 18 13:29:06.549: Vi2 PPP: I pkt type 0x0021, datagramsize 54 link[ip] Jun 18 13:29:06.549: Vi2 IP: Non-NCP packet, discarding Jun 18 13:29:06.629: Vi2 LCP-FS: I ECHOREQ [Open] id 1 len 12 magic 0x3E9A87AA Jun 18 13:29:06.633: Vi2 LCP-FS: O ECHOREP [Open] id 1 len 12 magic 0x23D4DB22 Jun 18 13:29:06.785: Vi2 IPCP: I CONFACK [ACKsent] id 2 len 10 Jun 18 13:29:06.789: Vi2 IPCP: Address 10.250.80.92 (0x03060AFA505C) Jun 18 13:29:07.357: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access2, changed state to up Jun 18 13:29:08.665: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 52 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:08.773: Vi2 DDR: dialer protocol up Jun 18 13:29:08.773: Di1 DDR: Persistent Dialer Profile nailed up successfully Jun 18 13:29:08.781: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 52 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:08.781: Vi2 PPP: O pkt type 0x0021, datagramsize 54 Jun 18 13:29:08.781: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 52 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:08.781: Vi2 PPP: O pkt type 0x0021, datagramsize 54 Jun 18 13:29:08.793: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 52 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:08.793: Vi2 PPP: O pkt type 0x0021, datagramsize 54 Jun 18 13:29:08.813: Vi2 PPP: I pkt type 0x0021, datagramsize 534 link[ip] Jun 18 13:29:09.081: Vi2 PPP: I pkt type 0x0021, datagramsize 534 link[ip] Jun 18 13:29:09.229: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 532 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.233: Vi2 PPP: O pkt type 0x0021, datagramsize 534 Jun 18 13:29:09.233: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 532 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.233: Vi2 PPP: O pkt type 0x0021, datagramsize 534 Jun 18 13:29:09.233: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 532 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.233: Vi2 PPP: O pkt type 0x0021, datagramsize 534 Jun 18 13:29:09.237: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 532 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.237: Vi2 PPP: O pkt type 0x0021, datagramsize 534 Jun 18 13:29:09.237: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 312 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.237: Vi2 PPP: O pkt type 0x0021, datagramsize 314 Jun 18 13:29:09.241: Di1 DDR: ip (s=10.250.80.92, d=88.151.217.68), 76 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:09.241: Vi2 PPP: O pkt type 0x0021, datagramsize 78 Jun 18 13:29:13.317: Vi2 LCP: O ECHOREQ [Open] id 1 len 12 magic 0x23D4DB22 Jun 18 13:29:13.337: Vi2 LCP-FS: I ECHOREP [Open] id 1 len 12 magic 0x3E9A87AA Jun 18 13:29:13.337: Vi2 LCP-FS: Received id 1, sent id 1, line up Jun 18 13:29:23.409: Vi2 LCP: O ECHOREQ [Open] id 2 len 12 magic 0x23D4DB22 Jun 18 13:29:23.425: Vi2 LCP-FS: I ECHOREP [Open] id 2 len 12 magic 0x3E9A87AA Jun 18 13:29:23.425: Vi2 LCP-FS: Received id 2, sent id 2, line up P340# P340# ----------------------------------------------------------------------------------------------------- DSL pulled 2nd , ATM goes down, Modem *Fails* to Dials Up vi AS1 ----------------------------------------------------------------------------------------------------- P340# P340# Jun 18 13:29:33.649: Vi2 LCP: O ECHOREQ [Open] id 3 len 12 magic 0x23D4DB22 Jun 18 13:29:33.665: Vi2 LCP-FS: I ECHOREP [Open] id 3 len 12 magic 0x3E9A87AA Jun 18 13:29:33.665: Vi2 LCP-FS: Received id 3, sent id 3, line upun 18 13:29:34.457: Vi2 PPP: I pkt type 0x0021, datagramsize 534 link[ip] Jun 18 13:29:35.777: Di1 DDR: ip (s=10.250.80.92, d=224.0.0.9), 532 bytes, outgoing interesting (pppox over dialer) Jun 18 13:29:35.777: Vi2 PPP: O pkt type 0x0021, datagramsize 534 Jun 18 13:29:35.821: Vi2 PPP: O pkt type 0x0021, datagramsize 334 Jun 18 13:29:42.469: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down Jun 18 13:29:42.473: Vi2 PPP: Authorization required Jun 18 13:29:42.473: Vi2 PPP: No remote authentication for call-out Jun 18 13:29:42.473: Vi2 LCP: O CONFREQ [Closed] id 76 len 10 Jun 18 13:29:42.473: Vi2 LCP: MagicNumber 0x23D5B203 (0x050623D5B203) Jun 18 13:29:42.473: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di1 Jun 18 13:29:42.681: Di1 DDR: Dialer cannot nail-up the profile - dialer string not configured Jun 18 13:29:43.113: %LINK-3-UPDOWN: Interface ATM0, changed state to down Jun 18 13:29:43.541: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access2, changed state to down Jun 18 13:29:44.197: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down Jun 18 13:29:44.201: Di2 DDR: Dialer profile is already nailed-up Jun 18 13:29:45.233: %LINK-3-UPDOWN: Interface Dialer2, changed state to up Jun 18 13:29:57.477: Vi2 DDR: re-enable timeout ----------------------------------------------------------------------------------------------------- DSL Replugged in, ATM goes UP, Modem drops connection *OK* ----------------------------------------------------------------------------------------------------- Jun 18 13:30:44.505: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up Jun 18 13:30:44.505: Vi2 DDR: Dialer statechange to up Jun 18 13:30:44.505: %DIALER-6-BIND: Interface Vi2 bound to profile Di1 Jun 18 13:30:44.505: Vi2 PPP: Using dialer call direction Jun 18 13:30:44.505: Vi2 PPP: Treating connection as a callout Jun 18 13:30:44.509: Vi2 PPP: Session handle[F5000011] Session id[22] Jun 18 13:30:44.509: Vi2 PPP: Authorization required Jun 18 13:30:44.509: Vi2 PPP: No remote authentication for call-out Jun 18 13:30:44.509: Vi2 LCP: O CONFREQ [Closed] id 77 len 10 Jun 18 13:30:44.509: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:45.029: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 18 13:30:45.033: Di2 DDR: dialer shutdown complete Jun 18 13:30:46.029: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up Jun 18 13:30:46.513: Vi2 LCP: Timeout: State REQsent Jun 18 13:30:46.513: Vi2 LCP: O CONFREQ [REQsent] id 78 len 10 Jun 18 13:30:46.513: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:47.033: %LINK-5-CHANGED: Interface Dialer2, changed state to standby mode P340# Jun 18 13:30:48.529: Vi2 LCP: Timeout: State REQsent Jun 18 13:30:48.529: Vi2 LCP: O CONFREQ [REQsent] id 79 len 10 Jun 18 13:30:48.529: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:48.573: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:30:48.573: Vi2 LCP: I CONFACK [REQsent] id 79 len 10 Jun 18 13:30:48.573: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:50.545: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:30:50.545: Vi2 LCP: O CONFREQ [ACKrcvd] id 80 len 10 Jun 18 13:30:50.545: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:50.569: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:30:50.569: Vi2 LCP: I CONFACK [REQsent] id 80 len 10 Jun 18 13:30:50.569: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:52.561: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:30:52.561: Vi2 LCP: O CONFREQ [ACKrcvd] id 81 len 10 Jun 18 13:30:52.561: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:52.581: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:30:52.581: Vi2 LCP: I CONFACK [REQsent] id 81 len 10 Jun 18 13:30:52.581: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:54.577: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:30:54.577: Vi2 LCP: O CONFREQ [ACKrcvd] id 82 len 10 Jun 18 13:30:54.577: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:54.593: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:30:54.597: Vi2 LCP: I CONFACK [REQsent] id 82 len 10 Jun 18 13:30:54.597: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:56.593: Vi2 LCP: Timeout: State ACKrcvd Jun 18 13:30:56.593: Vi2 LCP: O CONFREQ [ACKrcvd] id 83 len 10 Jun 18 13:30:56.593: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458) Jun 18 13:30:56.609: Vi2 PPP: I pkt type 0xC021, datagramsize 12 link[ppp] Jun 18 13:30:56.613: Vi2 LCP: I CONFACK [REQsent] id 83 len 10 Jun 18 13:30:56.613: Vi2 LCP: MagicNumber 0x23D6A458 (0x050623D6A458)