Debug of successful ppp connection using baselayout-2

ppp_connect_debug.txt (text/plain), 8.18 KB, created by Matthew Schultz on 2008-05-27 14:27:40 UTC

May 27 09:23:12 gandalf pppd[2102]: pppd 2.4.4 started by root, uid 0
May 27 09:23:12 gandalf pppd[2102]: Using interface ppp0
May 27 09:23:12 gandalf pppd[2102]: local  IP address 10.64.64.64
May 27 09:23:12 gandalf pppd[2102]: remote IP address 10.112.112.112
May 27 09:23:12 gandalf /etc/init.d/net.ppp0[860]: WARNING: net.ppp0 has started, but is inactive
May 27 09:23:31 gandalf pppd[2102]: Starting link
May 27 09:23:32 gandalf chat[2116]: abort on (BUSY)
May 27 09:23:32 gandalf chat[2116]: abort on (ERROR)
May 27 09:23:32 gandalf chat[2116]: abort on (NO ANSWER)
May 27 09:23:32 gandalf chat[2116]: abort on (NO CARRIER)
May 27 09:23:32 gandalf chat[2116]: abort on (NO DIALTONE)
May 27 09:23:32 gandalf chat[2116]: abort on (Invalid Login)
May 27 09:23:32 gandalf chat[2116]: abort on (Login incorrect)
May 27 09:23:32 gandalf chat[2116]: timeout set to 5 seconds
May 27 09:23:32 gandalf chat[2116]: send (ATZ^M)
May 27 09:23:32 gandalf chat[2116]: expect (OK)
May 27 09:23:32 gandalf chat[2116]: OK
May 27 09:23:32 gandalf chat[2116]:  -- got it
May 27 09:23:32 gandalf chat[2116]: send (AT^M)
May 27 09:23:32 gandalf chat[2116]: expect (OK)
May 27 09:23:32 gandalf chat[2116]: ^M
May 27 09:23:33 gandalf chat[2116]: ATZ^M^M
May 27 09:23:33 gandalf chat[2116]: OK
May 27 09:23:33 gandalf chat[2116]:  -- got it
May 27 09:23:33 gandalf chat[2116]: send (ATDT5551234^M)
May 27 09:23:33 gandalf chat[2116]: timeout set to 60 seconds
May 27 09:23:33 gandalf chat[2116]: expect (CONNECT)
May 27 09:23:33 gandalf chat[2116]: ^M
May 27 09:23:33 gandalf chat[2116]: AT^M^M
May 27 09:23:33 gandalf chat[2116]: OK^M
May 27 09:23:55 gandalf chat[2116]: ATDT5551234^M^M
May 27 09:23:55 gandalf chat[2116]: CONNECT
May 27 09:23:55 gandalf chat[2116]:  -- got it
May 27 09:23:55 gandalf chat[2116]: send (^M)
May 27 09:23:55 gandalf chat[2116]: timeout set to 5 seconds
May 27 09:23:55 gandalf chat[2116]: expect (~)
May 27 09:23:55 gandalf chat[2116]:  24000 V42bis^M
May 27 09:23:55 gandalf chat[2116]: ^M
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]:
May 27 09:23:55 gandalf chat[2116]: UQKT2 ** Welcome to hou4-dial1.popsite.net **^M
May 27 09:23:55 gandalf chat[2116]: ^M
May 27 09:23:55 gandalf chat[2116]: ^M
May 27 09:24:00 gandalf chat[2116]: alarm
May 27 09:24:00 gandalf chat[2116]: send (^M)
May 27 09:24:00 gandalf chat[2116]: send (^M)
May 27 09:24:00 gandalf pppd[2102]: Serial connection established.
May 27 09:24:00 gandalf pppd[2102]: using channel 49
May 27 09:24:00 gandalf pppd[2102]: Connect: ppp0 <--> /dev/ttyS2
May 27 09:24:01 gandalf pppd[2102]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xaba92fe0> <pcomp> <accomp>]
May 27 09:24:04 gandalf pppd[2102]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xaba92fe0> <pcomp> <accomp>]
May 27 09:24:04 gandalf pppd[2102]: rcvd [LCP ConfReq id=0x1 < 00 04 00 00> <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <mrru 1500> <endpoint 
[local:68.6f.75.34.73.31]>]
May 27 09:24:04 gandalf pppd[2102]: sent [LCP ConfRej id=0x1 < 00 04 00 00> <mrru 1500>]
May 27 09:24:04 gandalf pppd[2102]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xaba92fe0> <pcomp> <accomp>]
May 27 09:24:04 gandalf pppd[2102]: rcvd [LCP ConfReq id=0x2 <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <endpoint [local:68.6f.75.34.73.31]>]
May 27 09:24:04 gandalf pppd[2102]: sent [LCP ConfAck id=0x2 <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <endpoint [local:68.6f.75.34.73.31]>]
May 27 09:24:04 gandalf pppd[2102]: sent [LCP EchoReq id=0x0 magic=0xaba92fe0]
May 27 09:24:04 gandalf pppd[2102]: sent [PAP AuthReq id=0x1 user="[e-mail]" password=<hidden>]
May 27 09:24:04 gandalf pppd[2102]: rcvd [LCP EchoRep id=0x0 magic=0x0]
May 27 09:24:05 gandalf pppd[2102]: rcvd [PAP AuthAck id=0x1 ""]
May 27 09:24:05 gandalf pppd[2102]: PAP authentication succeeded
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfReq id=0x1 <mppe -H -M -S -L -D -C> <deflate 15> <deflate(old#) 15> <bsd v1 15>]
May 27 09:24:05 gandalf pppd[2102]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 66.217.192.8>]
May 27 09:24:05 gandalf pppd[2102]: sent [IPCP ConfAck id=0x1 <compress VJ 0f 01> <addr 66.217.192.8>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfReq id=0x1 <lzs 00 01 01 03>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfRej id=0x1 <lzs 00 01 01 03>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfRej id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfReq id=0x2 <mppe -H -M -S -L -D -C>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [IPCP ConfNak id=0x1 <addr 66.217.210.45>]
May 27 09:24:05 gandalf pppd[2102]: sent [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 66.217.210.45>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfReq id=0x2 <mppe -H -M -S -L -D +C>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfRej id=0x2 <mppe -H -M -S -L -D +C>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfNak id=0x2 <mppe -H -M -S -L -D +C>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfReq id=0x3 <mppe -H -M -S -L -D -C>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [IPCP ConfAck id=0x2 <compress VJ 0f 01> <addr 66.217.210.45>]
May 27 09:24:05 gandalf pppd[2102]: Local IP address changed to 66.217.210.45
May 27 09:24:05 gandalf pppd[2102]: Remote IP address changed to 66.217.192.8
May 27 09:24:05 gandalf pppd[2102]: sent [IP data] 45 00 00 48 6f 2e 40 00 ...
May 27 09:24:05 gandalf pppd[2102]: Script /etc/ppp/ip-up started (pid 2117)
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfReq id=0x3 <lzs 00 01 04>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfRej id=0x3 <lzs 00 01 04>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfNak id=0x3 <lzs 00 01 04>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfReq id=0x4 <mppe -H -M -S -L -D -C>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfReq id=0x4 <lzs 00 01 03>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfRej id=0x4 <lzs 00 01 03>]
May 27 09:24:05 gandalf pppd[2102]: Script /etc/ppp/ip-up finished (pid 2117), status = 0x0
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfNak id=0x4 <lzs 00 01 03>]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP ConfRej id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:05 gandalf pppd[2102]: Received bad configure-rej:  12 06 00 00 00 00
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP TermReq id=0x5]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP TermAck id=0x5]
May 27 09:24:05 gandalf pppd[2102]: rcvd [CCP TermReq id=0x6]
May 27 09:24:05 gandalf pppd[2102]: sent [CCP TermAck id=0x6]
May 27 09:24:08 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:11 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:14 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:17 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:20 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:23 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:26 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:29 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:32 gandalf pppd[2102]: sent [CCP ConfReq id=0x5 <mppe -H -M -S -L -D -C>]
May 27 09:24:35 gandalf pppd[2102]: CCP: timeout sending Config-Requests