[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

from hotplug to udev and speedtouch modem: a report of problems



Hello Debianists,

this mail is a rather detailed report of my attempts to have the green
ALCATEL SpeedTouch USB modem running after I moved to the udev system
some days ago (the bottom line is that I feel I'm not far from getting
it to work, but I need your help):

# apt-get install udev 
... installed version is 0.074-3

This removed the previously installed hotplug package, which is
fine. It also managed to convert some hotplug files from their
/etc/hotplug/usb/ location to a new format and location
/etc/udev/rules.d. Fine also.

I had the speedtouch_1.3.1-2_i386.deb package installed (deb package
by Benoit PAPILLAULT <benoit.papillault@free.fr>). That package
implemented a user-space device driver functionality (contrary to a
kernel-module device driver functionality that was also available).

The speedtouch modem ceased working after my switch to udev and after
poking around on the web I discovered that :

- there was no more speedtouch package in Debian (testing)

- apparently the best way to go was to use the kernel module

So I decided to go with the kernel device driver. I recompiled by
kernel (2.6.12).

One document explained that new kernels did not need modem_run anymore
to load the firmware into the modem. Instead, it explained how to
split the firmware into two files that will belong to the udev-related
/lib/firmware directory. The program that will split the mgmt.o
firmware object file into two files is called firmware-extractor, and
it is run like this :

./firmware-extractor mgmt.o
** Boot block from mgmt.o:
   CRC: 0xd80bf9f7
   Length: 991
** Firmware block from mgmt.o:
   CRC: 0x94a45435
   Length: 526187

The instructions ask to run `make install', which actually simply
copies the two generated files (speedtch-1.bin and speedtch-2.bin) in
/lib/firmware

# make install
install -p -d -m 755 /lib/firmware
install -m 600 speedtch-{1,2}.bin /lib/firmware

Great, now the firmware stuff is done.

The different network connection configuration files did not change :

/etc/ppp/peers/speedtch
/etc/ppp/chap-secrets
resolv.conf and so on...


Because I had removed the speedtch deb package I had to copy the
pppoa2 and pppoa3 files from the deb package to /usr/sbin, which I
did.


After reboot I get to see that apparently the speedtouch modem is
correctly detected and its firmware is correctly setup:

$ dmesg | grep speed
usb 1-1: new low speed USB device using uhci_hcd and address 3
usb 2-2: new full speed USB device using uhci_hcd and address 3
bound device '2-2:1.1' to driver 'speedtch'
 2-2: found stage 1 firmware speedtch-1.bin
 2-2: found stage 2 firmware speedtch-2.bin

So the next logical step is to start pppd with the following call
while having a look at /var/log/messages and /var/log/syslog:

# pppd call speedtch
Plugin pppoatm.so loaded.

Note that the "Plugin pppoatm.so loaded." was output almost
immediately after the call and pppd returned with exit status '0' (as
reported by echo $?).


Output from /var/log/syslog:
~~~~~~~~~~~~~~~~~~~~~~~~~~~~
pppd[10879]: Plugin pppoatm.so loaded.
pppd[10880]: pppd 2.4.3 started by root, uid 0
pppoa3[10881]: pppoa3 version 1.3.1 started by root (uid 0)
pppoa3[10881]: Control thread ready
pppoa3[10881]: Modem not found.
pppd[10880]: Couldn't get channel number: Input/output error
pppd[10880]: Script /usr/sbin/pppoa3 -c -m 1 -vpi 8 -vci 35 finished (pid 10881), status = 0xff

So I went back to the /usr/bin/speedtouch-start binary file that used
to fire-up my speedtouch when I was using the speedtouch deb package,
and it reads :

pppd call "${PPPD_PEER}" updetach (with ${PPPD_PEER} being the /etc/ppp/peers/speedtch file)

I thus killall pppd, to start fresh, and run that:

# pppd call speedtch updetach

This time I get an output from that command line that is longer:

Plugin pppoatm.so loaded.
using channel 2
Using interface ppp0
Connect: ppp0 <--> /dev/pts/5
Modem hangup
Connection terminated.
Script /usr/sbin/pppoa3 -c -m 1 -vpi 8 -vci 35 finished (pid 11238), status = 0xff

And this is the syslog output, which is rather longish:

pppd[11237]: Plugin pppoatm.so loaded.
pppd[11237]: pppd 2.4.3 started by rusconi, uid 0
pppoa3[11238]: pppoa3 version 1.3.1 started by root (uid 0)
pppoa3[11238]: Control thread ready
pppd[11237]: using channel 2
kernel: CLASS: registering class device: ID = 'ppp0'
kernel: class_hotplug - name = ppp0
udevd[997]: udev_event_run: seq 1039 forked, pid [11240], 'add' 'net', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd-event[11240]: udev_rules_get_name: no node name set, will use kernel name 'ppp0'
udevd-event[11240]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[11240]: run_program: '/bin/sh -c '/lib/udev/net.agent &''
udevd-event[11240]: run_program: '/bin/sh' returned with status 0
udevd-event[11240]: run_program: '/lib/udev/udev_run_hotplugd' 'net'
udevd-event[11240]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[11240]: run_program: '/lib/udev/udev_run_devd' 'net'
udevd-event[11240]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[11240]: udev_event_run: seq 1039 finished
udevd[997]: udev_done: seq 1039, pid [11240] exit, 0 seconds old
pppd[11237]: Using interface ppp0
pppd[11237]: Connect: ppp0 <--> /dev/pts/5
----> pppoa3[11238]: Modem not found.
pppd[11237]: Modem hangup
pppd[11237]: Connection terminated.
kernel: class_hotplug - name = ppp0
udevd[997]: udev_event_run: seq 1040 forked, pid [11245], 'remove' 'net', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: device class 'ppp0': release.
pppd[11237]: Script /usr/sbin/pppoa3 -c -m 1 -vpi 8 -vci 35 finished (pid 11238), status = 0xff
udevd-event[11245]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[11245]: run_program: '/bin/sh -c '/lib/udev/net.agent &''
udevd-event[11245]: run_program: '/bin/sh' returned with status 0
udevd-event[11245]: run_program: '/lib/udev/udev_run_hotplugd' 'net'
udevd-event[11245]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[11245]: run_program: '/lib/udev/udev_run_devd' 'net'
udevd-event[11245]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[11245]: udev_event_run: seq 1040 finished
udevd[997]: udev_done: seq 1040, pid [11245] exit, 1 seconds old




So I am discovering here that ----> pppoa3[11238]: Modem not found.


Since I recall that I used to have to mount /proc/bus/usb, I just do
that easily, as I have the following line in /etc/fstab

none /proc/bus/usb   usbfs   user,noauto 0 0

# mount /proc/bus/usb/



I just try to unplug the speedtouch modem, to plug it back and this
is what I get in /var/log/messages 


Nov 23 09:24:19 casamiatheirhouse kernel: usb 2-2: new full speed USB device using uhci_hcd and address 7
Nov 23 09:24:20 casamiatheirhouse kernel: usb 2-2: found stage 1 firmware speedtch-1.bin
Nov 23 09:24:20 casamiatheirhouse kernel: usb 2-2: found stage 2 firmware speedtch-2.bin
Nov 23 09:24:24 casamiatheirhouse kernel: ADSL line is synchronising
Nov 23 09:25:33 casamiatheirhouse kernel: DSL line goes up
Nov 23 09:25:33 casamiatheirhouse kernel: ADSL line is up (608 Kib/s down | 160 Kib/s up)


and this is what I get in /var/log/syslog (rather long udev output,
where successes were intersparsed with errors, which is why I give it
in full here, but see the end of the output immediately if you like):

kernel: usb 2-2: new full speed USB device using uhci_hcd and address 7
kernel: usb 2-2: device descriptor read/64, error -71
udevd[997]: udev_event_run: seq 1105 forked, pid [14136], 'add' 'usb', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: DEV: registering device: ID = '2-2'
kernel: PM: Adding info for usb:2-2
kernel: bus usb: add device 2-2
kernel: bound device '2-2' to driver 'usb'
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: DEV: registering device: ID = '2-2:1.0'
kernel: PM: Adding info for usb:2-2:1.0
kernel: bus usb: add device 2-2:1.0
kernel: DEV: registering device: ID = '2-2:1.1'
kernel: PM: Adding info for usb:2-2:1.1
kernel: bus usb: add device 2-2:1.1
udevd-event[14136]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14136]: run_program: '/lib/udev/udev_run_hotplugd' 'usb'
udevd-event[14136]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14136]: run_program: '/lib/udev/udev_run_devd' 'usb'
udevd-event[14136]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14136]: udev_event_run: seq 1105 finished
udevd[997]: udev_done: seq 1105, pid [14136] exit, 0 seconds old
udevd[997]: udev_event_run: seq 1106 forked, pid [14142], 'add' 'usb', 0 seconds old
udevd[997]: udev_event_run: seq 1107 forked, pid [14143], 'add' 'usb', 0 seconds old
udevd-event[14142]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14142]: run_program: '/sbin/modprobe usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip*'
udevd-event[14142]: run_program: '/sbin/modprobe' (stderr) 'FATAL: Module usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip* not found.'
udevd-event[14142]: run_program: '/sbin/modprobe' returned with status 1
udevd-event[14142]: run_program: '/lib/udev/udev_run_hotplugd' 'usb'
udevd-event[14142]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14142]: run_program: '/lib/udev/udev_run_devd' 'usb'
udevd-event[14142]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14142]: udev_event_run: seq 1106 finished
udevd[997]: udev_done: seq 1106, pid [14142] exit, 0 seconds old
udevd[997]: udev_event_run: seq 1108 forked, pid [14148], 'add' 'usb', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: bound device '2-2:1.1' to driver 'speedtch'
kernel: DEV: registering device: ID = '2-2:1.2'
kernel: PM: Adding info for usb:2-2:1.2
kernel: bus usb: add device 2-2:1.2
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1109 forked, pid [14150], 'add' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd-event[14148]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14148]: run_program: '/sbin/modprobe usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip*'
udevd-event[14148]: run_program: '/sbin/modprobe' (stderr) 'FATAL: Module usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip* not found.'
udevd-event[14148]: run_program: '/sbin/modprobe' returned with status 1
udevd-event[14148]: run_program: '/lib/udev/udev_run_hotplugd' 'usb'
udevd-event[14148]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14148]: run_program: '/lib/udev/udev_run_devd' 'usb'
udevd-event[14148]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14148]: udev_event_run: seq 1108 finished
udevd[997]: udev_done: seq 1108, pid [14148] exit, 0 seconds old
udevd-event[14150]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14150]: run_program: '/lib/udev/firmware.agent' 'firmware'
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1110 forked, pid [14156], 'remove' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1111 forked, pid [14158], 'add' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: device class '2-2': release.
udevd-event[14150]: run_program: '/lib/udev/firmware.agent' returned with status 1
udevd-event[14150]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14150]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14150]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14150]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14150]: udev_event_run: seq 1109 finished
udevd[997]: udev_done: seq 1109, pid [14150] exit, 0 seconds old
udevd-event[14156]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd-event[14156]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14156]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14156]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14156]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14143]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14143]: run_program: '/sbin/modprobe usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip*'
udevd-event[14143]: run_program: '/sbin/modprobe' (stderr) 'FATAL: Module usb:v06B9p4061d0000dcFFdsc00dp00ic*isc*ip* not found.'
udevd-event[14143]: run_program: '/sbin/modprobe' returned with status 1
udevd-event[14143]: run_program: '/lib/udev/udev_run_hotplugd' 'usb'
udevd-event[14143]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14143]: run_program: '/lib/udev/udev_run_devd' 'usb'
udevd-event[14143]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14143]: udev_event_run: seq 1107 finished
udevd[997]: udev_done: seq 1107, pid [14143] exit, 0 seconds old
udevd-event[14156]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14156]: udev_event_run: seq 1110 finished
udevd[997]: udev_done: seq 1110, pid [14156] exit, 0 seconds old
udevd-event[14158]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14158]: run_program: '/lib/udev/firmware.agent' 'firmware'
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1112 forked, pid [14168], 'remove' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1113 forked, pid [14170], 'add' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: device class '2-2': release.
udevd-event[14158]: run_program: '/lib/udev/firmware.agent' returned with status 1
udevd-event[14158]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14158]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14158]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14158]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14158]: udev_event_run: seq 1111 finished
udevd[997]: udev_done: seq 1111, pid [14158] exit, 0 seconds old
udevd-event[14168]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd-event[14168]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14168]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14168]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14168]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14168]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14168]: udev_event_run: seq 1112 finished
udevd[997]: udev_done: seq 1112, pid [14168] exit, 0 seconds old
udevd-event[14170]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14170]: run_program: '/lib/udev/firmware.agent' 'firmware'
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1114 forked, pid [14178], 'remove' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: usb 2-2: found stage 1 firmware speedtch-1.bin
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: udev_event_run: seq 1115 forked, pid [14180], 'add' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: device class '2-2': release.
udevd-event[14170]: run_program: '/lib/udev/firmware.agent' returned with status 0
udevd-event[14170]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14170]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14170]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14170]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14170]: udev_event_run: seq 1113 finished
udevd[997]: udev_done: seq 1113, pid [14170] exit, 0 seconds old
udevd-event[14178]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd-event[14178]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14178]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14178]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14178]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14178]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14178]: udev_event_run: seq 1114 finished
udevd[997]: udev_done: seq 1114, pid [14178] exit, 0 seconds old
udevd-event[14180]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14180]: run_program: '/lib/udev/firmware.agent' 'firmware'
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd[997]: udev_event_run: seq 1116 forked, pid [14189], 'remove' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd[997]: udev_event_run: seq 1117 forked, pid [14190], 'add' 'firmware', 0 seconds old
kernel: device class '2-2': release.
udevd-event[14180]: run_program: '/lib/udev/firmware.agent' returned with status 1
udevd-event[14180]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14180]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14180]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14180]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14180]: udev_event_run: seq 1115 finished
udevd[997]: udev_done: seq 1115, pid [14180] exit, 0 seconds old
udevd-event[14189]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd-event[14189]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14189]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14189]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14189]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14189]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14189]: udev_event_run: seq 1116 finished
udevd[997]: udev_done: seq 1116, pid [14189] exit, 0 seconds old
udevd-event[14190]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14190]: run_program: '/lib/udev/firmware.agent' 'firmware'
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: CLASS: registering class device: ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: class_hotplug - hotplug() returned -19
kernel: class_hotplug - name = 2-2
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd[997]: udev_event_run: seq 1118 forked, pid [14198], 'remove' 'firmware', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd[997]: udev_event_run: seq 1119 forked, pid [14199], 'add' 'firmware', 0 seconds old
kernel: device class '2-2': release.
udevd-event[14190]: run_program: '/lib/udev/firmware.agent' returned with status 1
udevd-event[14190]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14190]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14190]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14190]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14190]: udev_event_run: seq 1117 finished
udevd-event[14198]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd-event[14198]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14198]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14198]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14198]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14198]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14198]: udev_event_run: seq 1118 finished
udevd-event[14199]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14199]: run_program: '/lib/udev/firmware.agent' 'firmware'
udevd[997]: udev_done: seq 1117, pid [14190] exit, 0 seconds old
udevd[997]: udev_done: seq 1118, pid [14198] exit, 0 seconds old
kernel: CLASS: Unregistering class device. ID = '2-2'
kernel: class_hotplug - name = 2-2
kernel: usb 2-2: found stage 2 firmware speedtch-2.bin
kernel: bound device '2-2:1.2' to driver 'speedtch'
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
udevd-event[14207]: udev_db_get_device: no db file to read /dev/.udevdb/class@firmware@2-2: No such file or directory
udevd[997]: udev_event_run: seq 1120 forked, pid [14207], 'remove' 'firmware', 0 seconds old
udevd-event[14207]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14207]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14207]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14207]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14207]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14207]: udev_event_run: seq 1120 finished
udevd[997]: udev_done: seq 1120, pid [14207] exit, 0 seconds old
kernel: device class '2-2': release.
udevd-event[14199]: run_program: '/lib/udev/firmware.agent' returned with status 0
udevd-event[14199]: run_program: '/lib/udev/udev_run_hotplugd' 'firmware'
udevd-event[14199]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14199]: run_program: '/lib/udev/udev_run_devd' 'firmware'
udevd-event[14199]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14199]: udev_event_run: seq 1119 finished
udevd[997]: udev_done: seq 1119, pid [14199] exit, 0 seconds old
kernel: bound device '2-2:1.0' to driver 'speedtch'
kernel: ADSL line is synchronising
kernel: DSL line goes up
kernel: ADSL line is up (608 Kib/s down | 160 Kib/s up)


And now, back to starting the pppd daemon :

# pppd call speedtch updetach
Plugin pppoatm.so loaded.
using channel 3
Using interface ppp0
Connect: ppp0 <--> /dev/pts/7
Modem hangup
Connection terminated.
Script /usr/sbin/pppoa3 -c -m 1 -vpi 8 -vci 35 finished (pid 14462), status = 0xff
(command does not yield)

and this is what syslog has output :



pppd[14461]: Plugin pppoatm.so loaded.
pppd[14461]: pppd 2.4.3 started by rusconi, uid 0
pppd[14461]: using channel 3
udevd[997]: udev_event_run: seq 1121 forked, pid [14464], 'add' 'net', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
pppd[14461]: Using interface ppp0
pppd[14461]: Connect: ppp0 <--> /dev/pts/7
kernel: CLASS: registering class device: ID = 'ppp0'
kernel: class_hotplug - name = ppp0
udevd-event[14464]: udev_rules_get_name: no node name set, will use kernel name 'ppp0'
udevd-event[14464]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14464]: run_program: '/bin/sh -c '/lib/udev/net.agent &''
udevd-event[14464]: run_program: '/bin/sh' returned with status 0
udevd-event[14464]: run_program: '/lib/udev/udev_run_hotplugd' 'net'
udevd-event[14464]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14464]: run_program: '/lib/udev/udev_run_devd' 'net'
udevd-event[14464]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14464]: udev_event_run: seq 1121 finished
udevd[997]: udev_done: seq 1121, pid [14464] exit, 0 seconds old
pppoa3[14462]: pppoa3 version 1.3.1 started by root (uid 0)
pppoa3[14462]: Control thread ready
=======>>> pppoa3[14462]: pusb_claim_interface 1 failed
pppd[14461]: Modem hangup
pppd[14461]: Connection terminated.
kernel: class_hotplug - name = ppp0
udevd[997]: udev_event_run: seq 1122 forked, pid [14469], 'remove' 'net', 0 seconds old
udevd[997]: get_udevd_msg: udevd event message received
udevd[997]: main: skip non-kernel message with SEQNUM
kernel: device class 'ppp0': release.
pppd[14461]: Script /usr/sbin/pppoa3 -c -m 1 -vpi 8 -vci 35 finished (pid 14462), status = 0xff
udevd-event[14469]: pass_env_to_socket: passed -1 bytes to socket '/org/kernel/udev/monitor',
udevd-event[14469]: run_program: '/bin/sh -c '/lib/udev/net.agent &''
udevd-event[14469]: run_program: '/bin/sh' returned with status 0
udevd-event[14469]: run_program: '/lib/udev/udev_run_hotplugd' 'net'
udevd-event[14469]: run_program: '/lib/udev/udev_run_hotplugd' returned with status 0
udevd-event[14469]: run_program: '/lib/udev/udev_run_devd' 'net'
udevd-event[14469]: run_program: '/lib/udev/udev_run_devd' returned with status 0
udevd-event[14469]: udev_event_run: seq 1122 finished
udevd[997]: udev_done: seq 1122, pid [14469] exit, 0 seconds old


The first error I see in this output:

=======>>> pppoa3[14462]: pusb_claim_interface 1 failed

And my pppd does not start the ppp0 interface, as ifconfig does not show it.


I cannot figure out how to go ahead in my investigations... Anybody is
willing to help ? An another question is: What's the new solution
recommended to Debian GNU/Linux users with respect to having a
functional speedtouch modem ?


My Debian GNU/Linux system :

testing
ppp 2.4.3-20050321+2
udev 0.074-3


/etc/fstab lists :
none            /sys            sysfs   defaults                0       0
proc            /proc           proc    defaults                0       0
none            /proc/bus/usb   usbfs   user,noauto             0       0

/lib/firmware contains:
speedtch-1.bin  speedtch-2.bin

I can provide more details to anybody willing to help.

Thank you for your patience and help !

Filippo


-- 
Filippo Rusconi, PhD - CNRS researcher - key C78F687C @ pgp.mit.edu
Author of ``GNU polyxmass'' at http://www.polyxmass.org

Attachment: signature.asc
Description: Digital signature


Reply to: