2.6.20-rc2: oops with broken usb cable, fault handler locks up?

From: Pavel Machek
Date: Tue Dec 26 2006 - 16:49:32 EST

Hi!

I have cable between sx1 <-> pc, that is of _very_ low quality. Well,
today sx1 communication stopped working, and this time it was not
sx1's fault.

...wait a moment, what is that? did oops handler lock up for long
enough for softlockup to trigger?

Pavel
PM: Adding info for usb-serial:ttyUSB0
PM: Adding info for No Bus:ttyUSB0
usb 2-1: generic converter now attached to ttyUSB0
PM: Adding info for No Bus:usbdev2.81_ep01
PM: Adding info for No Bus:usbdev2.81_ep82
PM: Adding info for No Bus:usbdev2.81_ep83
usb 2-1: USB disconnect, address 81
PM: Removing info for No Bus:usbdev2.81_ep01
PM: Removing info for No Bus:usbdev2.81_ep82
PM: Removing info for No Bus:usbdev2.81_ep83
PM: Removing info for No Bus:ttyUSB0
generic ttyUSB0: generic converter now disconnected from ttyUSB0
PM: Removing info for usb-serial:ttyUSB0
usbserial_generic 2-1:1.0: device disconnected
PM: Removing info for usb:2-1:1.0
PM: Removing info for No Bus:usbdev2.81_ep00
PM: Removing info for usb:2-1
usb 2-1: new full speed USB device using uhci_hcd and address 82
usb 2-1: device descriptor read/64, error -71
PM: Adding info for usb:2-1
PM: Adding info for No Bus:usbdev2.82_ep00
usb 2-1: configuration #1 chosen from 2 choices
PM: Adding info for usb:2-1:1.0
usb0: register 'cdc_ether' at usb-0000:00:1d.0-1, CDC Ethernet Device, 5e:95:58:7c:02:7a
PM: Adding info for No Bus:usbdev2.82_ep83
PM: Adding info for usb:2-1:1.1
PM: Adding info for No Bus:usbdev2.82_ep81
PM: Adding info for No Bus:usbdev2.82_ep02
usb 2-1: USB disconnect, address 82
PM: Removing info for No Bus:usbdev2.82_ep83
usb0: unregister 'cdc_ether' usb-0000:00:1d.0-1, CDC Ethernet Device
PM: Removing info for usb:2-1:1.0
PM: Removing info for No Bus:usbdev2.82_ep81
PM: Removing info for No Bus:usbdev2.82_ep02
PM: Removing info for usb:2-1:1.1
PM: Removing info for No Bus:usbdev2.82_ep00
PM: Removing info for usb:2-1
usb 2-1: new full speed USB device using uhci_hcd and address 83
usb 2-1: device descriptor read/64, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 84
usb 2-1: device descriptor read/64, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 85
usb 2-1: device not accepting address 85, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 87
usb 2-1: device not accepting address 87, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 89
usb 2-1: device not accepting address 89, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 91
usb 2-1: device not accepting address 91, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 93
usb 2-1: device descriptor read/64, error -84
usb 2-1: new full speed USB device using uhci_hcd and address 94
usb 2-1: device descriptor read/64, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 95
usb 2-1: device not accepting address 95, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 97
usb 2-1: device not accepting address 97, error -71
usb 2-1: new full speed USB device using uhci_hcd and address 99
usb 2-1: new full speed USB device using uhci_hcd and address 100
usb 2-1: USB disconnect, address 100
BUG: unable to handle kernel NULL pointer dereference at virtual address 00000010
printing eip:
c060f5e4
*pde = 00000000
usb 2-1: unable to read config index 0 descriptor/start
usb 2-1: chopping to 0 config(s)
usb 2-1: string descriptor 0 read error: -19
usb 2-1: string descriptor 0 read error: -19
PM: Adding info for usb:2-1
PM: Adding info for No Bus:usbdev2.100_ep00
usb 2-1: no configuration chosen from 0 choices
BUG: soft lockup detected on CPU#1!
[<c014d469>] softlockup_tick+0xa9/0xd0
[<c0131383>] update_process_times+0x33/0x80
[<c011ab7b>] smp_apic_timer_interrupt+0x6b/0x80
[<c0103aa4>] apic_timer_interrupt+0x28/0x30
[<c0255602>] delay_tsc+0x12/0x20
[<c0255646>] __delay+0x6/0x10
[<c011fbbb>] do_page_fault+0x35b/0x600
[<c011f860>] do_page_fault+0x0/0x600
[<c061237c>] error_code+0x7c/0x84
[<c060f5e4>] klist_del+0x14/0x50
[<c0328a3b>] device_del+0x1b/0x1c0
[<c044ae91>] usb_disconnect+0xb1/0x120
[<c044d83a>] hub_thread+0x3ca/0xe00
[<c0120ab1>] __activate_task+0x21/0x40
[<c01238af>] try_to_wake_up+0x3f/0x420
[<c013c680>] autoremove_wake_function+0x0/0x50
[<c044d470>] hub_thread+0x0/0xe00
[<c013c5cc>] kthread+0xec/0xf0
[<c013c4e0>] kthread+0x0/0xf0
[<c0103be7>] kernel_thread_helper+0x7/0x10
=======================
Oops: 0000 [#1]
SMP
Modules linked in: usbserial
CPU: 1
EIP: 0060:[<c060f5e4>] Not tainted VLI
EFLAGS: 00010292 (2.6.20-rc2 #384)
EIP is at klist_del+0x14/0x50
eax: 00000000 ebx: 00000000 ecx: 0000000f edx: 00000000
esi: 0000007c edi: f309db70 ebp: f309dc44 esp: c2279ea4
ds: 007b es: 007b ss: 0068
Process khubd (pid: 304, ti=c2278000 task=c21fb030 task.ti=c2278000)
Stack: f309db80 0000007c f309db5c c0328a3b f7e81690 f309db80 0000007c f309db04
f309dc44 c044ae91 c0732acc c0701f8d f309dc14 00000064 f7e818b8 f309db5c
f78f84ac f7e99a44 f7e81638 f78f8494 c044d83a c2279fb0 0000000a c2279f10
Call Trace:
[<c0328a3b>] device_del+0x1b/0x1c0
[<c044ae91>] usb_disconnect+0xb1/0x120
[<c044d83a>] hub_thread+0x3ca/0xe00
[<c0120ab1>] __activate_task+0x21/0x40
[<c01238af>] try_to_wake_up+0x3f/0x420
[<c013c680>] autoremove_wake_function+0x0/0x50
[<c044d470>] hub_thread+0x0/0xe00
[<c013c5cc>] kthread+0xec/0xf0
[<c013c4e0>] kthread+0x0/0xf0
[<c0103be7>] kernel_thread_helper+0x7/0x10
=======================
Code: 04 89 46 04 89 4a 04 89 11 c6 03 01 8b 1c 24 8b 74 24 04 83 c4 08 c3 83 ec 0c 89 7c 24 08 89 c7 89 1c 24 89 74 24 04 8b 18 89 d8 <8b> 73 10 e8 e4 29 00 00 89 f8 e8 ad fe ff ff 85 c0 b8 00 00 00
EIP: [<c060f5e4>] klist_del+0x14/0x50 SS:ESP 0068:c2279ea4


--
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html

Attachment: oops5.bz2
Description: Binary data