(description of problem ppp frame with bad fcs in previous message)
Clifford Kite writes:
> |I think this has to be a bug with the current Linux PPP
> |implementation. How else could everything work under NT with the same
> |hardware, and with an older Linux on the same phone line? I'll post
>
> Hmm. The newer pppd's work fine for me and many others. Using the same
> kind of reasoning, one could ask "How could they work for everyone else but
> not work for you?"
Your logic would appear to have the advantage of being empirically
true. My logic has the disadvantage of being sleep deprived,
confused, and overly emotional. A fair fight if I ever saw one :)
I came to my conclusion by eliminating the other likely problems
because they work with the other systems: phone line, modem, hardware,
ISP. But you are right, there are other possibilities.
> |appropriate log files if anyone would like to see them. Unless
>
> There's always a chance that someone can spot a clue in the debug logs.
> At least until no-one does spot one. :)
I'll attach an excerpt of the log files to the end of this message.
> You might try the pppd option "noccp" if you haven't already done so, since
> that's one of the things you didn't address.
I just tried that for the mostly working ISP. I was unable to
connect, and got an error message regarding 'no response to PAP auth
requests'. Is this worth pursuing further?
> Have you compared pppd options you use on the box with the problem with
> those used on the laptop?
I started out with them identical, and then have played with the
settings on the new box. Both are essentially:
lock
defaultroute
noipdefault
modem
/dev/ttyS2
115200
crtscts
debug
kdebug 7
asyncmap 0
name "nate"
By essentially, I mean that I have been fiddling with the
configuration extensively, but revolving around these base settings.
> Do the files alway stick at the same place during an ftp download? Can you
> look at a hexdump of one of the troublesome files that you got by download
> with another box and see anything just before the sticking point that is
> suggestive?
Yes, it does always stick at the same point. I've looked at it, and
it appears to be null separated plain text. Nothing fancy.
> Have you tried the pppd option "asyncmap a0000," which sometimes works
> when the modem is absorbing XON/XOFF characters sent in the data? It
> doesn't seem right here, but I've seen it work in one instance where I
> swore that it couldn't help. :/
I just tried it specifically, and it did not seem to help. This would
have been covered by 'asyncmap ffffffff', wouldn't it?
> If you have an IDE hard drive, have you tried hdparm with the -u1 flag?
Problem still exists. And since the problem is reproducible and
specific to certain files, I think it probably not an IRQ problem.
> Given what you've already done, perhaps it's time to add the option
> "kdebug 2", and look for a clue in the hex dump during the period
> leading up to a hang while downloading one of the troublesome files.
> Since this produces a lot of output you should, if possible, choose a
> file that not only hangs but does it shortly after the download starts.
I've started down that route. There appear to be a couple frames that
are always broken. These frames begin normally, but then include a
few junk characters right at the end. Retransmits of a given frame
vary in length, and often appear truncated. Sometimes data is missing
from the middle of the frame. Other frames are occasionally broken.
---------------------------------------------------------------------
May 26 18:43:09 first chat[12939]: atdt 284 1008^M^M
May 26 18:43:09 first pppd[12938]: Serial connection established.
May 26 18:43:09 first chat[12939]: CONNECT
May 26 18:43:09 first chat[12939]: -- got it
May 26 18:43:09 first pppd[12938]: Using interface ppp0
May 26 18:43:09 first pppd[12938]: Connect: ppp0 <--> /dev/ttyS2
May 26 18:43:10 first pppd[12938]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0xcae16ad> <pcomp>]
May 26 18:43:10 first pppd[12938]: rcvd [LCP ConfReq id=0x1 < 00 04 00 00>
<mru 1524> <asyncmap 0xa0000>
<auth pap> <pcomp> <accomp>
< 11 04 05 f4>
< 13 09 03 00 c0 7b 82 50 7f>]
May 26 18:43:10 first pppd[12938]: sent [LCP ConfRej id=0x1 < 00 04 00 00>
<accomp> < 11 04 05 f4>
< 13 09 03 00 c0 7b 82 50 7f>]
May 26 18:43:10 first pppd[12938]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0xcae16ad> <pcomp>]
May 26 18:43:11 first pppd[12938]: rcvd [LCP ConfReq id=0x2 <mru 1524>
<asyncmap 0xa0000> <auth pap> <pcomp>]
May 26 18:43:11 first pppd[12938]: sent [LCP ConfAck id=0x2 <mru 1524>
<asyncmap 0xa0000> <auth pap> <pcomp>]
May 26 18:43:11 first pppd[12938]: sent [PAP AuthReq id=0x1
user="nate" password="*******"]
May 26 18:43:11 first pppd[12938]: rcvd [PAP AuthAck id=0x1 ""]
May 26 18:43:11 first pppd[12938]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>]
May 26 18:43:11 first pppd[12938]: sent [CCP ConfReq id=0x1 <bsd v1 15>]
May 26 18:43:11 first pppd[12938]: rcvd [IPCP ConfReq id=0x1
<compress VJ 0f 01>
<addr 63.65.239.17>]
May 26 18:43:11 first pppd[12938]: sent [IPCP ConfRej id=0x1
<compress VJ 0f 01>]
May 26 18:43:11 first pppd[12938]: rcvd [CCP ConfReq id=0x1
< 11 06 00 01 01 03>]
May 26 18:43:11 first pppd[12938]: sent [CCP ConfRej id=0x1
< 11 06 00 01 01 03>]
May 26 18:43:11 first pppd[12938]: rcvd [IPCP ConfNak id=0x1
<addr 63.83.209.51>]
May 26 18:43:11 first pppd[12938]: sent [IPCP ConfReq id=0x2
<addr 63.83.209.51>]
May 26 18:43:11 first pppd[12938]: rcvd [CCP ConfRej id=0x1 <bsd v1 15>]
May 26 18:43:11 first pppd[12938]: sent [CCP ConfReq id=0x2]
May 26 18:43:11 first pppd[12938]: rcvd [IPCP ConfReq id=0x2
<addr 63.65.239.17>]
May 26 18:43:11 first pppd[12938]: sent [IPCP ConfAck id=0x2
<addr 63.65.239.17>]
May 26 18:43:11 first pppd[12938]: rcvd [IPCP ConfAck id=0x2
<addr 63.83.209.51>]
May 26 18:43:11 first pppd[12938]: local IP address 63.83.209.51
May 26 18:43:11 first pppd[12938]: remote IP address 63.65.239.17
May 26 18:43:11 first pppd[12938]: Script /etc/ppp/ip-up started (pid 12941)
May 26 18:43:11 first pppd[12938]: rcvd [CCP ConfRej id=0x2]
May 26 18:43:11 first pppd[12938]: Script /etc/ppp/ip-up finished (pid 12941),
status = 0x0
[first few frames deleted]
*** all seems normal to here ***
May 26 18:43:30 first kernel: ppp: frame with bad fcs, length = 1448
May 26 18:43:30 first kernel: ppp: bad frame, count = 1448
May 26 18:43:30 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:30 first kernel: 25 38 00 00 34 06 43 3A %8..4.C:
May 26 18:43:30 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:30 first kernel: 00 50 08 44 8A 0C C4 51 .P.D...Q
May 26 18:43:30 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:30 first kernel: 70 51 00 00 70 69 63 73 pQ..pics
May 26 18:43:30 first kernel: 2F 62 74 6E 5F 61 2E 67 /btn_a.g
May 26 18:43:30 first kernel: 69 66 00 2F 75 73 72 2F if./usr/
May 26 18:43:30 first kernel: 6C 69 62 2F 52 65 61 6C lib/Real
May 26 18:43:30 first kernel: 50 6C 61 79 65 72 37 2F Player7/
May 26 18:43:30 first kernel: 48 65 6C 70 2F 72 65 61 Help/rea
May 26 18:43:30 first kernel: 6C 70 6C 61 79 2F 70 69 lplay/pi
[middle of frame deleted]
May 26 18:43:30 first kernel: 2F 52 65 61 6C 50 6C 61 /RealPla
May 26 18:43:30 first kernel: 79 65 72 37 2F 48 65 6C yer7/Hel
May 26 18:43:30 first kernel: 70 2F 72 65 61 6C 70 6C p/realpl
May 26 18:43:30 first kernel: 61 79 2F 70 69 63 73 2F ay/pics/
May 26 18:43:30 first kernel: 63 6C 6F 73 65 64 32 2E closed2.
May 26 18:43:30 first kernel: 67 69 66 00 2F 75 73 72 gif./usr
May 26 18:43:30 first kernel: 2F 6C 69 62 2F 52 65 61 /lib/Rea
May 26 18:43:30 first kernel: 6C 50 6C 61 79 65 72 37 lPlayer7
May 26 18:43:30 first kernel: 2F 48 65 6C 70 2F 72 65 /Help/re
May 26 18:43:30 first kernel: 61 6C 70 6C 61 79 E8 02 alplay..
*** those last two characters are wrong ***
[another good frame deleted]
May 26 18:43:32 first kernel: ppp: frame with bad fcs, length = 1448
May 26 18:43:32 first kernel: ppp: bad frame, count = 1448
May 26 18:43:32 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:32 first kernel: 32 42 00 00 34 06 36 30 2B..4.60
May 26 18:43:32 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:32 first kernel: 00 50 08 44 8A 0C CF B9 .P.D....
May 26 18:43:32 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:32 first kernel: 6F 73 00 00 70 6C 61 79 os..play
May 26 18:43:32 first kernel: 2F 70 69 63 73 2F 69 6E /pics/in
May 26 18:43:32 first kernel: 64 65 78 5F 61 2E 67 69 dex_a.gi
May 26 18:43:32 first kernel: 66 00 2F 75 73 72 2F 6C f./usr/l
May 26 18:43:32 first kernel: 69 62 2F 52 65 61 6C 50 ib/RealP
May 26 18:43:32 first kernel: 6C 61 79 65 72 37 2F 48 layer7/H
May 26 18:43:32 first kernel: 65 6C 70 2F 72 65 61 6C elp/real
May 26 18:43:32 first kernel: 70 6C 61 79 2F 70 69 63 play/pic
[middle of frame deleted]
May 26 18:43:33 first kernel: 62 75 66 66 65 72 2D 67 buffer-g
May 26 18:43:33 first kernel: 61 75 67 65 2E 67 69 66 auge.gif
May 26 18:43:33 first kernel: 00 2F 75 73 72 2F 6C 69 ./usr/li
May 26 18:43:33 first kernel: 62 2F 52 65 61 6C 50 6C b/RealPl
May 26 18:43:33 first kernel: 61 79 65 72 37 2F 48 65 ayer7/He
May 26 18:43:33 first kernel: 6C 70 2F 72 65 61 6C 70 lp/realp
May 26 18:43:33 first kernel: 6C 61 79 2F 70 69 63 73 lay/pics
May 26 18:43:33 first kernel: 2F 6C 65 64 2D 63 6F 6E /led-con
May 26 18:43:33 first kernel: 6E 65 63 74 2D 77 64 73 nect-wds
May 26 18:43:33 first kernel: 2E 67 69 66 00 2F 75 73 .gif./us
May 26 18:43:33 first kernel: 72 2F 6C 69 62 2F 52 65 r/lib/Re
May 26 18:43:33 first kernel: 61 6C 50 6C 61 79 B2 49 alPlay.I
*** again junk characters are end ***
May 26 18:43:33 first kernel: ppp: frame with bad fcs, length = 1390
May 26 18:43:33 first kernel: ppp: bad frame, count = 1390
May 26 18:43:33 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:33 first kernel: 32 43 00 00 34 06 36 2F 2C..4.6/
May 26 18:43:33 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:33 first kernel: 00 50 08 44 8A 0C D5 6D .P.D...m
May 26 18:43:33 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:33 first kernel: E4 6E 00 00 65 72 37 2F .n..er7/
May 26 18:43:33 first kernel: 48 65 6C 70 2F 72 65 61 Help/rea
May 26 18:43:33 first kernel: 6C 70 6C 61 79 2F 70 69 lplay/pi
May 26 18:43:33 first kernel: 63 73 2F 6C 65 64 2D 6B cs/led-k
May 26 18:43:33 first kernel: 62 70 73 2D 77 64 73 2E bps-wds.
May 26 18:43:33 first kernel: 67 69 66 00 2F 75 73 72 gif./usr
May 26 18:43:33 first kernel: 2F 65 61 6C 50 6C 61 79 /ealPlay
[middle of frame deleted]
May 26 18:43:33 first kernel: 79 2F 70 69 63 73 2F 70 y/pics/p
May 26 18:43:33 first kernel: 72 65 67 61 69 6E 33 2E regain3.
May 26 18:43:33 first kernel: 67 69 66 00 2F 75 73 72 gif./usr
May 26 18:43:33 first kernel: 2F 6C 69 62 2F 52 65 61 /lib/Rea
May 26 18:43:33 first kernel: 2F 48 65 6C 70 2F 72 65 /Help/re
May 26 18:43:33 first kernel: 61 6C 70 6C 61 79 2F 70 alplay/p
May 26 18:43:33 first kernel: 69 63 73 2F 70 72 65 76 ics/prev
May 26 18:43:33 first kernel: 31 2E 67 69 66 00 2F 75 1.gif./u
May 26 18:43:33 first kernel: 73 72 2F 6C 69 62 2F 52 sr/lib/R
May 26 18:43:33 first kernel: 65 61 6C 50 C2 73 ealP.s
*** ends with junk again ***
May 26 18:43:35 first kernel: ppp: frame with bad fcs, length = 1420
May 26 18:43:35 first kernel: ppp: bad frame, count = 1420
May 26 18:43:35 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:35 first kernel: 49 74 00 00 34 06 1E FE It..4...
May 26 18:43:35 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:35 first kernel: 00 50 08 44 8A 0C CF B9 .P.D....
May 26 18:43:35 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:35 first kernel: 6F 73 00 00 70 6C 61 79 os..play
May 26 18:43:35 first kernel: 2F 70 69 63 73 2F 69 6E /pics/in
May 26 18:43:35 first kernel: 64 65 78 5F 61 2E 67 69 dex_a.gi
May 26 18:43:35 first kernel: 66 00 2F 75 73 72 2F 6C f./usr/l
May 26 18:43:35 first kernel: 69 62 2F 52 65 61 6C 50 ib/RealP
May 26 18:43:35 first kernel: 6C 61 79 65 72 37 2F 48 layer7/H
May 26 18:43:35 first kernel: 65 6C 70 2F 72 65 61 6C elp/real
May 26 18:43:35 first kernel: 70 6C 61 79 2F 70 69 63 play/pic
[middle of frame deleted]
May 26 18:43:35 first kernel: 2F 48 65 6C 70 2F 72 65 /Help/re
May 26 18:43:35 first kernel: 61 6C 70 6C 61 79 2F 70 alplay/p
May 26 18:43:35 first kernel: 69 63 6C 69 62 2F 52 65 iclib/Re
May 26 18:43:35 first kernel: 61 6C 50 6C 61 79 65 72 alPlayer
May 26 18:43:35 first kernel: 37 2F 48 65 6C 70 2F 72 7/Help/r
May 26 18:43:35 first kernel: 65 61 6C 70 6C 61 79 2F ealplay/
May 26 18:43:35 first kernel: 70 69 63 73 2F 6C 65 64 pics/led
May 26 18:43:35 first kernel: 2D 63 6F 6E 6E 65 63 74 -connect
May 26 18:43:35 first kernel: 2D 77 64 73 2E 67 69 66 -wds.gif
May 26 18:43:35 first kernel: 00 2F 75 73 72 2F 6C 69 ./usr/li
May 26 18:43:35 first kernel: 62 2F 52 65 61 6C 50 6C b/RealPl
May 26 18:43:35 first kernel: 61 79 E6 2F ay./
*** the usual junk ***
May 26 18:43:42 first kernel: ppp: frame with bad fcs, length = 1420
May 26 18:43:42 first kernel: ppp: bad frame, count = 1420
May 26 18:43:42 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:42 first kernel: 79 01 00 00 34 06 EF 70 y...4..p
May 26 18:43:42 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:42 first kernel: 00 50 08 44 8A 0C CF B9 .P.D....
May 26 18:43:42 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:42 first kernel: 6F 73 00 00 70 6C 61 79 os..play
May 26 18:43:42 first kernel: 2F 70 69 63 73 2F 69 6E /pics/in
May 26 18:43:42 first kernel: 64 65 78 5F 61 2E 67 69 dex_a.gi
May 26 18:43:42 first kernel: 66 00 2F 75 73 72 2F 6C f./usr/l
May 26 18:43:42 first kernel: 69 62 2F 52 65 61 6C 50 ib/RealP
May 26 18:43:42 first kernel: 6C 61 79 65 72 37 2F 48 layer7/H
May 26 18:43:42 first kernel: 65 6C 70 2F 72 65 61 6C elp/real
May 26 18:43:42 first kernel: 70 6C 61 79 2F 70 69 63 play/pic
[middle of frame deleted]
May 26 18:43:42 first kernel: 72 2F 6C 69 62 2F 52 65 r/lib/Re
May 26 18:43:42 first kernel: 61 6C 50 6C 61 79 65 72 alPlayer
May 26 18:43:42 first kernel: 37 2F 48 65 6C 70 2F 72 7/Help/r
May 26 18:43:42 first kernel: 65 61 6C 70 6C 61 79 2F ealplay/
May 26 18:43:42 first kernel: 70 69 63 73 2F 6C 65 64 pics/led
May 26 18:43:42 first kernel: 2D 63 6F 6E 6E 65 63 74 -connect
May 26 18:43:42 first kernel: 2D 77 64 73 2E 67 69 66 -wds.gif
May 26 18:43:42 first kernel: 00 2F 75 73 72 2F 6C 69 ./usr/li
May 26 18:43:42 first kernel: 62 2F 52 65 61 6C 50 6C b/RealPl
May 26 18:43:42 first kernel: 61 79 3B 31 ay;1
*** again two character of junk ***
May 26 18:43:56 first kernel: ppp: frame with bad fcs, length = 1390
May 26 18:43:56 first kernel: ppp: bad frame, count = 1390
May 26 18:43:56 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:43:56 first kernel: CC 34 00 00 34 06 9C 3D .4..4..=
May 26 18:43:56 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:43:56 first kernel: 00 50 08 44 8A 0C CF B9 .P.D....
May 26 18:43:56 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:43:56 first kernel: 6F 73 00 00 70 6C 61 79 os..play
May 26 18:43:56 first kernel: 2F 70 69 63 73 2F 69 6E /pics/in
May 26 18:43:56 first kernel: 64 65 78 5F 61 2E 67 69 dex_a.gi
May 26 18:43:56 first kernel: 66 00 2F 75 73 72 2F 6C f./usr/l
May 26 18:43:56 first kernel: 69 62 2F 52 65 61 6C 50 ib/RealP
May 26 18:43:56 first kernel: 6C 61 79 65 72 37 2F 48 layer7/H
May 26 18:43:56 first kernel: 65 6C 70 2F 72 65 61 6C elp/real
May 26 18:43:56 first kernel: 70 6C 61 79 2F 70 69 63 play/pic
[middle of frame deleted]
May 26 18:43:56 first kernel: 2F 6C 69 62 2F 52 65 61 /lib/Rea
May 26 18:43:56 first kernel: 6C 50 6C 61 79 65 72 37 lPlayer7
May 26 18:43:56 first kernel: 2F 48 65 6C 70 2F 72 65 /Help/re
May 26 18:43:56 first kernel: 61 6C 70 6C 61 79 2F 70 alplay/p
May 26 18:43:56 first kernel: 69 63 73 2F 6C 65 64 2D ics/led-
May 26 18:43:56 first kernel: 62 75 2D 63 6F 6E 6E 65 bu-conne
May 26 18:43:56 first kernel: 63 74 2D 77 64 73 2E 67 ct-wds.g
May 26 18:43:56 first kernel: 69 66 00 2F 75 73 72 2F if./usr/
May 26 18:43:56 first kernel: 6C 69 62 2F 52 65 61 6C lib/Real
May 26 18:43:56 first kernel: 50 6C 61 79 34 02 Play4.
*** also two junk characters ***
May 26 18:44:24 first kernel: ppp: frame with bad fcs, length = 1390
May 26 18:44:24 first kernel: ppp: bad frame, count = 1390
May 26 18:44:24 first kernel: FF 03 00 21 45 00 05 DC ...!E...
May 26 18:44:24 first kernel: 63 E9 00 00 34 06 04 89 c...4...
May 26 18:44:24 first kernel: CD 9E 3A 85 3F 53 D1 33 ..:.?S.3
May 26 18:44:24 first kernel: 00 50 08 44 8A 0C CF B9 .P.D....
May 26 18:44:24 first kernel: 1F AF 7B 15 50 10 7F E0 ..{.P...
May 26 18:44:24 first kernel: 6F 73 00 00 70 6C 61 79 os..play
May 26 18:44:24 first kernel: 2F 70 69 63 73 2F 69 6E /pics/in
May 26 18:44:24 first kernel: 64 65 78 5F 61 2E 67 69 dex_a.gi
May 26 18:44:24 first kernel: 66 00 2F 75 73 72 2F 6C f./usr/l
May 26 18:44:24 first kernel: 69 62 2F 52 65 61 6C 50 ib/RealP
May 26 18:44:24 first kernel: 6C 61 79 65 72 37 2F 48 layer7/H
May 26 18:44:24 first kernel: 65 6C 70 2F 72 65 61 6C elp/real
May 26 18:44:24 first kernel: 70 6C 61 79 2F 70 69 63 play/pic
[middle of frame deleted]
May 26 18:44:24 first kernel: 66 66 65 72 2D 67 61 75 ffer-gau
May 26 18:44:24 first kernel: 67 65 2E 67 69 66 00 2F ge.gif./
May 26 18:44:24 first kernel: 75 73 72 2F 6C 69 62 2F usr/lib/
May 26 18:44:24 first kernel: 52 65 61 6C 50 6C 61 79 RealPlay
May 26 18:44:24 first kernel: 65 72 37 2F 48 65 6C 70 er7/Help
May 26 18:44:24 first kernel: 2F 72 65 61 6C 70 6C 61 /realpla
May 26 18:44:24 first kernel: 79 2F 70 69 63 73 2F 6C y/pics/l
May 26 18:44:24 first kernel: 65 64 2D 63 6F 6E 6E 65 ed-conne
May 26 18:44:24 first kernel: 63 74 2D 77 64 73 2E 67 ct-wds.g
May 26 18:44:24 first kernel: 69 66 00 2F 75 73 72 2F if./usr/
May 26 18:44:24 first kernel: 6C 69 62 2F 52 65 61 6C lib/Real
May 26 18:44:24 first kernel: 50 6C 61 79 AC 13 Play..
*** and again two junk characters ***
Looking close at one of the broken frames, I found that data was also
missing from the middle of one of the retransmits. In particular, the
six characters 'play/p' where dropped once. But mostly the frames
seem to be truncated, often at the same spot.
-
To unsubscribe from this list: send the line "unsubscribe linux-ppp" in
the body of a message to [EMAIL PROTECTED]
