Bug#288998: Kernel crash with USB storage device
Package: kernel-image-2.6.8-1-k7
Version: 2.6.8-10
Tested with an AMD Duron 650/VIA KT266 and an AMD Athlon 1.33C/VIA KT 133A.
This applies also to kernel-image-2.4.27-1-k7 version 2.4.27-6
When I have a USB stick connected at boot time the kernel seems to enter an
infinite loop:
---start----------------------------------------------------------------
Jan 5 06:41:15 stephan kernel: SCSI device sda: 250880 512-byte hdwr sectors
(128 MB)
Jan 5 06:41:15 stephan kernel: sda: assuming Write Enabled
Jan 5 06:41:15 stephan kernel: /dev/scsi/host0/bus0/target0/lun0: p1
Jan 5 06:41:15 stephan kernel: Attached scsi removable disk sda at scsi0,
channel 0, id 0, lun 0
Jan 5 06:41:17 stephan kernel: lp0: using parport0 (interrupt-driven).
Jan 5 06:41:22 stephan kernel: usb 1-1: control timeout on ep0in
Jan 5 06:41:27 stephan kernel: usb 1-1: control timeout on ep0in
Jan 5 06:41:32 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 06:41:48 stephan last message repeated 3 times
Jan 5 06:41:53 stephan kernel: usb 1-1: control timeout on ep0in
Jan 5 06:42:18 stephan last message repeated 5 times
Jan 5 06:42:23 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 06:42:28 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 06:42:31 stephan shutdown[1018]: shutting down for system reboot
---end------------------------------------------------------------------
When I connect the device to the running system and try to mount it
(mount /dev/sda1 /mnt) the kernel seems to do something bad and the system is
locked up:
---start----------------------------------------------------------------
Jan 5 07:18:40 stephan kernel: usb 1-1: new full speed USB device using
address 3
Jan 5 07:18:40 stephan kernel: scsi0 : SCSI emulation for USB Mass Storage
devices
Jan 5 07:18:40 stephan kernel: Vendor: Model: USB MP3
Rev: 1.02
Jan 5 07:18:40 stephan kernel: Type: Direct-Access
ANSI SCSI revision: 02
Jan 5 07:18:46 stephan kernel: usb 1-1: control timeout on ep0in
Jan 5 07:18:51 stephan kernel: usb 1-1: control timeout on ep0in
Jan 5 07:18:56 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 07:19:01 stephan kernel: NET: Registered protocol family 4
Jan 5 07:19:01 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 07:19:01 stephan kernel: NET: Registered protocol family 3
Jan 5 07:19:02 stephan kernel: NET: Registered protocol family 5
Jan 5 07:19:06 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 07:19:11 stephan kernel: usb 1-2: control timeout on ep0in
Jan 5 07:19:37 stephan kernel: usb 1-2: USB disconnect, address 2
Jan 5 07:19:41 stephan kernel: usb 1-2: new low speed USB device using
address 4
Jan 5 07:19:45 stephan kernel: apm: BIOS version 1.2 Flags 0x07 (Driver
version 1.16ac)
Jan 5 07:19:45 stephan kernel: apm: overridden by ACPI.
Jan 5 07:19:46 stephan kernel: usb 1-2: control timeout on ep0out
Jan 5 07:19:49 stephan kernel: apm: BIOS version 1.2 Flags 0x07 (Driver
version 1.16ac)
Jan 5 07:19:49 stephan kernel: apm: overridden by ACPI.
Jan 5 07:19:51 stephan kernel: usb 1-2: control timeout on ep0out
Jan 5 07:19:52 stephan kernel: usb 1-2: new low speed USB device using
address 5
Jan 5 07:19:57 stephan kernel: usb 1-2: control timeout on ep0out
Jan 5 07:20:02 stephan kernel: usb 1-2: control timeout on ep0out
Jan 5 07:20:02 stephan kernel: usb 1-1: USB disconnect, address 3
Jan 5 07:20:02 stephan kernel: scsi: Device offlined - not ready after error
recovery: host 0 channel 0 id 0 lun 0
Jan 5 07:20:02 stephan kernel: Badness in scsi_device_set_state at
drivers/scsi/scsi_lib.c:1643
Jan 5 07:20:02 stephan kernel: [__crc_get_wchan+5175177/6133658]
scsi_device_set_state+0xc6/0x120 [scsi_mod]
Jan 5 07:20:02 stephan kernel: [__crc_get_wchan+5165255/6133658]
scsi_eh_offline_sdevs+0x64/0x80 [scsi_mod]
Jan 5 07:20:02 stephan kernel: [__crc_get_wchan+5166831/6133658]
scsi_unjam_host+0xcc/0x200 [scsi_mod]
Jan 5 07:20:02 stephan kernel: [default_wake_function+0/32]
default_wake_function+0x0/0x20
Jan 5 07:20:02 stephan kernel: [__crc_get_wchan+5167398/6133658]
scsi_error_handler+0x103/0x1c0 [scsi_mod]
Jan 5 07:20:02 stephan kernel: [__crc_get_wchan+5167139/6133658]
scsi_error_handler+0x0/0x1c0 [scsi_mod]
Jan 5 07:20:02 stephan kernel: [kernel_thread_helper+5/20]
kernel_thread_helper+0x5/0x14
Jan 5 07:20:05 stephan kernel: apm: BIOS version 1.2 Flags 0x07 (Driver
version 1.16ac)
Jan 5 07:20:05 stephan kernel: apm: overridden by ACPI.
Jan 5 07:20:32 stephan kernel: scsi: Device offlined - not ready after error
recovery: host 0 channel 0 id 0 lun 0
Jan 5 07:20:32 stephan kernel: Badness in scsi_device_set_state at
drivers/scsi/scsi_lib.c:1643
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5175177/6133658]
scsi_device_set_state+0xc6/0x120 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5165255/6133658]
scsi_eh_offline_sdevs+0x64/0x80 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5166831/6133658]
scsi_unjam_host+0xcc/0x200 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [default_wake_function+0/32]
default_wake_function+0x0/0x20
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5167398/6133658]
scsi_error_handler+0x103/0x1c0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5167139/6133658]
scsi_error_handler+0x0/0x1c0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [kernel_thread_helper+5/20]
kernel_thread_helper+0x5/0x14
Jan 5 07:20:32 stephan kernel: c0200233
Jan 5 07:20:32 stephan kernel: PREEMPT
Jan 5 07:20:32 stephan kernel: Modules linked in: sd_mod appletalk ax25 ipx
nvidia lp ipv6 mousedev iptable_mangle iptable_filter ipt_state ipt_limit
ipt_LOG ip_conntrack_irc ip_conntrack_ftp ipt_MASQUERADE iptable_nat
ip_conntrack ip_tables xfrm_user ipcomp esp4 ah4 af_key agpgart parport_pc
parport emu10k1_gp gameport nls_iso8859_1 nls_cp437 dm_mod capability
commoncap tulip ppp_deflate zlib_deflate ppp_async ppp_generic slhc crc_ccitt
ne2k_pci 8390 crc32 uhci_hcd usb_storage scsi_mod snd_emu10k1_synth
snd_emux_synth snd_seq_virmidi snd_seq_midi_emul snd_seq_oss
snd_seq_midi_event snd_seq snd_emu10k1 snd_rawmidi snd_pcm_oss snd_mixer_oss
snd_pcm snd_timer snd_seq_device snd_ac97_codec snd_page_alloc snd_util_mem
snd_hwdep snd soundcore psmouse usbmouse usbkbd usbcore rtc ntfs reiserfs
isofs vfat fat ext2 ext3 jbd mbcache ide_generic ide_disk via82cxxx ide_core
unix font vesafb cfbcopyarea cfbimgblt cfbfillrect
Jan 5 07:20:32 stephan kernel: CPU: 0
Jan 5 07:20:32 stephan kernel: EIP: 0060:[as_requeue_request+131/224]
Tainted: P
Jan 5 07:20:32 stephan kernel: EFLAGS: 00010083 (2.6.8-1-k7)
Jan 5 07:20:32 stephan kernel: EIP is at as_requeue_request+0x83/0xe0
Jan 5 07:20:32 stephan kernel: eax: 00000000 ebx: 00000000 ecx: c15cd02c
edx: dd8e1eb0
Jan 5 07:20:32 stephan kernel: esi: dd8e1eb0 edi: df4e9aa0 ebp: 00000202
esp: da71bee0
Jan 5 07:20:32 stephan kernel: ds: 007b es: 007b ss: 0068
Jan 5 07:20:32 stephan kernel: Process scsi_eh_0 (pid: 5370,
threadinfo=da71a000 task=de0094a0)
Jan 5 07:20:32 stephan kernel: Stack: c012c2ee 00000000 00000000 c0106dcd
dd8e1eb0 dbdbec00 c15cd02c c01f7a66
Jan 5 07:20:32 stephan kernel: c15cd02c dd8e1eb0 dd8e1eb0 dbdbec00
c01fa30c c15cd02c dd8e1eb0 c31440a8
Jan 5 07:20:32 stephan kernel: 00000286 c3144040 dbdbec00 c16cbe00
00001057 e0bc6189 c15cd02c dd8e1eb0
Jan 5 07:20:32 stephan kernel: Call Trace:
Jan 5 07:20:32 stephan kernel: [__kernel_text_address+46/64]
__kernel_text_address+0x2e/0x40
Jan 5 07:20:32 stephan kernel: [print_context_stack+45/112]
print_context_stack+0x2d/0x70
Jan 5 07:20:32 stephan kernel: [elv_requeue_request+38/80]
elv_requeue_request+0x26/0x50
Jan 5 07:20:32 stephan kernel: [blk_insert_request+220/224]
blk_insert_request+0xdc/0xe0
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5168380/6133658]
scsi_queue_insert+0x89/0xd0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5166500/6133658]
scsi_eh_flush_done_q+0x71/0xf0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5166779/6133658]
scsi_unjam_host+0x98/0x200 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [default_wake_function+0/32]
default_wake_function+0x0/0x20
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5167398/6133658]
scsi_error_handler+0x103/0x1c0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [__crc_get_wchan+5167139/6133658]
scsi_error_handler+0x0/0x1c0 [scsi_mod]
Jan 5 07:20:32 stephan kernel: [kernel_thread_helper+5/20]
kernel_thread_helper+0x5/0x14
Jan 5 07:20:32 stephan kernel: Code: 8b 10 89 72 04 89 16 89 46 04 89 30 8b
74 24 14 8b 5c 24 10
Jan 5 07:20:32 stephan kernel: <6>note: scsi_eh_0[5370] exited with
preempt_count 1
Jan 5 07:20:34 stephan shutdown[5479]: shutting down for system reboot
---end------------------------------------------------------------------
--
__________________________________
Stephan Trebs stacit@freenet.de
Reply to: