2.4.0-test1-ac15: USB oopsed

From: Frank van Maarseveen (F.vanMaarseveen@inter.NL.net)
Date: Sun Jun 18 2000 - 07:27:14 EST


Jun 18 13:52:19 iapetus kernel: Unable to handle kernel paging request at virtual address 5a5a5a6e
Jun 18 13:52:19 iapetus kernel: c7877ae1
Jun 18 13:52:19 iapetus kernel: *pde = 00000000
Jun 18 13:52:19 iapetus kernel: Oops: 0000
Jun 18 13:52:19 iapetus kernel: CPU: 0
Jun 18 13:52:19 iapetus kernel: EIP: 0010:[<c7877ae1>]
Using defaults from ksymoops -t elf32-i386 -a i386
Jun 18 13:52:19 iapetus kernel: EFLAGS: 00010286
Jun 18 13:52:19 iapetus kernel: eax: 5a5a5a5a ebx: c1fc3ea0 ecx: c06fc840 edx: c06fc840
Jun 18 13:52:19 iapetus kernel: esi: 00000286 edi: c1fc3ec8 ebp: c1fc3edc esp: c1fc3e78
Jun 18 13:52:19 iapetus kernel: ds: 0018 es: 0018 ss: 0018
Jun 18 13:52:19 iapetus kernel: Process khubd (pid: 14148, stackpage=c1fc3000)
Jun 18 13:52:19 iapetus kernel: Stack: c7877d7f c06fc840 c286e000 fa5a5a00 c3cf0a60 00000000 c1fc3eb4 c1fc3ec0
Jun 18 13:52:19 iapetus kernel: c1fc3ea0 c1fc3edc c1fc3eb4 00000000 c1fc3eb8 c1fc3ec0 c1fc3ec0 00000001
Jun 18 13:52:19 iapetus kernel: c1fc3ed4 c1fc3ed4 c1fc3ec0 c7877c7a 00000000 01234567 c1fc2000 c1fc3eb8
Jun 18 13:52:19 iapetus kernel: Call Trace: [<c7877d7f>] [<fa5a5a00>] [<c7877c7a>] [<c7877f59>] [<c7877fe4>] [<fa5a5a00>] [<c787a13b>]
Jun 18 13:52:19 iapetus kernel: [<fa5a5a00>] [<c787aa1d>] [<c787acf8>] [<c7882020>] [<c7882208>] [<c7877048>] [<c7880001>] [<c787a2aa>]
Jun 18 13:52:19 iapetus kernel: [<c787aefb>] [kernel_thread+35/48] [<c7877000>]
Jun 18 13:52:19 iapetus kernel: Code: 8b 40 14 52 8b 40 0c ff d0 83 c4 04 c3 89 f6 b8 ff ff ff ff

>>EIP; c7877ae1 <[usbcore]usb_submit_urb+15/2c> <=====
Trace; c7877d7f <[usbcore]usb_start_wait_urb+1c3/338>
Trace; fa5a5a00 <END_OF_CODE+32d0bb41/????>
Trace; c7877c7a <[usbcore]usb_start_wait_urb+be/338>
Trace; c7877f59 <[usbcore]usb_internal_control_msg+65/78>
Trace; c7877fe4 <[usbcore]usb_control_msg+78/94>
Trace; fa5a5a00 <END_OF_CODE+32d0bb41/????>
Trace; c787a13b <[usbcore]usb_set_port_feature+33/38>
Trace; fa5a5a00 <END_OF_CODE+32d0bb41/????>
Trace; c787aa1d <[usbcore]usb_hub_port_connect_change+191/3b0>
Trace; c787acf8 <[usbcore]usb_hub_events+bc/26c>
Trace; c7882020 <[usbcore].rodata.start+26c0/3874>
Trace; c7882208 <[usbcore].rodata.start+28a8/3874>
Trace; c7877048 <[parport_pc].data.end+1c49/1c61>
Trace; c7880001 <[usbcore].rodata.start+6a1/3874>
Trace; c787a2aa <[usbcore]hub_irq+f6/110>
Trace; c787aefb <[usbcore]usb_hub_thread+53/80>
Code; c7877ae1 <[usbcore]usb_submit_urb+15/2c>
00000000 <_EIP>:
Code; c7877ae1 <[usbcore]usb_submit_urb+15/2c> <=====
   0: 8b 40 14 movl 0x14(%eax),%eax <=====
Code; c7877ae4 <[usbcore]usb_submit_urb+18/2c>
   3: 52 pushl %edx
Code; c7877ae5 <[usbcore]usb_submit_urb+19/2c>
   4: 8b 40 0c movl 0xc(%eax),%eax
Code; c7877ae8 <[usbcore]usb_submit_urb+1c/2c>
   7: ff d0 call *%eax
Code; c7877aea <[usbcore]usb_submit_urb+1e/2c>
   9: 83 c4 04 addl $0x4,%esp
Code; c7877aed <[usbcore]usb_submit_urb+21/2c>
   c: c3 ret
Code; c7877aee <[usbcore]usb_submit_urb+22/2c>
   d: 89 f6 movl %esi,%esi
Code; c7877af0 <[usbcore]usb_submit_urb+24/2c>
   f: b8 ff ff ff ff movl $0xffffffff,%eax

More details:
My system is SMP. Everything is a module.
I've only a printer attached. It was powered off and disconnected.
Hours later, I just plugged an USB cable in the Linux box but in the other outlet.
The cable is 9m long (one 3m cable plus 2x3m extension cables, probably
illegally long). At the other end there is _no_ device.
6 or 7 hours later, /var/log/messages starts to fill:

Jun 17 18:31:32 iapetus pppd[20356]: Exit.
Jun 18 00:18:05 iapetus kernel: usb.c: USB new device connect, assigned device number 2
Jun 18 00:18:05 iapetus kernel: URB [c258d160] urbp [c1aea01c]
Jun 18 00:18:05 iapetus kernel: QH [c19e0000]
Jun 18 00:18:05 iapetus kernel: td 0: [c3064000]
Jun 18 00:18:05 iapetus kernel: 03064044 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c260)
Jun 18 00:18:05 iapetus kernel: td 1: [c3064040]
Jun 18 00:18:05 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:05 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:05 iapetus kernel: usb.c: USB new device connect, assigned device number -1
Jun 18 00:18:05 iapetus kernel: URB [c258d160] urbp [c1aea01c]
Jun 18 00:18:05 iapetus kernel: QH [c19e0000]
Jun 18 00:18:05 iapetus kernel: td 0: [c3064040]
Jun 18 00:18:05 iapetus kernel: 03064004 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c260)
Jun 18 00:18:05 iapetus kernel: td 1: [c3064000]
Jun 18 00:18:05 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:05 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:05 iapetus kernel: usb.c: USB disconnect on device -1
Jun 18 00:18:13 iapetus kernel: usb.c: USB new device connect, assigned device number 2
Jun 18 00:18:13 iapetus kernel: URB [c258d2a0] urbp [c1aea01c]
Jun 18 00:18:13 iapetus kernel: QH [c19e0000]
Jun 18 00:18:13 iapetus kernel: td 0: [c3064000]
Jun 18 00:18:13 iapetus kernel: 03064044 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c1a0)
Jun 18 00:18:13 iapetus kernel: td 1: [c3064040]
Jun 18 00:18:13 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:13 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:13 iapetus kernel: usb.c: USB new device connect, assigned device number -1
Jun 18 00:18:13 iapetus kernel: URB [c258d2a0] urbp [c1aea01c]
Jun 18 00:18:13 iapetus kernel: QH [c19e0000]
Jun 18 00:18:13 iapetus kernel: td 0: [c3064040]
Jun 18 00:18:13 iapetus kernel: 03064004 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c1a0)
Jun 18 00:18:13 iapetus kernel: td 1: [c3064000]
Jun 18 00:18:13 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:13 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:13 iapetus kernel: usb.c: USB disconnect on device -1
Jun 18 00:18:27 iapetus kernel: usb.c: USB new device connect, assigned device number 2
Jun 18 00:18:27 iapetus kernel: URB [c258df20] urbp [c1aea01c]
Jun 18 00:18:27 iapetus kernel: QH [c19e0000]
Jun 18 00:18:27 iapetus kernel: td 0: [c3064000]
Jun 18 00:18:27 iapetus kernel: 03064044 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c320)
Jun 18 00:18:27 iapetus kernel: td 1: [c3064040]
Jun 18 00:18:27 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:27 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:27 iapetus kernel: usb.c: USB new device connect, assigned device number -1
Jun 18 00:18:27 iapetus kernel: URB [c258df20] urbp [c1aea01c]
Jun 18 00:18:27 iapetus kernel: QH [c19e0000]
Jun 18 00:18:27 iapetus kernel: td 0: [c3064040]
Jun 18 00:18:27 iapetus kernel: 03064004 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c320)
Jun 18 00:18:27 iapetus kernel: td 1: [c3064000]
Jun 18 00:18:27 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 00:18:27 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 00:18:27 iapetus kernel: usb.c: USB disconnect on device -1
Jun 18 00:18:35 iapetus kernel: usb.c: USB new device connect, assigned device number 2
Jun 18 00:18:35 iapetus kernel: URB [c258d020] urbp [c1aea01c]
Jun 18 00:18:35 iapetus kernel: QH [c19e0000]
Jun 18 00:18:35 iapetus kernel: td 0: [c3064000]
Jun 18 00:18:35 iapetus kernel: 03064044 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=01d7c320)
Jun 18 00:18:35 iapetus kernel: td 1: [c3064040]

This goes on and on. A few hours later:

Jun 18 04:02:01 iapetus syslogd 1.3-3: restart.
Jun 18 04:02:01 iapetus syslogd 1.3-3: restart.
Jun 18 04:02:01 iapetus syslogd 1.3-3: restart.
Jun 18 04:04:35 iapetus PAM_pwdb[21475]: (su) session opened for user news by (uid=0)
Jun 18 04:04:37 iapetus PAM_pwdb[21475]: (su) session closed for user news
Jun 18 04:11:36 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:11:36 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:11:42 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:11:42 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:24:56 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:24:56 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:24:59 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:24:59 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:25:10 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:25:10 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:25:45 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:25:45 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:25:50 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:25:50 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:26:28 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:26:28 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:26:45 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:26:45 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:26:48 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:26:48 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:26:54 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:26:54 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:26:58 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:26:58 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:27:27 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:27:27 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:27:57 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:27:57 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:28:08 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:28:08 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:28:40 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:28:40 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:28:46 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:28:46 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:28:50 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:28:50 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:28:54 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:28:54 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:29:10 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:29:10 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:29:17 iapetus kernel: hub.c: Cannot enable port 2 after 5 retries, disabling port.
Jun 18 04:29:17 iapetus kernel: hub.c: Maybe the USB cable is bad?
Jun 18 04:29:19 iapetus kernel: usb.c: USB new device connect, assigned device number 2
Jun 18 04:29:19 iapetus kernel: URB [c258d0c0] urbp [c33c7018]
Jun 18 04:29:19 iapetus kernel: QH [c133b020]
Jun 18 04:29:19 iapetus kernel: td 0: [c3757020]
Jun 18 04:29:19 iapetus kernel: 03757064 e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=033d8260)
Jun 18 04:29:19 iapetus kernel: td 1: [c3757060]
Jun 18 04:29:19 iapetus kernel: 00000001 e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jun 18 04:29:19 iapetus kernel: usb.c: USB device not accepting new address (error=-110)
Jun 18 04:29:19 iapetus kernel: usb.c: USB new device connect, assigned device number -1

Tired of this, I unplugged the last 6m of the USB cable, leaving 3m in
the linux box. It had no effect. The next step was to get rid of the
modules involved:
        modprobe -r printer
        modprobe -r uhci
This immediately triggered the OOPS:

Jun 18 13:52:19 iapetus kernel: usb.c: USB disconnect on device 1
Jun 18 13:52:19 iapetus kernel: usb.c: USB bus 1 deregistered
[oops data, symbols partially missing so I ran ksymoops]

ksymoops 2.3.3 on i686 2.4.0-y1. Options used
     -V (default)
     -k /proc/ksyms (default)
     -l /proc/modules (default)
     -o /lib/modules/2.4.0-y1/ (default)
     -m /boot/System.map-2.4.0-y1 (specified)

[oops data on top of this mail]

-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@vger.rutgers.edu
Please read the FAQ at http://www.tux.org/lkml/



This archive was generated by hypermail 2b29 : Fri Jun 23 2000 - 21:00:15 EST