Okay, I haven't gotten any useful responses here. I'm going to
try again. I think I didn't explain what was going on well
enough, and people were misled by the X2 in the subject line.
I'm having problems getting Linux 2.0.36 running with ppp-2.3.5.
I got them to compile together, and everything starts out okay,
but as PPP goes through it's negotiatiion, something is causing
it to drop all the received frames as having a bad fcs.
I'm using the right device, I'm using hardware handshaking,
and I'm almost always getting connected at (according to the
response from the modem): 50666/ARQ/x2/LAPM/V42BIS (no MNP5).
I don't think the physical layer is the problem because initially
all the frames come across just fine.
The connection starts out fine, it connects as above, the chat
script succeeds. Then pppd initializaes the channel like:
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set xasyncmap
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set xmit asyncmap ffffffff
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set mru to 5dc
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Then, it sends it's ConfReq, and receives a ConfReq from the
peer. Everything is fine, it sends a ConfAck, and it updates
it's kernel config:
Nov 19 13:39:05 taz pppd[1830]: rcvd [LCP ConfReq id=0x2 <mru 1514>
<asyncmap 0x0> <magic 0xf6bc9967> <pcomp> <accomp>]
Nov 19 13:39:05 taz pppd[1830]: fsm_rconfreq(LCP): Rcvd id 2.
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd MRU
Nov 19 13:39:05 taz pppd[1830]: (1514)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd ASYNCMAP
Nov 19 13:39:05 taz pppd[1830]: (0)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd MAGICNUMBER
Nov 19 13:39:05 taz pppd[1830]: (f6bc9967)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd PCOMPRESSION
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd ACCOMPRESSION
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: returning CONFACK.
Nov 19 13:39:05 taz pppd[1830]: sent [LCP ConfAck id=0x2 <mru 1514>
<asyncmap 0x0> <magic 0xf6bc9967> <pcomp> <accomp>]
Nov 19 13:39:05 taz kernel: ppp: write frame, count = 28
Nov 19 13:39:05 taz kernel: FF 03 C0 21 02 02 00 18 ...!....
Nov 19 13:39:05 taz kernel: 01 04 05 EA 02 06 00 00 ........
Nov 19 13:39:05 taz kernel: 00 00 05 06 F6 BC 99 67 .......g
Nov 19 13:39:05 taz kernel: 07 02 08 02 ....
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set xmit asyncmap 0
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0003
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set mru to 5dc
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0003
Nov 19 13:39:05 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:05 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:05 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0043
Nov 19 13:39:05 taz kernel: ppp: receive buffer, count = 22
Nov 19 13:39:05 taz kernel: 7E 80 21 01 03 00 10 02 ~.!.....
Nov 19 13:39:05 taz kernel: 06 00 2D 0F 00 03 06 C0 ..-.....
Nov 19 13:39:05 taz kernel: 30 20 04 31 F8 7E 0 .1.~
Nov 19 13:39:05 taz kernel: ppp: frame with bad fcs, length = 10
After that point, ALL recevied frames/buffers have bad fcs.
I think that something in the ConfReq setting is causing the fcs to
be calculated incorrrectly, or the frames are truncated, or something
like that. I have looked at it, but I don't see any problems.
I was hoping someone could look at the log, and look for
something like that.
This is a stock RedHat 5.1 system.
Thanks,
Pete
P.S. Complete log attached again...
Nov 19 13:38:35 taz ifup-ppp: pppd started for ppp0 on /dev/cua1 at 115200
Nov 19 13:38:35 taz pppd[1830]: pppd 2.3.5 started by root, uid 0
Nov 19 13:38:35 taz pppd[1830]: Connecting with </usr/sbin/chat -v -f
/etc/sysconfig/network-scripts/chat-ppp0>
Nov 19 13:38:36 taz chat[1832]: abort on (BUSY)
Nov 19 13:38:36 taz chat[1832]: abort on (ERROR)
Nov 19 13:38:36 taz chat[1832]: abort on (NO CARRIER)
Nov 19 13:38:36 taz chat[1832]: abort on (NO DIALTONE)
Nov 19 13:38:36 taz chat[1832]: abort on (Invalid Login)
Nov 19 13:38:36 taz chat[1832]: abort on (Login incorrect)
Nov 19 13:38:36 taz chat[1832]: send (AT &F1 S11=50^M)
Nov 19 13:38:36 taz chat[1832]: expect (OK)
Nov 19 13:38:37 taz chat[1832]: AT &F1 S11=50^M^M
Nov 19 13:38:37 taz chat[1832]: OK
Nov 19 13:38:37 taz chat[1832]: -- got it
Nov 19 13:38:37 taz chat[1832]: send (ATDT358-9910^M)
Nov 19 13:38:37 taz chat[1832]: expect (CONNECT)
Nov 19 13:38:37 taz chat[1832]: ^M
Nov 19 13:38:56 taz chat[1832]: ATDT358-9910^M^M
Nov 19 13:38:56 taz chat[1832]: CONNECT
Nov 19 13:38:56 taz chat[1832]: -- got it
Nov 19 13:38:56 taz chat[1832]: send (^M)
Nov 19 13:38:56 taz chat[1832]: expect (ogin:)
Nov 19 13:38:56 taz chat[1832]: 52000/ARQ/x2/LAPM/V42BIS^M
Nov 19 13:38:56 taz chat[1832]: ^M
Nov 19 13:38:56 taz chat[1832]: Welcome to USRobotics^M
Nov 19 13:38:56 taz chat[1832]: The Intelligent Choice in Information Access^M
Nov 19 13:39:01 taz chat[1832]: login:
Nov 19 13:39:01 taz chat[1832]: -- got it
Nov 19 13:39:01 taz chat[1832]: send (merlin^M)
Nov 19 13:39:01 taz chat[1832]: expect (ord:)
Nov 19 13:39:01 taz chat[1832]: merlin^M
Nov 19 13:39:01 taz chat[1832]: Password:
Nov 19 13:39:01 taz chat[1832]: -- got it
Nov 19 13:39:01 taz chat[1832]: send (\^space\$^M)
Nov 19 13:39:01 taz chat[1832]: timeout set to 5 seconds
Nov 19 13:39:01 taz chat[1832]: expect (~)
Nov 19 13:39:01 taz chat[1832]: ^M
Nov 19 13:39:02 taz chat[1832]: ~
Nov 19 13:39:02 taz chat[1832]: -- got it
Nov 19 13:39:02 taz chat[1832]: send (^M)
Nov 19 13:39:02 taz pppd[1830]: Serial connection established.
Nov 19 13:39:03 taz pppd[1830]: setting ppp_fd to -1
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set xasyncmap
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set xmit asyncmap ffffffff
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set mru to 5dc
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Nov 19 13:39:03 taz kernel: ppp_tty_ioctl: set flags to f0000
Nov 19 13:39:03 taz kernel: ppp: write frame, count = 18
Nov 19 13:39:03 taz kernel: FF 03 C0 21 01 01 00 0E ...!....
Nov 19 13:39:03 taz kernel: 05 06 00 00 FF AF 07 02 ........
Nov 19 13:39:03 taz kernel: 08 02 ..
Nov 19 13:39:03 taz pppd[1830]: set kernel debugging level to 15
Nov 19 13:39:03 taz pppd[1830]: get flags = 0
Nov 19 13:39:03 taz pppd[1830]: set flags = 0
Nov 19 13:39:03 taz pppd[1830]: Using version 2.3.5 of PPP driver
Nov 19 13:39:03 taz pppd[1830]: Using interface ppp0
Nov 19 13:39:03 taz pppd[1830]: Connect: ppp0 <--> /dev/cua1
Nov 19 13:39:03 taz pppd[1830]: set_xaccm: 00000000 00000000 00000000 60000000
Nov 19 13:39:03 taz pppd[1830]: send_config: mtu = 1500
Nov 19 13:39:03 taz pppd[1830]: send_config: asyncmap = ffffffff
Nov 19 13:39:03 taz pppd[1830]: get flags = 0
Nov 19 13:39:03 taz pppd[1830]: set flags = 0
Nov 19 13:39:03 taz pppd[1830]: recv_config: mru = 1500
Nov 19 13:39:03 taz pppd[1830]: recv_config: asyncmap = ffffffff
Nov 19 13:39:03 taz pppd[1830]: get flags = 0
Nov 19 13:39:03 taz pppd[1830]: set flags = 0
Nov 19 13:39:03 taz pppd[1830]: sent [LCP ConfReq id=0x1 <magic 0xffaf> <pcomp>
<accomp>]
Nov 19 13:39:03 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:03 taz kernel: 7E ~
Nov 19 13:39:03 taz kernel: ppp: receive buffer, count = 9
Nov 19 13:39:03 taz kernel: FF 7D 23 C0 21 7D 22 7D .}#.!}"}
Nov 19 13:39:03 taz kernel: 21 !
Nov 19 13:39:03 taz kernel: ppp: receive buffer, count = 26
Nov 19 13:39:03 taz kernel: 7D 20 7D 2E 7D 25 7D 26 } }.}%}&
Nov 19 13:39:03 taz kernel: 7D 20 7D 20 FF AF 7D 27 } } ..}'
Nov 19 13:39:03 taz kernel: 7D 22 7D 28 7D 22 A9 7D }"}(}".}
Nov 19 13:39:03 taz kernel: 2E 7E .~
Nov 19 13:39:03 taz kernel: ppp: receive frame, count = 18
Nov 19 13:39:03 taz kernel: FF 03 C0 21 02 01 00 0E ...!....
Nov 19 13:39:03 taz kernel: 05 06 00 00 FF AF 07 02 ........
Nov 19 13:39:03 taz kernel: 08 02 ..
Nov 19 13:39:03 taz pppd[1830]: fsm_sdata(LCP): Sent code 1, id 1.
Nov 19 13:39:03 taz pppd[1830]: Timeout 804c354:8064bec in 3 seconds.
Nov 19 13:39:03 taz pppd[1830]: LCP: sending Configure-Request, id 1
Nov 19 13:39:03 taz pppd[1830]: rcvd [LCP ConfAck id=0x1 <magic 0xffaf> <pcomp>
<accomp>]
Nov 19 13:39:03 taz pppd[1830]: fsm_rconfack(LCP): Rcvd id 1.
Nov 19 13:39:05 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:05 taz kernel: 7E ~
Nov 19 13:39:05 taz kernel: ppp: receive buffer, count = 50
Nov 19 13:39:05 taz kernel: FF 7D 23 C0 21 7D 21 7D .}#.!}!}
Nov 19 13:39:05 taz kernel: 22 7D 20 7D 38 7D 21 7D "} }8}!}
Nov 19 13:39:05 taz kernel: 24 7D 25 EA 7D 22 7D 26 $}%.}"}&
Nov 19 13:39:05 taz kernel: 7D 20 7D 20 7D 20 7D 20 } } } }
Nov 19 13:39:05 taz kernel: 7D 25 7D 26 F6 BC 99 67 }%}&...g
Nov 19 13:39:05 taz kernel: 7D 27 7D 22 7D 28 7D 22 }'}"}(}"
Nov 19 13:39:05 taz kernel: BC A8 ..
Nov 19 13:39:05 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:05 taz kernel: 7E ~
Nov 19 13:39:05 taz kernel: ppp: receive frame, count = 28
Nov 19 13:39:05 taz kernel: FF 03 C0 21 01 02 00 18 ...!....
Nov 19 13:39:05 taz kernel: 01 04 05 EA 02 06 00 00 ........
Nov 19 13:39:05 taz kernel: 00 00 05 06 F6 BC 99 67 .......g
Nov 19 13:39:05 taz kernel: 07 02 08 02 ....
Nov 19 13:39:05 taz kernel: ppp: write frame, count = 28
Nov 19 13:39:05 taz kernel: FF 03 C0 21 02 02 00 18 ...!....
Nov 19 13:39:05 taz pppd[1830]: rcvd [LCP ConfReq id=0x2 <mru 1514> <asyncmap 0x0>
<magic 0xf6bc9967> <pcomp> <accomp>]
Nov 19 13:39:05 taz pppd[1830]: fsm_rconfreq(LCP): Rcvd id 2.
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd MRU
Nov 19 13:39:05 taz pppd[1830]: (1514)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd ASYNCMAP
Nov 19 13:39:05 taz pppd[1830]: (0)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd MAGICNUMBER
Nov 19 13:39:05 taz pppd[1830]: (f6bc9967)
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd PCOMPRESSION
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: rcvd ACCOMPRESSION
Nov 19 13:39:05 taz pppd[1830]: (ACK)
Nov 19 13:39:05 taz pppd[1830]: lcp_reqci: returning CONFACK.
Nov 19 13:39:05 taz pppd[1830]: sent [LCP ConfAck id=0x2 <mru 1514> <asyncmap 0x0>
<magic 0xf6bc9967> <pcomp> <accomp>]
Nov 19 13:39:05 taz kernel: 01 04 05 EA 02 06 00 00 ........
Nov 19 13:39:05 taz kernel: 00 00 05 06 F6 BC 99 67 .......g
Nov 19 13:39:05 taz kernel: 07 02 08 02 ....
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set xmit asyncmap 0
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0003
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set mru to 5dc
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0003
Nov 19 13:39:05 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:05 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:05 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:05 taz kernel: ppp_tty_ioctl: set flags to f0f0043
Nov 19 13:39:05 taz kernel: ppp: receive buffer, count = 22
Nov 19 13:39:05 taz kernel: 7E 80 21 01 03 00 10 02 ~.!.....
Nov 19 13:39:05 taz kernel: 06 00 2D 0F 00 03 06 C0 ..-.....
Nov 19 13:39:05 taz kernel: 30 20 04 31 F8 7E 0 .1.~
Nov 19 13:39:05 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:05 taz pppd[1830]: fsm_sdata(LCP): Sent code 2, id 2.
Nov 19 13:39:05 taz pppd[1830]: Untimeout 804c354:8064bec.
Nov 19 13:39:05 taz pppd[1830]: send_config: mtu = 1514
Nov 19 13:39:05 taz pppd[1830]: send_config: asyncmap = 0
Nov 19 13:39:05 taz pppd[1830]: get flags = f000000
Nov 19 13:39:05 taz pppd[1830]: set flags = f000003
Nov 19 13:39:05 taz pppd[1830]: recv_config: mru = 1500
Nov 19 13:39:05 taz pppd[1830]: recv_config: asyncmap = ffffffff
Nov 19 13:39:05 taz pppd[1830]: get flags = f000003
Nov 19 13:39:05 taz pppd[1830]: set flags = f000003
Nov 19 13:39:05 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:05 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:05 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:05 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:05 taz pppd[1830]: get flags = f000003
Nov 19 13:39:05 taz pppd[1830]: set flags = f000043
Nov 19 13:39:05 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:05 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:05 taz kernel: 31 F8 1.
Nov 19 13:39:08 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:08 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:08 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:08 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:08 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:08 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:08 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:08 taz kernel: ppp: receive buffer, count = 17
Nov 19 13:39:08 taz kernel: 7E 80 21 01 04 00 10 02 ~.!.....
Nov 19 13:39:08 taz kernel: 06 00 2D 0F 00 03 06 C0 ..-.....
Nov 19 13:39:08 taz kernel: 30 0
Nov 19 13:39:08 taz kernel: ppp: receive buffer, count = 21
Nov 19 13:39:08 taz kernel: 20 04 F6 08 7E 7E 80 21 ...~~.!
Nov 19 13:39:08 taz kernel: 03 01 00 0A 03 06 C0 30 .......0
Nov 19 13:39:08 taz kernel: 20 91 67 85 7E .g.~
Nov 19 13:39:08 taz kernel: ppp: frame with bad fcs, length = 9
Nov 19 13:39:08 taz kernel: ppp: bad frame, count = 9
Nov 19 13:39:08 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:08 taz kernel: F6 .
Nov 19 13:39:08 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:08 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:08 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:08 taz kernel: 67 85 g.
Nov 19 13:39:11 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:11 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:11 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:11 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:11 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:11 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:11 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:11 taz kernel: ppp: receive buffer, count = 22
Nov 19 13:39:11 taz kernel: 7E 80 21 01 05 00 10 02 ~.!.....
Nov 19 13:39:11 taz kernel: 06 00 2D 0F 00 03 06 C0 ..-.....
Nov 19 13:39:11 taz kernel: 30 20 04 DC 40 7E 0 ..@~
Nov 19 13:39:11 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:11 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:11 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:11 taz kernel: DC 40 .@
Nov 19 13:39:11 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:11 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:11 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:11 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:11 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:11 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:11 taz kernel: 67 85 g.
Nov 19 13:39:14 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:14 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:14 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:14 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:14 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:14 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:14 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:14 taz kernel: ppp: receive buffer, count = 19
Nov 19 13:39:14 taz kernel: 7E 80 21 01 06 00 10 02 ~.!.....
Nov 19 13:39:14 taz kernel: 06 00 2D 0F 00 03 06 C0 ..-.....
Nov 19 13:39:14 taz kernel: 30 20 04 0 .
Nov 19 13:39:14 taz kernel: ppp: receive buffer, count = 3
Nov 19 13:39:14 taz kernel: A2 98 7E ..~
Nov 19 13:39:14 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:14 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:14 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:14 taz kernel: A2 98 ..
Nov 19 13:39:14 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:14 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:14 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:14 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:14 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:14 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:14 taz kernel: 67 85 g.
Nov 19 13:39:17 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:17 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:17 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:17 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:17 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:17 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:17 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:17 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:17 taz kernel: 7E ~
Nov 19 13:39:17 taz kernel: ppp: receive buffer, count = 19
Nov 19 13:39:17 taz kernel: 80 21 01 07 00 10 02 06 .!......
Nov 19 13:39:17 taz kernel: 00 2D 0F 00 03 06 C0 30 .-.....0
Nov 19 13:39:17 taz kernel: 20 04 88 ..
Nov 19 13:39:17 taz kernel: ppp: receive buffer, count = 18
Nov 19 13:39:17 taz kernel: D0 7E 7E 80 21 03 01 00 .~~.!...
Nov 19 13:39:17 taz kernel: 0A 03 06 C0 30 20 91 67 ....0 .g
Nov 19 13:39:17 taz kernel: 85 7E .~
Nov 19 13:39:17 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:17 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:17 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:17 taz kernel: 88 D0 ..
Nov 19 13:39:17 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:17 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:17 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:17 taz kernel: 67 85 g.
Nov 19 13:39:20 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:20 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:20 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:20 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:20 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:20 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:20 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:20 taz kernel: ppp: receive buffer, count = 4
Nov 19 13:39:20 taz kernel: 7E 80 21 01 ~.!.
Nov 19 13:39:20 taz kernel: ppp: receive buffer, count = 18
Nov 19 13:39:20 taz kernel: 08 00 10 02 06 00 2D 0F ......-.
Nov 19 13:39:20 taz kernel: 00 03 06 C0 30 20 04 3D ....0 .=
Nov 19 13:39:20 taz kernel: 71 7E q~
Nov 19 13:39:20 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:20 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:20 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:20 taz kernel: 3D 71 =q
Nov 19 13:39:20 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:20 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:20 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:20 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:20 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:20 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:20 taz kernel: 67 85 g.
Nov 19 13:39:23 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:23 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:23 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:23 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:23 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:23 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:23 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:23 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:23 taz kernel: 7E ~
Nov 19 13:39:23 taz kernel: ppp: receive buffer, count = 21
Nov 19 13:39:23 taz kernel: 80 21 01 09 00 10 02 06 .!......
Nov 19 13:39:23 taz kernel: 00 2D 0F 00 03 06 C0 30 .-.....0
Nov 19 13:39:23 taz kernel: 20 04 17 39 7E ..9~
Nov 19 13:39:23 taz kernel: ppp: frame with bad fcs, length = 9
Nov 19 13:39:23 taz kernel: ppp: bad frame, count = 9
Nov 19 13:39:23 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:23 taz kernel: 39 9
Nov 19 13:39:23 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:23 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:23 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:23 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:23 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:23 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:23 taz kernel: 67 85 g.
Nov 19 13:39:26 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:26 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:26 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:26 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:26 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:26 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:26 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:26 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:26 taz kernel: 7E ~
Nov 19 13:39:26 taz kernel: ppp: receive buffer, count = 21
Nov 19 13:39:26 taz kernel: 80 21 01 0A 00 10 02 06 .!......
Nov 19 13:39:26 taz kernel: 00 2D 0F 00 03 06 C0 30 .-.....0
Nov 19 13:39:26 taz kernel: 20 04 69 E1 7E .i.~
Nov 19 13:39:26 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:26 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:26 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:26 taz kernel: 69 E1 i.
Nov 19 13:39:26 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:26 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:26 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:26 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:26 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:26 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:26 taz kernel: 67 85 g.
Nov 19 13:39:29 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:29 taz kernel: 7E ~
Nov 19 13:39:29 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:29 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:29 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:29 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:29 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:29 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:29 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:29 taz kernel: ppp: receive buffer, count = 21
Nov 19 13:39:29 taz kernel: 80 21 01 0B 00 10 02 06 .!......
Nov 19 13:39:29 taz kernel: 00 2D 0F 00 03 06 C0 30 .-.....0
Nov 19 13:39:29 taz kernel: 20 04 43 A9 7E .C.~
Nov 19 13:39:29 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:29 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:29 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:29 taz kernel: 43 A9 C.
Nov 19 13:39:29 taz kernel: ppp: receive buffer, count = 16
Nov 19 13:39:29 taz kernel: 7E 80 21 03 01 00 0A 03 ~.!.....
Nov 19 13:39:29 taz kernel: 06 C0 30 20 91 67 85 7E ..0 .g.~
Nov 19 13:39:29 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:29 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:29 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:29 taz kernel: 67 85 g.
Nov 19 13:39:32 taz kernel: ppp: receive buffer, count = 11
Nov 19 13:39:32 taz kernel: 7E 80 21 01 0C 00 10 02 ~.!.....
Nov 19 13:39:32 taz kernel: 06 00 2D ..-
Nov 19 13:39:32 taz kernel: ppp: write frame, count = 14
Nov 19 13:39:32 taz kernel: FF 03 80 21 01 01 00 0A ...!....
Nov 19 13:39:32 taz kernel: 03 06 00 00 00 00 ......
Nov 19 13:39:32 taz pppd[1830]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
Nov 19 13:39:32 taz pppd[1830]: fsm_sdata(IPCP): Sent code 1, id 1.
Nov 19 13:39:32 taz pppd[1830]: Timeout 804c354:8064d24 in 3 seconds.
Nov 19 13:39:32 taz pppd[1830]: IPCP: sending Configure-Request, id 1
Nov 19 13:39:32 taz kernel: ppp: receive buffer, count = 10
Nov 19 13:39:32 taz kernel: 0F 00 03 06 C0 30 20 04 .....0 .
Nov 19 13:39:32 taz kernel: 84 59 .Y
Nov 19 13:39:32 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:32 taz kernel: 7E ~
Nov 19 13:39:32 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:32 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:32 taz kernel: FF 03 80 21 2D C0 30 20 ...!-.0
Nov 19 13:39:32 taz kernel: 84 59 .Y
Nov 19 13:39:32 taz kernel: ppp: receive buffer, count = 1
Nov 19 13:39:32 taz kernel: 7E ~
Nov 19 13:39:32 taz kernel: ppp: receive buffer, count = 15
Nov 19 13:39:32 taz kernel: 80 21 03 01 00 0A 03 06 .!......
Nov 19 13:39:32 taz kernel: C0 30 20 91 67 85 7E .0 .g.~
Nov 19 13:39:32 taz kernel: ppp: frame with bad fcs, length = 10
Nov 19 13:39:32 taz kernel: ppp: bad frame, count = 10
Nov 19 13:39:32 taz kernel: FF 03 80 21 C0 30 20 91 ...!.0 .
Nov 19 13:39:32 taz kernel: 67 85 g.
Nov 19 13:39:35 taz kernel: ppp0: ccp closed
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set flags to f0f0003
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set xmit asyncmap ffffffff
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set flags to f0f0000
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set mru to 5dc
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Nov 19 13:39:35 taz kernel: ppp_tty_ioctl: set flags to f0f0000
Nov 19 13:39:35 taz kernel: ppp: write frame, count = 36
Nov 19 13:39:35 taz kernel: FF 03 C0 21 05 02 00 20 ...!...
Nov 19 13:39:35 taz kernel: 4E 6F 20 6E 65 74 77 6F No netwo
Nov 19 13:39:35 taz kernel: 72 6B 20 70 72 6F 74 6F rk proto
Nov 19 13:39:35 taz kernel: 63 6F 6C 73 20 72 75 6E cols run
Nov 19 13:39:35 taz kernel: 6E 69 6E 67 ning
Nov 19 13:39:35 taz pppd[1830]: IPCP: timeout sending Config-Requests
Nov 19 13:39:35 taz pppd[1830]: get flags = f000043
Nov 19 13:39:35 taz pppd[1830]: set flags = f000003
Nov 19 13:39:35 taz pppd[1830]: send_config: mtu = 1500
Nov 19 13:39:35 taz pppd[1830]: send_config: asyncmap = ffffffff
Nov 19 13:39:35 taz pppd[1830]: get flags = f000003
Nov 19 13:39:35 taz pppd[1830]: set flags = f000000
Nov 19 13:39:35 taz pppd[1830]: recv_config: mru = 1500
Nov 19 13:39:35 taz pppd[1830]: recv_config: asyncmap = ffffffff
Nov 19 13:39:35 taz pppd[1830]: get flags = f000000
Nov 19 13:39:35 taz pppd[1830]: set flags = f000000
Nov 19 13:39:35 taz pppd[1830]: sent [LCP TermReq id=0x2 "No network protocols
running"]
Nov 19 13:39:35 taz pppd[1830]: fsm_sdata(LCP): Sent code 5, id 2.
Nov 19 13:39:35 taz pppd[1830]: Timeout 804c354:8064bec in 3 seconds.
Nov 19 13:39:36 taz kernel: ppp: receive buffer, count = 14
Nov 19 13:39:36 taz kernel: 0D 0A 4E 4F 20 43 41 52 ..NO CAR
Nov 19 13:39:36 taz kernel: 52 49 45 52 0D 0A RIER..
Nov 19 13:39:36 taz kernel: ppp: channel ppp0 closing.
Nov 19 13:39:36 taz kernel: ppp0 released
Nov 19 13:39:36 taz kernel: ppp0: ccp closed
Nov 19 13:39:36 taz pppd[1830]: Modem hangup
Nov 19 13:39:36 taz pppd[1830]: Untimeout 804c354:8064bec.
Nov 19 13:39:36 taz pppd[1830]: Connection terminated.
Nov 19 13:39:37 taz pppd[1830]: Exit.
