Hello all, I've been having this problem forever! I can't seem to get my PPP connection to last more than a few hours. Currently I'm using kernel 2.4.27 (which seems to have the latest speedtch.c) on Mandrake 9.2. Here are the vital files:
---/etc/ppp/options--- lock defaultroute noauth noipdefault holdoff 4 maxfail 25 persist asyncmap 0 lcp-echo-interval 2 lcp-echo-failure 15 name xxxx user xxxx plugin /usr/lib/pppd/2.4.1/pppoatm.so 0.35 ------ ---/usr/sbin/connect--- #ADSL connection script logger -t connect Reconnection script started. killall -s SIGTERM pppd >/dev/null killall modem_run >/dev/null sleep 5 modprobe -r speedtch pppoatm uhci >/dev/null modprobe -r atm >/dev/null sleep 1 modprobe uhci >/dev/null modprobe speedtch >/dev/null sleep 1 /usr/sbin/modem_run -k -a /usr/lib/speedtouch/ZZZLP1.eni -f /usr/lib/speedtouch/ZZZLP2.eni pppd >/dev/null ------ Here's what syslog says when the connection script is run. I've enabled both USB kernel debug and speedtch.c DEBUG flag. ---start syslog--- Sep 22 08:22:37 hostname kernel: uhci.c: USB Universal Host Controller Interface driver v1.1 Sep 22 08:22:37 hostname kernel: uhci.c: USB UHCI at I/O 0xef80, IRQ 9 Sep 22 08:22:37 hostname kernel: usb.c: new USB bus registered, assigned bus number 1 Sep 22 08:22:37 hostname kernel: uhci.c: detected 2 ports Sep 22 08:22:37 hostname kernel: usb.c: kmalloc IF c3a5b380, numif 1 Sep 22 08:22:37 hostname kernel: usb.c: new device strings: Mfr=0, Product=2, SerialNumber=1 Sep 22 08:22:37 hostname kernel: usb.c: USB device number 1 default language ID 0x0 Sep 22 08:22:37 hostname kernel: Product: USB UHCI-alt Root Hub Sep 22 08:22:37 hostname kernel: SerialNumber: ef80 Sep 22 08:22:37 hostname kernel: hub.c: USB hub found Sep 22 08:22:37 hostname kernel: hub.c: 2 ports detected Sep 22 08:22:37 hostname kernel: hub.c: standalone hub Sep 22 08:22:37 hostname kernel: hub.c: ganged power switching Sep 22 08:22:37 hostname kernel: hub.c: global over-current protection Sep 22 08:22:37 hostname kernel: hub.c: Port indicators are not supported Sep 22 08:22:37 hostname kernel: hub.c: power on to power good time: 2ms Sep 22 08:22:37 hostname kernel: hub.c: hub controller current requirement: 0mA Sep 22 08:22:37 hostname kernel: hub.c: port removable status: RR Sep 22 08:22:37 hostname kernel: hub.c: local power source is good Sep 22 08:22:37 hostname kernel: hub.c: no over-current condition exists Sep 22 08:22:37 hostname kernel: hub.c: enabling power on all ports Sep 22 08:22:37 hostname kernel: usb.c: hub driver claimed interface c3a5b380 Sep 22 08:22:37 hostname kernel: usb.c: kusbd: /sbin/hotplug add 1 Sep 22 08:22:37 hostname /sbin/hotplug: arguments (usb) env (DEVFS=/proc/bus/usb OLDPWD=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin ACTION=add PWD=/etc/hotplug SHLVL=1 HOME=/ DEVICE=/proc/bus/usb/001/001 PRODUCT=0/0/0 TYPE=9/0/0 DEBUG=kernel _=/bin/env) Sep 22 08:22:37 hostname kernel: speedtch.c: udsl_usb_init: driver version 1.8 Sep 22 08:22:37 hostname kernel: usb.c: registered new driver speedtch Sep 22 08:22:37 hostname /sbin/hotplug: invoke /etc/hotplug/usb.agent () Sep 22 08:22:37 hostname kernel: uhci.c: root-hub INT complete: port1: 80 port2: 93 data: 4 Sep 22 08:22:37 hostname kernel: hub.c: port 1, portstatus 100, change 0, 12 Mb/s Sep 22 08:22:37 hostname kernel: hub.c: port 2, portstatus 101, change 1, 12 Mb/s Sep 22 08:22:37 hostname kernel: hub.c: port 2 connection change Sep 22 08:22:37 hostname kernel: hub.c: port 2, portstatus 101, change 1, 12 Mb/s Sep 22 08:22:37 hostname kernel: hub.c: port 2, portstatus 101, change 0, 12 Mb/s Sep 22 08:22:37 hostname last message repeated 3 times Sep 22 08:22:37 hostname kernel: hub.c: port 2, portstatus 103, change 0, 12 Mb/s Sep 22 08:22:37 hostname kernel: hub.c: new USB device 00:07.2-2, assigned address 2 Sep 22 08:22:37 hostname kernel: usb.c: kmalloc IF c1f113a0, numif 3 Sep 22 08:22:37 hostname kernel: usb.c: new device strings: Mfr=1, Product=2, SerialNumber=3 Sep 22 08:22:37 hostname kernel: usb.c: USB device number 2 default language ID 0x409 Sep 22 08:22:37 hostname kernel: Manufacturer: THOMSON Sep 22 08:22:37 hostname kernel: Product: Speed Touch 330 Sep 22 08:22:37 hostname kernel: SerialNumber: 0090D0C871A3 Sep 22 08:22:37 hostname kernel: speedtch.c: udsl_usb_probe: trying device with vendor=0x6b9, product=0x4061, ifnum 0 Sep 22 08:22:37 hostname kernel: speedtch.c: udsl_usb_probe: trying device with vendor=0x6b9, product=0x4061, ifnum 1 Sep 22 08:22:37 hostname kernel: speedtch.c: udsl_usb_probe: device accepted Sep 22 08:22:37 hostname kernel: usb.c: speedtch driver claimed interface c1f113b8 Sep 22 08:22:37 hostname kernel: speedtch.c: udsl_usb_probe: trying device with vendor=0x6b9, product=0x4061, ifnum 2 Sep 22 08:22:37 hostname kernel: usb.c: unhandled interfaces on device Sep 22 08:22:37 hostname kernel: usb.c: kusbd: /sbin/hotplug add 2 Sep 22 08:22:38 hostname last message repeated 2 times Sep 22 08:22:38 hostname /sbin/hotplug: arguments (usb) env (DEVFS=/proc/bus/usb OLDPWD=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin ACTION=add PWD=/etc/hotplug SHLVL=1 HOME=/ DEVICE=/proc/bus/usb/001/002 PRODUCT=6b9/4061/400 TYPE=255/0/0 DEBUG=kernel _=/bin/env) Sep 22 08:22:38 hostname /sbin/hotplug: invoke /etc/hotplug/usb.agent () Sep 22 08:22:38 hostname /sbin/hotplug: arguments (usb) env (DEVFS=/proc/bus/usb OLDPWD=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin ACTION=add PWD=/etc/hotplug SHLVL=1 HOME=/ DEVICE=/proc/bus/usb/001/002 PRODUCT=6b9/4061/400 TYPE=255/0/0 DEBUG=kernel _=/bin/env) Sep 22 08:22:38 hostname /sbin/hotplug: invoke /etc/hotplug/usb.agent () Sep 22 08:22:38 hostname modem_run[24710]: modem_run version 1.2-beta3 started by root uid 0 Sep 22 08:22:38 hostname kernel: usb.c: usbdevfs driver claimed interface c1f113d0 Sep 22 08:22:38 hostname /sbin/hotplug: arguments (usb) env (DEVFS=/proc/bus/usb OLDPWD=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin ACTION=add PWD=/etc/hotplug SHLVL=1 HOME=/ DEVICE=/proc/bus/usb/001/002 PRODUCT=6b9/4061/400 TYPE=255/0/0 DEBUG=kernel _=/bin/env) Sep 22 08:22:38 hostname /sbin/hotplug: invoke /etc/hotplug/usb.agent () Sep 22 08:22:40 hostname /etc/hotplug/usb.agent: ... no modules for USB product 0/0/0 Sep 22 08:22:41 hostname kernel: usb_control/bulk_msg: timeout Sep 22 08:22:41 hostname kernel: usbdevfs: USBDEVFS_BULK failed dev 2 ep 0x85 len 512 ret -110 Sep 22 08:22:43 hostname /etc/hotplug/usb.agent: Setup speedtch for USB product 6b9/4061/400 Sep 22 08:22:44 hostname last message repeated 2 times Sep 22 08:22:50 hostname kernel: usb.c: usbdevfs driver claimed interface c1f113a0 Sep 22 08:23:51 hostname kernel: speedtch.c: udsl_usb_ioctl entered Sep 22 08:23:51 hostname modem_run[24710]: ADSL synchronization has been obtained Sep 22 08:23:51 hostname modem_run[24710]: ADSL line is up (768 kbit/s down | 192 kbit/s up) ---end syslog--- Okay thus far, right after this pppd starts, and speedtch.c starts complaining about stuff: ---start syslog--- Sep 22 08:23:51 hostname pppd[24778]: Plugin /usr/lib/pppd/2.4.1/pppoatm.so loaded. Sep 22 08:23:51 hostname pppd[24778]: PPPoATM plugin_init Sep 22 08:23:51 hostname pppd[24778]: PPPoATM setdevname - remove unwanted options Sep 22 08:23:51 hostname pppd[24778]: PPPoATM setdevname_pppoatm - SUCCESS:0.35 Sep 22 08:23:51 hostname pppd[24779]: pppd 2.4.1 started by root, uid 0 Sep 22 08:23:51 hostname kernel: speedtch.c: udsl_atm_open: vpi 0, vci 35 Sep 22 08:23:51 hostname kernel: speedtch.c: udsl_atm_open: allocated vcc data 0xc1539a60 (max_pdu: 1536) Sep 22 08:23:51 hostname pppd[24779]: Using interface ppp0 Sep 22 08:23:51 hostname pppd[24779]: Connect: ppp0 <--> 0.35 Sep 22 08:23:51 hostname /etc/hotplug/net.agent: assuming ppp0 is already up Sep 22 08:23:51 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:23:51 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:23:51 hostname pppd[24779]: local IP address xxx.xxx.xxx.xxx Sep 22 08:23:51 hostname pppd[24779]: remote IP address xxx.xxx.xxx.xxx Sep 22 08:23:51 hostname ip-up: Applying ruleset Sep 22 08:23:52 hostname ip-up: Done. ---end syslog--- Everything is working perfectly at this point, but speedtch.c keeps saying the same thing: ---start syslog--- Sep 22 08:23:53 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:23:57 hostname last message repeated 9 times Sep 22 08:23:58 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:24:29 hostname last message repeated 56 times Sep 22 08:24:59 hostname last message repeated 55 times Sep 22 08:25:00 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:25:29 hostname last message repeated 53 times Sep 22 08:25:31 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:25:59 hostname last message repeated 53 times ***etc*** ---end syslog--- And it just keeps on going like this, with otherwise normal connection performance. Occasionally, however, speedtch.c will say other seemingly interesting stuff: ---start syslog--- Sep 22 08:26:52 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:27:00 hostname last message repeated 15 times Sep 22 08:27:01 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:27:32 hostname last message repeated 56 times Sep 22 08:28:33 hostname last message repeated 110 times Sep 22 08:29:27 hostname last message repeated 97 times Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: buffer overrun (sarb->len 1536, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 1008, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: buffer overrun (sarb->len 1536, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 1056, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: buffer overrun (sarb->len 1536, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 1152, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 288, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 1392, vcc: 0xc37e7400)! Sep 22 08:29:27 hostname kernel: speedtch.c: udsl_extract_cells: bogus pdu_length 1536 (sarb->len: 96, vcc: 0xc37e7400)! Sep 22 08:29:28 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:29:59 hostname last message repeated 56 times Sep 22 08:29:59 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 08:30:29 hostname last message repeated 54 times ***etc*** ---end syslog--- And this will repeat once in a while, at random intervals. Eventually, a disconnection occurs: ---start syslog--- Sep 22 09:00:31 hostname kernel: speedtch.c: udsl_extract_cells: unknown vpi/vci (0/16)! Sep 22 09:00:39 hostname last message repeated 15 times Sep 22 09:01:11 hostname pppd[25116]: No response to 15 echo-requests Sep 22 09:01:11 hostname pppd[25116]: Serial link appears to be disconnected. Sep 22 09:01:17 hostname pppd[25116]: Connection terminated. Sep 22 09:01:17 hostname pppd[25116]: Connect time 17.4 minutes. Sep 22 09:01:17 hostname pppd[25116]: Sent 369398 bytes, received 3686655 bytes. Sep 22 09:01:17 hostname kernel: speedtch.c: udsl_atm_close called Sep 22 09:01:17 hostname kernel: speedtch.c: udsl_atm_close: deallocating vcc 0xc3ac0f00 with vpi 0 vci 35 Sep 22 09:01:17 hostname kernel: speedtch.c: udsl_cancel_send entered Sep 22 09:01:17 hostname kernel: speedtch.c: udsl_cancel_send done Sep 22 09:01:17 hostname kernel: speedtch.c: udsl_atm_close successful Sep 22 09:01:18 hostname /etc/hotplug/net.agent: NET unregister event not supported Sep 22 09:01:22 hostname kernel: speedtch.c: udsl_atm_open: vpi 0, vci 35 Sep 22 09:01:22 hostname kernel: speedtch.c: udsl_atm_open: allocated vcc data 0xc3ac0f00 (max_pdu: 1536) Sep 22 09:01:22 hostname pppd[25116]: Using interface ppp0 Sep 22 09:01:22 hostname pppd[25116]: Connect: ppp0 <--> 0.35 Sep 22 09:01:22 hostname /etc/hotplug/net.agent: assuming ppp0 is already up Sep 22 09:01:52 hostname pppd[25116]: LCP: timeout sending Config-Requests Sep 22 09:01:52 hostname pppd[25116]: Connection terminated. Sep 22 09:01:52 hostname kernel: speedtch.c: udsl_atm_close called Sep 22 09:01:52 hostname kernel: speedtch.c: udsl_atm_close: deallocating vcc 0xc3ac0f00 with vpi 0 vci 35 Sep 22 09:01:52 hostname kernel: speedtch.c: udsl_cancel_send entered Sep 22 09:01:52 hostname kernel: speedtch.c: udsl_cancel_send done Sep 22 09:01:52 hostname kernel: speedtch.c: udsl_atm_close successful Sep 22 09:01:52 hostname /etc/hotplug/net.agent: NET unregister event not supported Sep 22 09:01:56 hostname kernel: speedtch.c: udsl_atm_open: vpi 0, vci 35 Sep 22 09:01:56 hostname kernel: speedtch.c: udsl_atm_open: allocated vcc data 0xc3ac0e20 (max_pdu: 1536) Sep 22 09:01:56 hostname pppd[25116]: Using interface ppp0 Sep 22 09:01:56 hostname pppd[25116]: Connect: ppp0 <--> 0.35 Sep 22 09:01:56 hostname /etc/hotplug/net.agent: assuming ppp0 is already up Sep 22 09:02:26 hostname pppd[25116]: LCP: timeout sending Config-Requests Sep 22 09:02:26 hostname pppd[25116]: Connection terminated. Sep 22 09:02:26 hostname kernel: speedtch.c: udsl_atm_close called Sep 22 09:02:26 hostname kernel: speedtch.c: udsl_atm_close: deallocating vcc 0xc3ac0e20 with vpi 0 vci 35 Sep 22 09:02:26 hostname kernel: speedtch.c: udsl_cancel_send entered Sep 22 09:02:26 hostname kernel: speedtch.c: udsl_cancel_send done Sep 22 09:02:26 hostname kernel: speedtch.c: udsl_atm_close successful Sep 22 09:02:26 hostname /etc/hotplug/net.agent: NET unregister event not supported Sep 22 09:02:30 hostname kernel: speedtch.c: udsl_atm_open: vpi 0, vci 35 Sep 22 09:02:30 hostname kernel: speedtch.c: udsl_atm_open: allocated vcc data 0xc3ac0e20 (max_pdu: 1536) Sep 22 09:02:30 hostname pppd[25116]: Using interface ppp0 Sep 22 09:02:30 hostname pppd[25116]: Connect: ppp0 <--> 0.35 Sep 22 09:02:30 hostname /etc/hotplug/net.agent: assuming ppp0 is already up Sep 22 09:03:00 hostname pppd[25116]: LCP: timeout sending Config-Requests Sep 22 09:03:00 hostname pppd[25116]: Connection terminated. ---end syslog--- As you can see, pppd can't re-establish the connection now, it seems as if the modem has gone deaf.. I tried killing pppd & restarting it, it doesn't work. The only way I'm able to re-establish the connection is if I `rmmod' the uhci module & reload it, which is equivalent to disconnecting & reconnecting the usb cable, and thats why I've included commands to accomplish that in the connect script above. As a work around I've set up a cron job which checks for a ppp0 & calls the connect script if it's down. Connections last for random periods, ranging from a few minutes to several hours, to (rarely) several days. Sometimes it gets real annoying & disconnects every couple of minutes for a couple hours or so, then it goes back to normal. I haven't been able to find a pattern here. At a minimum, if it's possible to somehow make pppd reconnect (the persist thing) normally without having to rmmod+insmod+resync, it would make things way less inconvenient. Any help great appreciated! Thanks in advance. Liste de diffusion modem ALCATEL SpeedTouch USB Pour se d�sinscrire : mailto:[EMAIL PROTECTED]
