The situation: I used to be able to connect to worldnet just fine via pppd
under Linux 1.2.8. After not using Linux for two months, while I replaced
a hard drive and then upgraded my kernel to 2.0.0, I can now no longer talk
to anything on the net when dialing in under Linux. I backed off to 1.2.13
and then 1.2.8 just to make sure it wasn't something broken in a newer
version, but get the EXACT same results. The data which will follow, and
my current attempts are made with kernel 1.2.8 on a P120 w/80Mb RAM, and an
external USR 33.6 Sportster.
After invoking the ppp-on script, my modem dials out, connects, configures
the ppp0 interface, and sets the default route. Any attempt to utilize the
connection will cause it to drop. Ping attempts return a "network
unreachable" even when attempting to ping the remote directly by IP#.
Whether I do anything or not, the connection will drop after a about
fifteen seconds, immediately if I've tried to do anything with the ppp0
interface.
Information follows: (LONG!)
****Output to /var/adm/debug of a full session (captured with tail -f)
Oct 23 20:17:39 darkstar pppd[140]: send_config: mtu = 1500
Oct 23 20:17:39 darkstar pppd[140]: send_config: asyncmap = ffffffff
Oct 23 20:17:39 darkstar pppd[140]: send_config: flags = f000004
Oct 23 20:17:39 darkstar pppd[140]: recv_config: mru = 1500
Oct 23 20:17:39 darkstar pppd[140]: recv_config: asyncmap = 0
Oct 23 20:17:39 darkstar pppd[140]: recv_config: flags = f000014
Oct 23 20:17:39 darkstar pppd[140]: Timeout 2194:10920 in 3 seconds.
Oct 23 20:17:39 darkstar pppd[140]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:17:39 darkstar pppd[140]: sent [LCP TermAck id=0x27]
Oct 23 20:17:42 darkstar pppd[140]: Alarm
Oct 23 20:18:41 darkstar pppd[147]: set_xaccm: 00000000 00000000 00000000
60000000
Oct 23 20:18:41 darkstar pppd[147]: send_config: mtu = 1500
Oct 23 20:18:41 darkstar pppd[147]: send_config: asyncmap = ffffffff
Oct 23 20:18:41 darkstar pppd[147]: send_config: flags = 0
Oct 23 20:18:41 darkstar pppd[147]: recv_config: mru = 1500
Oct 23 20:18:41 darkstar pppd[147]: recv_config: asyncmap = 0
Oct 23 20:18:41 darkstar pppd[147]: recv_config: flags = 10
Oct 23 20:18:41 darkstar pppd[147]: sent [LCP ConfReq id=0x1 <mru 1500>
<asyncmap 0x0> <magic 0x326955f7> <pcomp> <accomp>]
Oct 23 20:18:41 darkstar pppd[147]: Timeout 2194:10920 in 3 seconds.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:18:41 darkstar pppd[147]: IO signal received
Oct 23 20:18:41 darkstar pppd[147]: rcvd [LCP ConfReq id=0x20 <asyncmap
0x0> <pcomp> <accomp> <auth chap 05>] 03 3c
Oct 23 20:18:41 darkstar pppd[147]: sent [LCP ConfRej id=0x20 <auth chap
05>]
Oct 23 20:18:41 darkstar pppd[147]: IO signal received
Oct 23 20:18:41 darkstar pppd[147]: rcvd [LCP ConfRej id=0x1 <mru 1500>
<magic 0x326955f7>] 65 66
Oct 23 20:18:41 darkstar pppd[147]: Untimeout 2194:10920.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 0 seconds in
untimeout.
Oct 23 20:18:41 darkstar pppd[147]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>
<pcomp> <accomp>]
Oct 23 20:18:41 darkstar pppd[147]: Timeout 2194:10920 in 3 seconds.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:18:41 darkstar pppd[147]: IO signal received
Oct 23 20:18:41 darkstar pppd[147]: rcvd [LCP ConfReq id=0x22 <asyncmap
0x0> <pcomp> <accomp>] 27 8f
Oct 23 20:18:41 darkstar pppd[147]: sent [LCP ConfAck id=0x22 <asyncmap
0x0> <pcomp> <accomp>]
Oct 23 20:18:41 darkstar pppd[147]: IO signal received
Oct 23 20:18:41 darkstar pppd[147]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>
<pcomp> <accomp>] b9 b9
Oct 23 20:18:41 darkstar pppd[147]: Untimeout 2194:10920.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 0 seconds in
untimeout.
Oct 23 20:18:41 darkstar pppd[147]: send_config: mtu = 1500
Oct 23 20:18:41 darkstar pppd[147]: send_config: asyncmap = 0
Oct 23 20:18:41 darkstar pppd[147]: send_config: flags = f000013
Oct 23 20:18:41 darkstar pppd[147]: recv_config: mru = 1500
Oct 23 20:18:41 darkstar pppd[147]: recv_config: asyncmap = 0
Oct 23 20:18:41 darkstar pppd[147]: recv_config: flags = f000003
Oct 23 20:18:41 darkstar pppd[147]: sent [IPCP ConfReq id=0x1 <addr
0.0.0.0> <compress VJ 0f 01>]
Oct 23 20:18:41 darkstar pppd[147]: Timeout 2194:114f0 in 3 seconds.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:18:41 darkstar pppd[147]: IO signal received
Oct 23 20:18:41 darkstar pppd[147]: rcvd [IPCP ConfNak id=0x1 <addr
207.147.0.91>] 48 9d
Oct 23 20:18:41 darkstar pppd[147]: Untimeout 2194:114f0.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 0 seconds in
untimeout.
Oct 23 20:18:41 darkstar pppd[147]: sent [IPCP ConfReq id=0x2 <addr
207.147.0.91> <compress VJ 0f 01>]
Oct 23 20:18:41 darkstar pppd[147]: Timeout 2194:114f0 in 3 seconds.
Oct 23 20:18:41 darkstar pppd[147]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:18:42 darkstar pppd[147]: IO signal received
Oct 23 20:18:42 darkstar pppd[147]: rcvd [IPCP ConfAck id=0x2 <addr
207.147.0.91> <compress VJ 0f 01>] 20 e1
Oct 23 20:18:42 darkstar pppd[147]: IO signal received
Oct 23 20:18:42 darkstar pppd[147]: rcvd [IPCP ConfReq id=0x26 <addr
207.147.0.1> <compress VJ 0f 01>] f9 85
Oct 23 20:18:42 darkstar pppd[147]: sent [IPCP ConfAck id=0x26 <addr
207.147.0.1> <compress VJ 0f 01>]
Oct 23 20:18:42 darkstar pppd[147]: Untimeout 2194:114f0.
Oct 23 20:18:42 darkstar pppd[147]: Setting itimer for 0 seconds in
untimeout.
Oct 23 20:18:42 darkstar pppd[147]: Script /etc/ppp/ip-up started; pid =
149
Oct 23 20:18:50 darkstar pppd[147]: IO signal received
Oct 23 20:18:50 darkstar pppd[147]: rcvd [LCP TermReq id=0x27] df 12
Oct 23 20:18:50 darkstar pppd[147]: Script /etc/ppp/ip-down started; pid =
152
Oct 23 20:18:50 darkstar pppd[147]: send_config: mtu = 1500
Oct 23 20:18:50 darkstar pppd[147]: send_config: asyncmap = ffffffff
Oct 23 20:18:50 darkstar pppd[147]: send_config: flags = f000004
Oct 23 20:18:50 darkstar pppd[147]: recv_config: mru = 1500
Oct 23 20:18:50 darkstar pppd[147]: recv_config: asyncmap = 0
Oct 23 20:18:50 darkstar pppd[147]: recv_config: flags = f000014
Oct 23 20:18:50 darkstar pppd[147]: Timeout 2194:10920 in 3 seconds.
Oct 23 20:18:50 darkstar pppd[147]: Setting itimer for 3 seconds in
timeout.
Oct 23 20:18:50 darkstar pppd[147]: sent [LCP TermAck id=0x27]
Oct 23 20:18:53 darkstar pppd[147]: Alarm
****End content of /var/adm/debug
****Content of file /etc/ppp/options
name "<actual id removed>"
/dev/cua1
modem
lock
debug
noipdefault
asyncmap 0
defaultroute
crtscts
****End content of /etc/ppp/options
****Content of file /etc/ppp/chap-secrets
<actual id removed> * <actual pw removed> *
****End content of /etc/ppp/chap-secrets
****Content of file /usr/sbin/ppp-on
/usr/lib/ppp/pppd name "<actual id removed>" -d connect \
'/usr/lib/ppp/chat -t 45 -v ABORT BUSY "" ATDT4440216 CONNECT ""' \
/dev/cua1 38400 noipdefault debug modem crtscts &
****End content of /usr/sbin/ppp-on
****Dump of dmesg/kernel information, for reference
ide1: hdd: cdrom
Console: colour EGA+ 80x25, 1 virtual console (max 63)
bios32_init : BIOS32 Service Directory structure at 0x000fd870
bios32_init : BIOS32 Service Directory entry at 0xfd880
pcibios_init : PCI BIOS revision 2.10 entry at 0xfd8a1
Probing PCI hardware.
Calibrating delay loop.. ok - 36.08 BogoMips
Serial driver version 4.11 with no serial options enabled
tty00 at 0x03f8 (irq = 4) is a 16550A
tty01 at 0x02f8 (irq = 3) is a 16550A
lp1 at 0x0378, using polling driver
snd2 <SoundBlaster 2.1> at 0x220 irq 7 drq 1
snd1 <Yamaha 2-OP FM> at 0x388 irq 0 drq 0
hda: NEC Corporation DSE2010A, 1919MB w/66KB Cache, LBA, CHS=975/64/63,
MaxMult=16
hdb: Conner Peripherals 1620MB - CFS1621A, 1548MB w/0KB Cache, LBA,
CHS=786/64/63, MaxMult=16
hdd: GCD-R540, ATAPI, CDROM drive
ide1: secondary interface on irq 15
ide0: primary interface on irq 14
Floppy drive(s): fd0 is 1.44M, fd1 is 1.2M
FDC 0 is a post-1991 82077
Memory: 64036k/65536k available (672k kernel code, 384k reserved, 444k
data)
This processor honours the WP bit even when in supervisor mode. Good.
Swansea University Computer Society NET3.019
Swansea University Computer Society TCP/IP for NET3.019
IP Protocols: ICMP, UDP, TCP
PPP: version 0.2.7 (4 channels) NEW_TTY_DRIVERS OPTIMIZE_FLAGS
TCP compression code copyright 1989 Regents of the University of California
PPP line discipline registered.
SLIP: version 0.8.3-NET3.019-NEWTTY (16 channels) (6 bit encapsulation
enabled)
CSLIP: code copyright 1989 Regents of the University of California
Checking 386/387 coupling... Ok, fpu using exception 16 error reporting.
Checking 'hlt' instruction... Ok.
Linux version 1.2.8 (root@darkstar) (gcc version 2.6.3) #1 Sat Oct 19
14:04:55 MDT 1996
Partition check:
hda: disabled multiple mode
hda: hda1 hda2 < hda5 >
hdb: disabled multiple mode
hdb: hdb1 hdb2
hdc: bad access: block=0, count=2
end_request: I/O error, dev 1600, sector 0
unable to read partition table of device 1600
VFS: Mounted root (ext2 filesystem) readonly.
hdd: media changed
hdd: status error: status=0x59
hdd: status error: error=0x60
ide1: do_ide_reset: timed-out, status=0xff
hdd: DRDY error: status=0xfe
ide1: do_ide_reset: timed-out, status=0xff
hdd: DRDY error: status=0xfe
end_request: I/O error, dev 1640, sector 0
hdd: unexpected_intr: status=0xff
VFS: Disk change detected on device 22/64
hdd: media changed
hdd: status error: status=0x59
hdd: status error: error=0x60
ide1: do_ide_reset: timed-out, status=0xff
hdd: DRDY error: status=0xff
ide1: do_ide_reset: timed-out, status=0xff
hdd: DRDY error: status=0xfe
end_request: I/O error, dev 1640, sector 64
hdd: unexpected_intr: status=0x7a
isofs_read_super: bread failed, dev 0x1640 iso_blknum 16
Unable to identify CD-ROM format.
Unable to identify CD-ROM format.
****End content of dmesg/kernel information
****Contents of "script" capture of an attempted PPP session
Script started on Wed Oct 23 20:21:22 1996
...
read more »