ide-scsi bug hard locks machine

ide-scsi bug hard locks machine

[Jason Thomas]

Hi all, heres the kernel logs from my machine which hard locks.

If you need more info email me directly I'm not subscribed.

Thanks.

ptelnet log 1: 13 Jan 2003  1:51 pm
ide-scsi: abort called for 2
bad: scheduling while atomic!
Call Trace: [<c0116941>]  [<c0109b76>]  [<c01169b0>]  [<c011a5db>]  [<c0109d8c>]  [<c0263100>]  [<c0262730>]  [<c026848c>]  [<c0268390>]  [<c0261e63>]  [<c0261cc0>]  [<c0261c80>]  [<c026225d>]  [<c02622d7>]  [<c0262b65>]  [<c0109d97>]  [<c0262c89>]  [<c0262bb0>]  [<c0108be9>] 
hde: lost interrupt
ide-scsi: CoD != 0 in idescsi_pc_intr
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }
ide-scsi: reset called for 2
------------[ cut here ]------------
kernel BUG at drivers/scsi/ide-scsi.c:483!
invalid operand: 0000
CPU:    0
EIP:    0060:[<c0267845>]    Not tainted
EFLAGS: 00010286
eax: c024ab50   ebx: c0448d84   ecx: edae15ed   edx: 0000d002
esi: d7c96800   edi: d7c93bc0   ebp: 00000000   esp: d7c4be5c
ds: 007b   es: 007b   ss: 0068
Process scsi_eh_0 (pid: 11, threadinfo=d7c4a000 task=d7c498c0)
Stack: 0000d002 c0448d84 00000008 00000080 0000001e c0448d84 c0448d84 00000000 
       d7d023c0 c0246bd9 c0448d84 d7c93bc0 00000000 00000088 0000001e d7c4a000 
       d7d023c0 c0448d84 d7d7eb80 c0246dd9 c0448d84 d7d023c0 c0448e2c d7c4a000 
Call Trace: [<c0246bd9>]  [<c0246dd9>]  [<c02474ff>]  [<c0247443>]  [<c026825b>]  [<c0261dbc>]  [<c0261cc0>]  [<c0261c80>]  [<c026225d>]  [<c0262417>]  [<c02622d7>]  [<c0262b79>]  [<c0109d97>]  [<c0262c89>]  [<c0262bb0>]  [<c0108be9>] 
Code: 0f 0b e3 01 2b 07 38 c0 8b 57 38 a1 c8 d0 42 c0 89 d1 29 c1 
 hde: ATAPI reset complete
hde: status error: status=0x48 { DriveReady DataRequest }
hde: drive not ready for command
hde: lost interrupt
ide-scsi: CoD != 0 in idescsi_pc_intr
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }
hde: ATAPI reset complete
hde: irq timeout: status=0xc0 { Busy }

-- 
Jason Thomas                           Phone:  +61 2 6257 7111
Unix System Administrator              Fax:    +61 2 6257 7311
tSA Consulting Group Pty. Ltd.         Mobile: 0418 29 66 81
1 Hall Street Lyneham ACT 2602         http://www.topic.com.au/

Re: ide-scsi bug hard locks machine

[Alan Cox]

On Mon, 2003-01-13 at 03:07, Jason Thomas wrote:
> ide-scsi: abort called for 2
> bad: scheduling while atomic!
> Call Trace: [<c0116941>]  [<c0109b76>]  [<c01169b0>]  [<c011a5db>]  [<c0109d8c>]  [<c0263100>]  [<c0262730>]  [<c026848c>]  [<c0268390>]  [<c0261e63>]  [<c0261cc0>]  [<c0261c80>]  [<c026225d>]  [<c02622d7>]  [<c0262b65>]  [<c0109d97>]  [<c0262c89>]  [<c0262bb0>]  [<c0108be9>] 
> hde: lost interrupt
> ide-scsi: CoD != 0 in idescsi_pc_intr
> hde: ATAPI reset complete
> hde: irq timeout: status=0xc0 { Busy }
> hde: ATAPI reset complete
> hde: irq timeout: status=0xc0 { Busy }
> hde: ATAPI reset complete
> hde: irq timeout: status=0xc0 { Busy }
> ide-scsi: reset called for 2

Someone broke ide-scsi in 2.5. I've not had time to investigate why yet. If you 
need ide-scsi use 2.4.

Re: ide-scsi bug hard locks machine

[Jason Thomas]

Some more info for those interested, it seems to have started in 2.5.53
with the implementation of the mid level api.

heres a trace from 2.5.53 maybe this will make it easier to find.

Unable to handle kernel NULL pointer dereference at virtual address 00000030
 printing eip:
c0252ba3
*pde = 00000000
Oops: 0000
CPU:    0
EIP:    0060:[<c0252ba3>]    Not tainted
EFLAGS: 00010086
EIP is at idescsi_abort+0x73/0x110
eax: 00000002   ebx: 00000086   ecx: d7cc5200   edx: 00000000
esi: d7c3e000   edi: d7c3e000   ebp: 00000007   esp: d7c3ff60
ds: 0068   es: 0068   ss: 0068
Process scsi_eh_0 (pid: 9, threadinfo=d7c3e000 task=d7d1a680)
Stack: c030dee0 00000002 d7c3e000 d7c8c980 c040e1ec d7c3e000 00000202 d7c3e000
       00000000 c024c7a2 d7cc5200 d7cc5200 d7cdf180 d7c3e000 c024c8c8 d7cc5200
       d7cdf180 d7cdf180 d7c3ffd4 c024d165 d7cc5200 d7cdf180 c0109d97 d7cc5200
Call Trace:
 [<c024c7a2>] scsi_try_to_abort_cmd+0x62/0x80
 [<c024c8c8>] scsi_eh_abort_cmd+0x38/0x70
 [<c024d165>] scsi_unjam_host+0xa5/0xf0
 [<c0109d97>] __down_failed_interruptible+0x7/0xc
 [<c024d289>] scsi_error_handler+0xd9/0x110
 [<c024d1b0>] scsi_error_handler+0x0/0x110
 [<c0108bfd>] kernel_thread_helper+0x5/0x18

Code: 39 42 30 74 67 8b 44 24 10 83 c0 10 89 04 24 e8 69 55 fc ff
 <6>note: scsi_eh_0[9] exited with preempt_count 2


On Mon, Jan 13, 2003 at 03:02:53PM +0000, Alan Cox wrote:
> Someone broke ide-scsi in 2.5. I've not had time to investigate why yet. If you 
> need ide-scsi use 2.4.

Re: ide-scsi bug hard locks machine

[Jason Thomas]

so after some coaching, below should be some useful information.

I have a liteon 48x cd burner connected to a promise controller using
ide-scsi, this currently works fine on 2.5.52. I think this also
happened with 2.5.54 and I gave up on that one. The output below is from
2.5.56.

Thanks


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

Error (regular_file): read_ksyms stat /proc/ksyms failed
No modules in ksyms, skipping objects
No ksyms, skipping lsmod
Call Trace: [<c0116941>]  [<c0109b76>]  [<c01169b0>]  [<c011a5db>]  [<c0109d8c>]  [<c0263100>]  [<c0262730>]  [<c026848c>]  [<c0268390>]  [<c0261e63>]  [<c0261cc0>]  [<c0261c80>]  [<c026225d>]  [<c02622d7>]  [<c0262b65>]  [<c0109d97>]  [<c0262c89>]  [<c0262bb0>]  [<c0108be9>] 
Warning (Oops_read): Code line not seen, dumping what data is available


Trace; c0116941 <schedule+2f1/300>
Trace; c0109b76 <__down+96/100>
Trace; c01169b0 <default_wake_function+0/40>
Trace; c011a5db <call_console_drivers+5b/120>
Trace; c0109d8c <__down_failed+8/c>
Trace; c0263100 <.text.lock.scsi_error+2d/6d>
Trace; c0262730 <scsi_sleep_done+0/20>
Trace; c026848c <idescsi_abort+fc/110>
Trace; c0268390 <idescsi_abort+0/110>
Trace; c0261e63 <scsi_send_eh_cmnd+153/1a0>
Trace; c0261cc0 <scsi_eh_done+0/50>
Trace; c0261c80 <scsi_eh_times_out+0/40>
Trace; c026225d <scsi_eh_tur+9d/d0>
Trace; c02622d7 <scsi_eh_abort_cmd+47/70>
Trace; c0262b65 <scsi_unjam_host+a5/f0>
Trace; c0109d97 <__down_failed_interruptible+7/c>
Trace; c0262c89 <scsi_error_handler+d9/110>
Trace; c0262bb0 <scsi_error_handler+0/110>
Trace; c0108be9 <kernel_thread_helper+5/c>

kernel BUG at drivers/scsi/ide-scsi.c:483!
invalid operand: 0000
CPU:    0
EIP:    0060:[<c0267845>]    Not tainted
Using defaults from ksymoops -t elf32-i386 -a i386
EFLAGS: 00010286
eax: c024ab50   ebx: c0448d84   ecx: edae15ed   edx: 0000d002
esi: d7c96800   edi: d7c93bc0   ebp: 00000000   esp: d7c4be5c
ds: 007b   es: 007b   ss: 0068
Stack: 0000d002 c0448d84 00000008 00000080 0000001e c0448d84 c0448d84 00000000 
       d7d023c0 c0246bd9 c0448d84 d7c93bc0 00000000 00000088 0000001e d7c4a000 
       d7d023c0 c0448d84 d7d7eb80 c0246dd9 c0448d84 d7d023c0 c0448e2c d7c4a000 
Call Trace: [<c0246bd9>]  [<c0246dd9>]  [<c02474ff>]  [<c0247443>]  [<c026825b>]  [<c0261dbc>]  [<c0261cc0>]  [<c0261c80>]  [<c026225d>]  [<c0262417>]  [<c02622d7>]  [<c0262b79>]  [<c0109d97>]  [<c0262c89>]  [<c0262bb0>]  [<c0108be9>] 
Code: 0f 0b e3 01 2b 07 38 c0 8b 57 38 a1 c8 d0 42 c0 89 d1 29 c1 


>>EIP; c0267845 <idescsi_transfer_pc+a5/110>   <=====

>>eax; c024ab50 <atapi_reset_pollfunc+0/120>
>>ebx; c0448d84 <ide_hwifs+f64/4998>

Trace; c0246bd9 <start_request+f9/1b0>
Trace; c0246dd9 <ide_do_request+f9/210>
Trace; c02474ff <ide_do_drive_cmd+9f/100>
Trace; c0247443 <ide_init_drive_cmd+23/40>
Trace; c026825b <idescsi_queue+1eb/320>
Trace; c0261dbc <scsi_send_eh_cmnd+ac/1a0>
Trace; c0261cc0 <scsi_eh_done+0/50>
Trace; c0261c80 <scsi_eh_times_out+0/40>
Trace; c026225d <scsi_eh_tur+9d/d0>
Trace; c0262417 <scsi_eh_bus_device_reset+77/c0>
Trace; c02622d7 <scsi_eh_abort_cmd+47/70>
Trace; c0262b79 <scsi_unjam_host+b9/f0>
Trace; c0109d97 <__down_failed_interruptible+7/c>
Trace; c0262c89 <scsi_error_handler+d9/110>
Trace; c0262bb0 <scsi_error_handler+0/110>
Trace; c0108be9 <kernel_thread_helper+5/c>

Code;  c0267845 <idescsi_transfer_pc+a5/110>
00000000 <_EIP>:
Code;  c0267845 <idescsi_transfer_pc+a5/110>   <=====
   0:   0f 0b                     ud2a      <=====
Code;  c0267847 <idescsi_transfer_pc+a7/110>
   2:   e3 01                     jecxz  5 <_EIP+0x5>
Code;  c0267849 <idescsi_transfer_pc+a9/110>
   4:   2b 07                     sub    (%edi),%eax
Code;  c026784b <idescsi_transfer_pc+ab/110>
   6:   38 c0                     cmp    %al,%al
Code;  c026784d <idescsi_transfer_pc+ad/110>
   8:   8b 57 38                  mov    0x38(%edi),%edx
Code;  c0267850 <idescsi_transfer_pc+b0/110>
   b:   a1 c8 d0 42 c0            mov    0xc042d0c8,%eax
Code;  c0267855 <idescsi_transfer_pc+b5/110>
  10:   89 d1                     mov    %edx,%ecx
Code;  c0267857 <idescsi_transfer_pc+b7/110>
  12:   29 c1                     sub    %eax,%ecx


1 warning and 1 error issued.  Results may not be reliable.



output from lspci:

00:00.0 Host bridge: VIA Technologies, Inc. VT8363/8365 [KT133/KM133] (rev 03)
00:01.0 PCI bridge: VIA Technologies, Inc. VT8363/8365 [KT133/KM133 AGP]
00:07.0 ISA bridge: VIA Technologies, Inc. VT82C686 [Apollo Super South] (rev 40)
00:07.2 USB Controller: VIA Technologies, Inc. USB (rev 1a)
00:07.3 USB Controller: VIA Technologies, Inc. USB (rev 1a)
00:07.4 Bridge: VIA Technologies, Inc. VT82C686 [Apollo Super ACPI] (rev 40)
00:09.0 Ethernet controller: Digital Equipment Corporation DECchip 21140 [FasterNet] (rev 22)
00:0a.0 Unknown mass storage controller: Promise Technology, Inc. 20267 (rev 02)
00:0b.0 Multimedia video controller: Brooktree Corporation Bt878 Video Capture (rev 02)
00:0b.1 Multimedia controller: Brooktree Corporation Bt878 Audio Capture (rev 02)
00:0c.0 Multimedia audio controller: Creative Labs SB Live! EMU10k1 (rev 08)
00:0c.1 Input device controller: Creative Labs SB Live! MIDI/Game Port (rev 08)
01:00.0 VGA compatible controller: nVidia Corporation NV6 [Vanta] (rev 15)


-- 
Jason Thomas                           Phone:  +61 2 6257 7111
Unix System Administrator              Fax:    +61 2 6257 7311
tSA Consulting Group Pty. Ltd.         Mobile: 0418 29 66 81
1 Hall Street Lyneham ACT 2602         http://www.topic.com.au/