SCSI Bus error with NCR card (1.3.68)

Jeremy A. Gilbert (grath@gryphon.ccs.brandeis.edu)
Mon, 26 Feb 1996 18:13:17 -0500 (EST)


All:

I am running 1.3.68 on a 90 Mhz pentium with 16 MB ram, standard IDE
and a NCR SCSI board. Using the grep-inode feature of lde (linux disk
editor), I was just able to cause a rather spectacular problem.
Apparently, a bus error was genereated on the SCSI chain and the kernel
tried several times to reset it. After about three attempts, it suceeded
and the program terminated normally. I have never had any strange or
erronenous SCSI errors before with any version of the kernel. This is
also the first time I have used lde, but my understanding is that this
kind of error should not occur under any circumstances.

Is is possible this is because I have circa 1989 hard drive
connected on my scsi chain? If so, why would it bring the bus down when I
read from a completely different disk?

The command I used was grep-indode "Richard" /dev/sda4

Please contact me if you want more information about my
configuration or the errrors generated. Is there anyone in particular who
I should forward this to?

Here is the syslog output:

Feb 26 17:51:33 gryphon kernel: scsi0 channel 0 : resetting for second half of retries.
Feb 26 17:52:05 gryphon kernel: SCSI bus is being reset for host 0.
Feb 26 17:52:05 gryphon kernel: scsi0 : DCMD|DBC=0x50000000, DNAD=0x250c58 (virt 0x00250c58)
Feb 26 17:52:05 gryphon kernel: DSA=0x161e8 (virt 0x000161e8)
Feb 26 17:52:05 gryphon kernel: DSPS=0x250d68, TEMP=0x1604c (virt 0x0001604c), DMODE=0x88
Feb 26 17:52:05 gryphon kernel: SXFER=0x0, SCNTL3=0x3
Feb 26 17:52:05 gryphon kernel: phase=MSGIN, 0 bytes in SCSI FIFO
Feb 26 17:52:05 gryphon kernel: STEST0=0x7
Feb 26 17:52:05 gryphon kernel: scsi0 : DSP 0x250c50 (virt 0x00250c50) ->
Feb 26 17:52:05 gryphon kernel: 0x250c50 (virt 0x00250c50) : 0x50000000 0x00250d68 (virt 0x00250d68)
Feb 26 17:52:05 gryphon kernel: 0x250c58 (virt 0x00250c58) : 0x60000200 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c60 (virt 0x00250c60) : 0x78388800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c68 (virt 0x00250c68) : 0x0f000001 0x002505d4 (virt 0x002505d4)
Feb 26 17:52:05 gryphon kernel: 0x250c70 (virt 0x00250c70) : 0x78389800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c78 (virt 0x00250c78) : 0xc0000004 0x002505d0 (virt 0x002505d0) 0x00006034 (virt 0x00006034)
Feb 26 17:52:05 gryphon kernel: scsi0 : issue queue
Feb 26 17:52:05 gryphon kernel: scsi0 : dsa at phys 0x80080 (virt 0x00080080)
Feb 26 17:52:05 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:05 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:05 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:05 gryphon kernel: scsi0 : schedule dsa array :
Feb 26 17:52:05 gryphon kernel: scsi0 : end schedule dsa array
Feb 26 17:52:05 gryphon kernel: scsi0 : reconnect_dsa_head :
Feb 26 17:52:05 gryphon kernel: scsi0 : end reconnect_dsa_head
Feb 26 17:52:05 gryphon kernel: scsi0: lost command 952532
Feb 26 17:52:05 gryphon kernel: scsi0 : target 0 accepting asynchronous SCSI
Feb 26 17:52:08 gryphon afpd[24997]: session from 28.6:249 on 65051.159:133
Feb 26 17:52:18 gryphon afpd[24997]: login noauth
Feb 26 17:52:08 gryphon afpd[24996]: session from 28.6:249 on 65051.159:131
Feb 26 17:52:05 gryphon kernel: scsi0 : setting target 0 to asynchronous SCSI
Feb 26 17:52:05 gryphon kernel: scsi : aborting command due to timeout : pid 952534, scsi0, channel 0, id 0, lun 0 Read (6) 1b 24 6c 02 00
Feb 26 17:52:05 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:05 gryphon kernel: SCSI disk error : host 0 channel 0 id 0 lun 0 return code = 18000002
Feb 26 17:52:05 gryphon kernel: Current error sd08:04: sense key Medium Error
Feb 26 17:52:05 gryphon kernel: Additional sense indicates Unrecovered read error
Feb 26 17:52:05 gryphon kernel: scsidisk I/O error: dev 08:04, sector 4928
Feb 26 17:52:05 gryphon kernel: scsi0 channel 0 : resetting for second half of retries.
Feb 26 17:52:05 gryphon kernel: SCSI bus is being reset for host 0.
Feb 26 17:52:05 gryphon kernel: scsi0 : DCMD|DBC=0x50000000, DNAD=0x250c58 (virt 0x00250c58)
Feb 26 17:52:05 gryphon kernel: DSA=0x1a1e8 (virt 0x0001a1e8)
Feb 26 17:52:05 gryphon kernel: DSPS=0x250d68, TEMP=0x1a04c (virt 0x0001a04c), DMODE=0x88
Feb 26 17:52:05 gryphon kernel: SXFER=0x0, SCNTL3=0x3
Feb 26 17:52:05 gryphon kernel: phase=MSGIN, 0 bytes in SCSI FIFO
Feb 26 17:52:05 gryphon kernel: STEST0=0x7
Feb 26 17:52:05 gryphon kernel: scsi0 : DSP 0x250c50 (virt 0x00250c50) ->
Feb 26 17:52:05 gryphon kernel: 0x250c50 (virt 0x00250c50) : 0x50000000 0x00250d68 (virt 0x00250d68)
Feb 26 17:52:05 gryphon kernel: 0x250c58 (virt 0x00250c58) : 0x60000200 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c60 (virt 0x00250c60) : 0x78388800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c68 (virt 0x00250c68) : 0x0f000001 0x002505d4 (virt 0x002505d4)
Feb 26 17:52:05 gryphon kernel: 0x250c70 (virt 0x00250c70) : 0x78389800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c78 (virt 0x00250c78) : 0xc0000004 0x002505d0 (virt 0x002505d0) 0x00006034 (virt 0x00006034)
Feb 26 17:52:05 gryphon kernel: scsi0 : issue queue
Feb 26 17:52:05 gryphon kernel: scsi0 : dsa at phys 0x16080 (virt 0x00016080)
Feb 26 17:52:05 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:05 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:05 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:05 gryphon kernel: scsi0 : dsa at phys 0x80080 (virt 0x00080080)
Feb 26 17:52:05 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:05 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:05 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:05 gryphon kernel: scsi0 : schedule dsa array :
Feb 26 17:52:05 gryphon kernel: scsi0 : end schedule dsa array
Feb 26 17:52:05 gryphon kernel: scsi0 : reconnect_dsa_head :
Feb 26 17:52:05 gryphon kernel: scsi0 : end reconnect_dsa_head
Feb 26 17:52:05 gryphon kernel: scsi0: lost command 952554
Feb 26 17:52:05 gryphon kernel: scsi0 : target 0 accepting asynchronous SCSI
Feb 26 17:52:05 gryphon kernel: scsi0 : setting target 0 to asynchronous SCSI
Feb 26 17:52:05 gryphon kernel: SCSI disk error : host 0 channel 0 id 0 lun 0 return code = 18000002
Feb 26 17:52:05 gryphon kernel: Current error sd08:04: sense key Medium Error
Feb 26 17:52:05 gryphon kernel: Additional sense indicates Unrecovered read error
Feb 26 17:52:05 gryphon kernel: scsidisk I/O error: dev 08:04, sector 4930
Feb 26 17:52:05 gryphon kernel: scsi : aborting command due to timeout : pid 952557, scsi0, channel 0, id 0, lun 0 Read (6) 19 e3 fa 02 00
Feb 26 17:52:05 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:05 gryphon kernel: scsi : aborting command due to timeout : pid 952558, scsi0, channel 0, id 0, lun 0 Read (6) 0f aa d0 08 00
Feb 26 17:52:05 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:05 gryphon kernel: SCSI disk error : host 0 channel 0 id 0 lun 0 return code = 18000002
Feb 26 17:52:05 gryphon kernel: Current error sd08:04: sense key Medium Error
Feb 26 17:52:05 gryphon kernel: Additional sense indicates Unrecovered read error
Feb 26 17:52:05 gryphon kernel: scsidisk I/O error: dev 08:04, sector 4932
Feb 26 17:52:05 gryphon kernel: scsi0 channel 0 : resetting for second half of retries.
Feb 26 17:52:05 gryphon kernel: SCSI bus is being reset for host 0.
Feb 26 17:52:05 gryphon kernel: scsi0 : DCMD|DBC=0x50000000, DNAD=0x250c58 (virt 0x00250c58)
Feb 26 17:52:05 gryphon kernel: DSA=0x1a1e8 (virt 0x0001a1e8)
Feb 26 17:52:05 gryphon kernel: DSPS=0x250d68, TEMP=0x1a04c (virt 0x0001a04c), DMODE=0x88
Feb 26 17:52:05 gryphon kernel: SXFER=0x0, SCNTL3=0x3
Feb 26 17:52:05 gryphon kernel: phase=MSGIN, 0 bytes in SCSI FIFO
Feb 26 17:52:05 gryphon kernel: STEST0=0x7
Feb 26 17:52:05 gryphon kernel: scsi0 : DSP 0x250c50 (virt 0x00250c50) ->
Feb 26 17:52:05 gryphon kernel: 0x250c50 (virt 0x00250c50) : 0x50000000 0x00250d68 (virt 0x00250d68)
Feb 26 17:52:05 gryphon kernel: 0x250c58 (virt 0x00250c58) : 0x60000200 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c60 (virt 0x00250c60) : 0x78388800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c68 (virt 0x00250c68) : 0x0f000001 0x002505d4 (virt 0x002505d4)
Feb 26 17:52:05 gryphon kernel: 0x250c70 (virt 0x00250c70) : 0x78389800 0x00000000 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: 0x250c78 (virt 0x00250c78) : 0xc0000004 0x002505d0 (virt 0x002505d0) 0x00006034 (virt 0x00006034)
Feb 26 17:52:05 gryphon kernel: scsi0 : issue queue
Feb 26 17:52:05 gryphon kernel: scsi0 : dsa at phys 0x16080 (virt 0x00016080)
Feb 26 17:52:05 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:05 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:05 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:05 gryphon kernel: scsi0 : dsa at phys 0x80080 (virt 0x00080080)
Feb 26 17:52:05 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:05 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:05 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:05 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:05 gryphon kernel: scsi0 : schedule dsa array :
Feb 26 17:52:05 gryphon kernel: scsi0 : end schedule dsa array
Feb 26 17:52:05 gryphon kernel: scsi0 : reconnect_dsa_head :
Feb 26 17:52:05 gryphon kernel: scsi0 : end reconnect_dsa_head
Feb 26 17:52:05 gryphon kernel: scsi0: lost command 952568
Feb 26 17:52:05 gryphon kernel: scsi0 : target 0 accepting asynchronous SCSI
Feb 26 17:52:05 gryphon kernel: scsi0 : setting target 0 to asynchronous SCSI
Feb 26 17:52:05 gryphon kernel: SCSI disk error : host 0 channel 0 id 0 lun 0 return code = 18000002
Feb 26 17:52:05 gryphon kernel: Current error sd08:04: sense key Medium Error
Feb 26 17:52:05 gryphon kernel: Additional sense indicates Unrecovered read error
Feb 26 17:52:05 gryphon kernel: scsidisk I/O error: dev 08:04, sector 4934
Feb 26 17:52:05 gryphon kernel: scsi : aborting command due to timeout : pid 952571, scsi0, channel 0, id 0, lun 0 Write (6) 19 e3 fa 02 00
Feb 26 17:52:05 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:05 gryphon kernel: scsi : aborting command due to timeout : pid 952572, scsi0, channel 0, id 0, lun 0 Read (6) 1d 66 72 04 00
Feb 26 17:52:05 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:13 gryphon kernel: scsi0 channel 0 : resetting for second half of retries.
Feb 26 17:52:13 gryphon kernel: SCSI bus is being reset for host 0.
Feb 26 17:52:13 gryphon kernel: scsi0 : DCMD|DBC=0x50000000, DNAD=0x250c58 (virt 0x00250c58)
Feb 26 17:52:13 gryphon kernel: DSA=0x161e8 (virt 0x000161e8)
Feb 26 17:52:13 gryphon kernel: DSPS=0x250d68, TEMP=0x1604c (virt 0x0001604c), DMODE=0x88
Feb 26 17:52:13 gryphon kernel: SXFER=0x0, SCNTL3=0x3
Feb 26 17:52:13 gryphon kernel: phase=MSGIN, 0 bytes in SCSI FIFO
Feb 26 17:52:13 gryphon kernel: STEST0=0x7
Feb 26 17:52:13 gryphon kernel: scsi0 : DSP 0x250c50 (virt 0x00250c50) ->
Feb 26 17:52:13 gryphon kernel: 0x250c50 (virt 0x00250c50) : 0x50000000 0x00250d68 (virt 0x00250d68)
Feb 26 17:52:13 gryphon kernel: 0x250c58 (virt 0x00250c58) : 0x60000200 0x00000000 (virt 0x00000000)
Feb 26 17:52:13 gryphon kernel: 0x250c60 (virt 0x00250c60) : 0x78388800 0x00000000 (virt 0x00000000)
Feb 26 17:52:13 gryphon kernel: 0x250c68 (virt 0x00250c68) : 0x0f000001 0x002505d4 (virt 0x002505d4)
Feb 26 17:52:13 gryphon kernel: 0x250c70 (virt 0x00250c70) : 0x78389800 0x00000000 (virt 0x00000000)
Feb 26 17:52:13 gryphon kernel: 0x250c78 (virt 0x00250c78) : 0xc0000004 0x002505d0 (virt 0x002505d0) 0x00006034 (virt 0x00006034)
Feb 26 17:52:13 gryphon kernel: scsi0 : issue queue
Feb 26 17:52:13 gryphon kernel: scsi0 : dsa at phys 0x80080 (virt 0x00080080)
Feb 26 17:52:13 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:13 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:13 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:13 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:13 gryphon kernel: scsi0 : dsa at phys 0x1a080 (virt 0x0001a080)
Feb 26 17:52:13 gryphon kernel: + 64 : dsa_msgout length = 2426336, data = 0x0 (virt 0x00000000)
Feb 26 17:52:13 gryphon kernel: + 60 : select_indirect = 0xc0000004
Feb 26 17:52:13 gryphon kernel: + 56 : dsa_cmnd = 0x0
Feb 26 17:52:13 gryphon kernel: + 48 : dsa_next = 0x0
Feb 26 17:52:13 gryphon kernel: scsi0 : schedule dsa array :
Feb 26 17:52:13 gryphon kernel: scsi0 : end schedule dsa array
Feb 26 17:52:13 gryphon kernel: scsi0 : reconnect_dsa_head :
Feb 26 17:52:13 gryphon kernel: scsi0 : end reconnect_dsa_head
Feb 26 17:52:13 gryphon kernel: scsi0: lost command 952621
Feb 26 17:52:13 gryphon kernel: scsi0 : target 0 accepting asynchronous SCSI
Feb 26 17:52:13 gryphon kernel: scsi0 : setting target 0 to asynchronous SCSI
Feb 26 17:52:16 gryphon kernel: SCSI disk error : host 0 channel 0 id 0 lun 0 return code = 18000002
Feb 26 17:52:16 gryphon kernel: Current error sd08:04: sense key Medium Error
Feb 26 17:52:16 gryphon kernel: Additional sense indicates Unrecovered read error
Feb 26 17:52:16 gryphon kernel: scsidisk I/O error: dev 08:04, sector 5696
Feb 26 17:52:17 gryphon kernel: scsi : aborting command due to timeout : pid 952624, scsi0, channel 0, id 0, lun 0 Read (6) 1c 8b 42 02 00
Feb 26 17:52:17 gryphon kernel: scsi0 : did this command ever run?
Feb 26 17:52:17 gryphon kernel: scsi : aborting command due to timeout : pid 952625, scsi0, channel 0, id 0, lun 0 Read (6) 10 13 08 08 00