2.6.0-test5 & test6 cd burning/scheduler/ide-scsi.c bug

From: AG
Date: Thu Oct 02 2003 - 20:28:23 EST

Next message: Matt Mackall: "[RFC] [PATCH 3/3] netconsole using netpoll"
Previous message: Michael Still: "[Patch] Documentation tweaks to remove build errors"
Next in thread: Zwane Mwaikambo: "Re: 2.6.0-test5 & test6 cd burning/scheduler/ide-scsi.c bug"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

Hi

This is my first bug submission, so please have patience with my noobness :)

Here goes:

Kernel test5 and test6 appear to have a bug which prevents at least some systems from burning CDs correctly. It manifests itself in kernel lockups (requiring a hard reset) in test6 and burner application lockups in test5 on x86 hardware.

Hardware in both instances is different; burners were tested in Windows, BeOS and in kernels 2.4.20 and 2.4.22 and everything was found to work correctly, so it's not likely to be a hardware bug. One system is using ext3 and a 24x burner (the system), the other system is using reiserfs and a 4x HP CD-Writer+ 8200a (the Duron system).

The 24x system under test5 showed k3b, Gtoaster and cdrecord all locking up at random points during the burn and the system required a reboot for the drive to work again;; under test6 the system locked up entirely requiring a hard reset.

The system with the 4x burner was upgraded from test3 and hardlocks every time during the burn. The cdwriter appeared to be alive, showing a red light when the eject button was pushed, indicating that the tray was locked as it should be.

Neither system under test6 would respond to any commands and required hard resets.

Under kernels 2.4.20, 2.4.22 and 2.6.0-test3 the same installations will burn cds with no difficulties.

The 24x system using k3b showed a wildly varying buffer value during the burn, which is unusual.

A search of the lkml archives revealed nothing relevant to our knowledge. After some discussion and testing we were able to obtain a log showing the crash, which is below.

-------
Crash log which shows the ide-scsi.c BUG at line 493;

/crash log from machine with 24x burner

Sep 30 22:09:53 sdchomelinux kernel: hdd: irq timeout: status=0xd0 { Busy }
Sep 30 22:09:53 sdchomelinux kernel: hdd: DMA disabled
Sep 30 22:09:53 sdchomelinux kernel: ide-scsi: abort called for 5687
Sep 30 22:09:53 sdchomelinux kernel: Debug: sleeping function called from invalid context at include/asm/semaphore.h:119
Sep 30 22:09:53 sdchomelinux kernel: in_atomic():1, irqs_disabled():1
Sep 30 22:09:53 sdchomelinux kernel: Call Trace:
Sep 30 22:09:53 sdchomelinux kernel: [<c0117a9a>] __might_sleep+0x9c/0xb6
Sep 30 22:09:53 sdchomelinux kernel: [<c0274d5d>] scsi_sleep+0x6c/0x89
Sep 30 22:09:53 sdchomelinux kernel: [<c0274cdd>] scsi_sleep_done+0x0/0x14
Sep 30 22:09:53 sdchomelinux kernel: [<d886c859>] idescsi_abort+0xee/0xf7 [ide_scsi]
Sep 30 22:09:53 sdchomelinux kernel: [<c027460c>] scsi_try_to_abort_cmd+0x65/0x80
Sep 30 22:09:53 sdchomelinux kernel: [<c0274732>] scsi_eh_abort_cmds+0x40/0x74
Sep 30 22:09:53 sdchomelinux kernel: [<c0275159>] scsi_unjam_host+0xa5/0xcb
Sep 30 22:09:53 sdchomelinux kernel: [<c027525f>] scsi_error_handler+0xe0/0x11c
Sep 30 22:09:53 sdchomelinux kernel: [<c027517f>] scsi_error_handler+0x0/0x11c
Sep 30 22:09:53 sdchomelinux kernel: [<c0107345>] kernel_thread_helper+0x5/0xb
Sep 30 22:09:53 sdchomelinux kernel:
Sep 30 22:09:53 sdchomelinux kernel: bad: scheduling while atomic!
Sep 30 22:09:53 sdchomelinux kernel: Call Trace:
Sep 30 22:09:53 sdchomelinux kernel: [<c0116661>] schedule+0x58c/0x591
Sep 30 22:09:53 sdchomelinux kernel: [<c011a5a0>] printk+0x11d/0x17b
Sep 30 22:09:53 sdchomelinux kernel: [<c01081c2>] __down+0x91/0x107
Sep 30 22:09:53 sdchomelinux kernel: [<c01166b6>] default_wake_function+0x0/0x2e
Sep 30 22:09:53 sdchomelinux kernel: [<c01098b9>] dump_stack+0x1e/0x22
Sep 30 22:09:53 sdchomelinux kernel: [<c01083eb>] __down_failed+0xb/0x14
Sep 30 22:09:53 sdchomelinux kernel: [<c02754bf>] .text.lock.scsi_error+0x37/0x48
Sep 30 22:09:53 sdchomelinux kernel: [<c0274cdd>] scsi_sleep_done+0x0/0x14
Sep 30 22:09:53 sdchomelinux kernel: [<d886c859>] idescsi_abort+0xee/0xf7 [ide_scsi]
Sep 30 22:09:53 sdchomelinux kernel: [<c027460c>] scsi_try_to_abort_cmd+0x65/0x80
Sep 30 22:09:53 sdchomelinux kernel: [<c0274732>] scsi_eh_abort_cmds+0x40/0x74
Sep 30 22:09:53 sdchomelinux kernel: [<c0275159>] scsi_unjam_host+0xa5/0xcb
Sep 30 22:09:53 sdchomelinux kernel: [<c027525f>] scsi_error_handler+0xe0/0x11c
Sep 30 22:09:53 sdchomelinux kernel: [<c027517f>] scsi_error_handler+0x0/0x11c
Sep 30 22:09:53 sdchomelinux kernel: [<c0107345>] kernel_thread_helper+0x5/0xb
Sep 30 22:09:53 sdchomelinux kernel:
Sep 30 22:09:53 sdchomelinux kernel: hdd: ATAPI reset complete
Sep 30 22:09:53 sdchomelinux kernel: hdd: irq timeout: status=0xc0 { Busy }
Sep 30 22:09:53 sdchomelinux kernel: hdd: ATAPI reset complete
Sep 30 22:09:53 sdchomelinux kernel: hdd: irq timeout: status=0xc0 { Busy }
Sep 30 22:09:55 sdchomelinux kernel: ide-scsi: reset called for 5687

Sep 30 22:09:55 sdchomelinux kernel: ------------[ cut here ]------------
Sep 30 22:09:55 sdchomelinux kernel: kernel BUG at drivers/scsi/ide-scsi.c:493!
Sep 30 22:09:55 sdchomelinux kernel: invalid operand: 0000 [#1]
Sep 30 22:09:55 sdchomelinux kernel: CPU: 0
Sep 30 22:09:55 sdchomelinux kernel: EIP: 0060:[<d886ba9c>] Not tainted
Sep 30 22:09:55 sdchomelinux kernel: EFLAGS: 00010286
Sep 30 22:09:55 sdchomelinux kernel: EIP is at idescsi_transfer_pc+0x9c/0x120 [ide_scsi]
Sep 30 22:09:55 sdchomelinux kernel: eax: c025fc53 ebx: c047eafc ecx: 72c79a8e edx: 00000172
Sep 30 22:09:55 sdchomelinux kernel: esi: cfa1fb80 edi: d7687ba4 ebp: d789fdf4 esp: d789fdd0
Sep 30 22:09:55 sdchomelinux kernel: ds: 007b es: 007b ss: 0068

Sep 30 22:09:55 sdchomelinux kernel: Process scsi_eh_1 (pid: 2369, threadinfo=d789e000 task=d78a1900)
Sep 30 22:09:55 sdchomelinux kernel: Stack: 00000172 c047eafc 00000008 00000080 0000001e d7687ba4 c047eafc d6f9b580
Sep 30 22:09:55 sdchomelinux kernel: 00000000 d789fe20 c025c38b c047eafc cfa1fb80 00000000 00000000 0000001e
Sep 30 22:09:55 sdchomelinux kernel: 0000000f d6f9b580 c047eafc c047e8bc d789fe50 c025c685 c047eafc d6f9b580
Sep 30 22:09:55 sdchomelinux kernel: Call Trace:
Sep 30 22:09:55 sdchomelinux kernel: [<c025c38b>] start_request+0x179/0x27b
Sep 30 22:09:55 sdchomelinux kernel: [<c025c685>] ide_do_request+0x1d5/0x34e
Sep 30 22:09:55 sdchomelinux kernel: [<c0248a7e>] __elv_add_request+0x27/0x38
Sep 30 22:09:55 sdchomelinux kernel: [<c025ce66>] ide_do_drive_cmd+0xd0/0x132
Sep 30 22:09:55 sdchomelinux kernel: [<c01166e0>] default_wake_function+0x2a/0x2e
Sep 30 22:09:55 sdchomelinux kernel: [<d886c31c>] idescsi_queue+0x1e1/0x630 [ide_scsi]
Sep 30 22:09:55 sdchomelinux kernel: [<c02742c2>] scsi_send_eh_cmnd+0xa7/0x189
Sep 30 22:09:55 sdchomelinux kernel: [<c02741d1>] scsi_eh_done+0x0/0x4a
Sep 30 22:09:55 sdchomelinux kernel: [<c02741b0>] scsi_eh_times_out+0x0/0x21
Sep 30 22:09:55 sdchomelinux kernel: [<c02746bb>] scsi_eh_tur+0x94/0xcb
Sep 30 22:09:55 sdchomelinux kernel: [<c027493e>] scsi_eh_bus_device_reset+0x145/0x177
Sep 30 22:09:55 sdchomelinux kernel: [<c02746c5>] scsi_eh_tur+0x9e/0xcb
Sep 30 22:09:55 sdchomelinux kernel: [<c0274ff8>] scsi_eh_ready_devs+0x28/0x74
Sep 30 22:09:55 sdchomelinux kernel: [<c0275176>] scsi_unjam_host+0xc2/0xcb
Sep 30 22:09:55 sdchomelinux kernel: [<c027525f>] scsi_error_handler+0xe0/0x11c
Sep 30 22:09:55 sdchomelinux kernel: [<c027517f>] scsi_error_handler+0x0/0x11c
Sep 30 22:09:55 sdchomelinux kernel: [<c0107345>] kernel_thread_helper+0x5/0xb
Sep 30 22:09:55 sdchomelinux kernel:
Sep 30 22:09:55 sdchomelinux kernel: Code: 0f 0b ed 01 69 cf 86 d8 8b 56 38 a1 00 9d 3c c0 89 d1 29 c1
Sep 30 22:09:55 sdchomelinux kernel: hdd: ATAPI reset complete

/end log

------

An IRC log of our conversation in which we discovered this is available.

No workarounds as of yet or other info.

DarkHills and Cyber Daemon of #cola on infrared.oftc.net

contact email: agroz@xxxxxxxxxxxxx

Info section:

Kernel versions: 2.6.0-test5 and 2.6.0-test6
Processors: Duron 1200 and P4 1.5GHz
Modules loaded: Duron system:
system:
/proc/ioports:
Duron

bash-2.05b# cat /proc/ioports
0000-001f : dma1
0020-0021 : pic1
0040-005f : timer
0060-006f : keyboard
0080-008f : dma page reg
00a0-00a1 : pic2
00c0-00df : dma2
00f0-00ff : fpu
0170-0177 : ide1
01f0-01f7 : ide0
02f8-02ff : serial
0330-0331 : MPU401 UART
0376-0376 : ide1
0378-037a : parport0
037b-037f : parport0
0388-0389 : OPL2/3 (left)
038a-038b : OPL2/3 (right)
03c0-03df : vga+
03f6-03f6 : ide0
03f8-03ff : serial
0cf8-0cff : PCI conf1
4000-40ff : 0000:00:07.4
5000-500f : 0000:00:07.4
6000-607f : 0000:00:07.4
d000-d00f : 0000:00:07.1
d000-d007 : ide0
d008-d00f : ide1
d400-d41f : 0000:00:07.2
d400-d41f : uhci-hcd
d800-d81f : 0000:00:07.3
d800-d81f : uhci-hcd
dc00-dcff : 0000:00:0f.0
dc00-dcff : 8139too
e000-e0ff : 0000:00:11.0
e000-e0ff : CMI8738

P4:
stephen@sdchomelinux stephen $ cat /proc/ioports 0000-001f : dma1
0020-0021 : pic1
0040-005f : timer
0060-006f : keyboard
0070-0077 : rtc
0080-008f : dma page reg
00a0-00a1 : pic2
00c0-00df : dma2
00f0-00ff : fpu
0170-0177 : ide1
01f0-01f7 : ide0
02f8-02ff : serial
0376-0376 : ide1
0378-037a : parport0
037b-037f : parport0
03c0-03df : vga+
03f6-03f6 : ide0
03f8-03ff : serial
0500-050f : 0000:00:1f.3
0cf8-0cff : PCI conf1
c000-c01f : 0000:02:03.0
c000-c01f : ne2k-pci
c400-c4ff : 0000:02:04.0
c400-c4ff : 8139too
d000-d01f : 0000:00:1f.2
d000-d01f : uhci-hcd
d800-d81f : 0000:00:1f.4
d800-d81f : uhci-hcd
f000-f00f : 0000:00:1f.1
f000-f007 : ide0
f008-f00f : ide1

/proc/iomem:

Duron

bash-2.05b# cat /proc/iomem
00000000-0009fbff : System RAM
0009fc00-0009ffff : reserved
000a0000-000bffff : Video RAM area
000c0000-000c7fff : Video ROM
000f0000-000fffff : System ROM
00100000-1ffeffff : System RAM
00100000-0037e9b2 : Kernel code
0037e9b3-004590ff : Kernel data
1fff0000-1fff2fff : ACPI Non-volatile Storage
1fff3000-1fffffff : ACPI Tables
d0000000-d7ffffff : PCI Bus #01
d0000000-d7ffffff : 0000:01:00.0
d0000000-d1ffffff : rivafb
d8000000-dbffffff : 0000:00:00.0
dc000000-ddffffff : PCI Bus #01
dc000000-dcffffff : 0000:01:00.0
dc000000-dcffffff : rivafb
df000000-df0000ff : 0000:00:0f.0
df000000-df0000ff : 8139too
df001000-df001fff : 0000:00:10.0
df002000-df002fff : 0000:00:10.1
ffff0000-ffffffff : reserved

P4:
stephen@sdchomelinux stephen $ cat /proc/iomem
00000000-0009fbff : System RAM
0009fc00-0009ffff : reserved
000a0000-000bffff : Video RAM area
000c0000-000c7fff : Video ROM
000f0000-000fffff : System ROM
00100000-17feffff : System RAM
00100000-003693dc : Kernel code
003693dd-0044857f : Kernel data
17ff0000-17ff2fff : ACPI Non-volatile Storage
17ff3000-17ffffff : ACPI Tables
e0000000-e3ffffff : 0000:00:00.0
e4000000-ebffffff : PCI Bus #01
e4000000-e7ffffff : 0000:01:00.0
e8000000-e807ffff : 0000:01:00.0
ec000000-edffffff : PCI Bus #01
ec000000-ecffffff : 0000:01:00.0
ef000000-ef0fffff : 0000:02:01.0
ef100000-ef1000ff : 0000:02:04.0
ef100000-ef1000ff : 8139too
ef101000-ef101fff : 0000:02:01.0
fec00000-fec00fff : reserved
fee00000-fee00fff : reserved
ffb00000-ffffffff : reserved

PCI info: Duron

lspci not installed

P4:
lspci not installed

/proc/scsi/scsi Duron

bash-2.05b# cat /proc/scsi/scsi
Attached devices:
Host: scsi0 Channel: 00 Id: 00 Lun: 00
Vendor: HP Model: CD-Writer+ 8200a Rev: 1.0f
Type: CD-ROM ANSI SCSI revision: 02

P4:
sdchomelinux root # cat /proc/scsi/scsi Attached devices:
Host: scsi0 Channel: 00 Id: 00 Lun: 00
Vendor: Model: DVD-ROM Rev: 2.00
Type: CD-ROM ANSI SCSI revision: 02
Host: scsi1 Channel: 00 Id: 00 Lun: 00
Vendor: TDK Model: CDRW241040B Rev: 57S4
Type: CD-ROM ANSI SCSI revision: 02

ver_linux script results:

Duron

Linux AMDbox 2.6.0-test6 #2 Sun Sep 28 15:31:35 MDT 2003 i686 AMD Athlon(tm) Processor AuthenticAMD GNU/Linux

Gnu C 3.2.3
Gnu make 3.80
util-linux 2.11z
mount 2.11z
e2fsprogs 1.33
reiserfsprogs 3.6.8
Linux C Library 2.3.2
Dynamic linker (ldd) 2.3.2
Procps 3.1.9
Net-tools 1.60
Kbd 1.06
Sh-utils 2.0.15
Modules Loaded se401 videodev ide_scsi nvidia 8139too mii crc32 sg sr_mod sd_mod scsi_mod

P4:
Linux sdchomelinux 2.6.0-test6 #2 Tue Sep 30 21:44:42 CDT 2003 i686 Intel(R) Pentium(R) 4 CPU 1.50GHz GenuineIntel GNU/Linux

Gnu C 3.2.3
Gnu make 3.80
util-linux 2.11z
mount 2.11z
e2fsprogs 1.33
Linux C Library 2.3.2
Dynamic linker (ldd) 2.3.2
Procps 3.1.9
Net-tools 1.60
Kbd 1.06
Sh-utils 5.0
Modules Loaded ipt_TOS ipt_MASQUERADE ipt_REJECT ipt_LOG ipt_state ip_nat_irc ip_nat_ftp ip_conntrack_irc ip_conntrack_ftp ipt_multiport ipt_conntrack iptable_filter iptable_mangle iptable_nat ip_conntrack ip_tables rtc joydev 8139too mii ne2k_pci 8390 crc32 ide_scsi sr_mod

Duron environment is KDE 3.1.2
P4 environment is Fluxbox 0.1.14
-----------------------------------------
*end cut and paste section*

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

Next message: Matt Mackall: "[RFC] [PATCH 3/3] netconsole using netpoll"
Previous message: Michael Still: "[Patch] Documentation tweaks to remove build errors"
Next in thread: Zwane Mwaikambo: "Re: 2.6.0-test5 & test6 cd burning/scheduler/ide-scsi.c bug"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]