Problem with 1.3.69 and NCR53c810

Jeremy Fitzhardinge (jeremy@zip.com.au)
Thu, 29 Feb 1996 11:00:40 +1100


Hi all,

I just booted 1.3.69, and all seemed well. After a while however,
a number of bad-looking messages started appearing in the syslog
(attached below).

My system is a 486dx2/66, PCI, 32MB of memory, NCR53c810 SCSI controller,
two Fujitsu 500MB drives, ESS688 soundcard. Kernel compiled with gcc
2.7.2, -fno-strength-reduce.

At the time of the crash I was connected with ppp to my ISP, running
netscape, and also running the Quake test.

The system was still working (no obvious hangs or crashes, but I think
xquake was blocked in IO), so I halted immediately and rebooted. fsck
noted some filesystems were dirty, but found no problems.

J

--
Here's the syslog: boot time first, then the actual problem.
Sorry about the line-wrap, but I'm have to use Netscape's mailer and
I can't make it stop.

Feb 29 10:29:25 ixodes linux: scsi-ncr53c7,8xx : at PCI bus 0, device 1, function 0 Feb 29 10:29:25 ixodes linux: scsi-ncr53c7,8xx : NCR53c810 at memory 0xffbfff00, io 0xfc00, irq 9 Feb 29 10:29:25 ixodes linux: scsi0 : burst length 8 Feb 29 10:29:26 ixodes linux: scsi0 : reset ccf to 3 from 0 Feb 29 10:29:26 ixodes linux: scsi0 : NCR code relocated to 0x23c5fc (virt 0x0023c5fc) Feb 29 10:29:26 ixodes linux: scsi0 : test 1 started Feb 29 10:29:26 ixodes linux: scsi0 : NCR53c{7,8}xx (rel 17) Feb 29 10:29:26 ixodes linux: scsi : 1 host. Feb 29 10:29:26 ixodes linux: scsi0 : target 0 accepting asynchronous SCSI Feb 29 10:29:26 ixodes linux: scsi0 : setting target 0 to asynchronous SCSI Feb 29 10:29:26 ixodes linux: Vendor: FUJITSU Model: M2624S-512 Rev: 0405 Feb 29 10:29:26 ixodes linux: Type: Direct-Access ANSI SCSI revision: 02 Feb 29 10:29:26 ixodes linux: Detected scsi disk sda at scsi0, channel 0, id 0, lun 0 Feb 29 10:29:27 ixodes linux: scsi0 : target 1 accepting asynchronous SCSI Feb 29 10:29:27 ixodes linux: scsi0 : setting target 1 to asynchronous SCSI Feb 29 10:29:27 ixodes linux: Vendor: FUJITSU Model: M2624S-512 Rev: 0303 Feb 29 10:29:27 ixodes linux: Type: Direct-Access ANSI SCSI revision: 01 CCS Feb 29 10:29:27 ixodes linux: Detected scsi disk sdb at scsi0, channel 0, id 1, lun 0 Feb 29 10:29:27 ixodes linux: scsi : detected 2 SCSI disks total. Feb 29 10:29:27 ixodes linux: SCSI Hardware sector size is 512 bytes on device sda Feb 29 10:29:27 ixodes linux: SCSI Hardware sector size is 512 bytes on device sdb [...] Feb 29 10:39:43 ixodes linux: scsi : aborting command due to timeout : pid 9403, scsi0, channel 0, id 1, lun 0 0x08 00 f8 2d 10 00 Feb 29 10:39:43 ixodes linux: scsi0 : DANGER : command running, can not abort. Feb 29 10:39:44 ixodes linux: scsi : aborting command due to timeout : pid 9406, scsi0, channel 0, id 1, lun 0 0x08 00 f8 45 02 00 Feb 29 10:39:44 ixodes linux: scsi0 : found command 9406 in Linux issue queue Feb 29 10:39:44 ixodes linux: scsi : aborting command due to timeout : pid 9405, scsi0, channel 0, id 1, lun 0 0x08 00 f8 3d 08 00 Feb 29 10:39:44 ixodes linux: scsi0 : found command 9405 in Linux issue queue Feb 29 10:39:55 ixodes linux: scsi : aborting command due to timeout : pid 9403, scsi0, channel 0, id 1, lun 0 0x08 00 f8 2d 10 00 Feb 29 10:39:55 ixodes linux: scsi0 : DANGER : command running, can not abort. Feb 29 10:39:55 ixodes linux: SCSI host 0 abort (pid 9403) timed out - resetting Feb 29 10:39:55 ixodes linux: SCSI bus is being reset for host 0. Feb 29 10:39:55 ixodes linux: scsi0 : DCMD|DBC=0x50000000, DNAD=0x23cc58 (virt 0x0023cc58) Feb 29 10:39:55 ixodes linux: DSA=0x8a1e8 (virt 0x0008a1e8) Feb 29 10:39:55 ixodes linux: DSPS=0x23cd68, TEMP=0x23c9b8 (virt 0x0023c9b8), DMODE=0x80 Feb 29 10:39:55 ixodes linux: SXFER=0x0, SCNTL3=0x3 Feb 29 10:39:55 ixodes linux: phase=MSGIN, 0 bytes in SCSI FIFO Feb 29 10:39:55 ixodes linux: STEST0=0x7 Feb 29 10:39:55 ixodes linux: scsi0 : DSP 0x23cc50 (virt 0x0023cc50) -> Feb 29 10:39:55 ixodes linux: 0x23cc50 (virt 0x0023cc50) : 0x50000000 0x0023cd68 (virt 0x0023cd68) Feb 29 10:39:55 ixodes linux: 0x23cc58 (virt 0x0023cc58) : 0x60000200 0x00000000 (virt 0x00000000) Feb 29 10:39:55 ixodes linux: 0x23cc60 (virt 0x0023cc60) : 0x78388000 0x00000000 (virt 0x00000000) Feb 29 10:39:55 ixodes linux: 0x23cc68 (virt 0x0023cc68) : 0x0f000001 0x0023c5d4 (virt 0x0023c5d4) Feb 29 10:39:55 ixodes linux: 0x23cc70 (virt 0x0023cc70) : 0x78389000 0x00000000 (virt 0x00000000) Feb 29 10:39:55 ixodes linux: 0x23cc78 (virt 0x0023cc78) : 0xc0000004 0x0023c5d0 (virt 0x0023c5d0) 0x0000fc34 (virt 0x0000fc34) Feb 29 10:39:55 ixodes linux: scsi0 : issue queue Feb 29 10:39:55 ixodes linux: scsi0 : dsa at phys 0x8c080 (virt 0x0008c080) Feb 29 10:39:55 ixodes linux: + 64 : dsa_msgout length = 2344416, data = 0x0 (virt 0x00000000) Feb 29 10:39:55 ixodes linux: + 60 : select_indirect = 0xc0000004 Feb 29 10:39:55 ixodes linux: + 56 : dsa_cmnd = 0x0 Feb 29 10:39:55 ixodes linux: + 48 : dsa_next = 0x0 Feb 29 10:39:55 ixodes linux: scsi0 : dsa at phys 0x230080 (virt 0x00230080) Feb 29 10:39:55 ixodes linux: + 64 : dsa_msgout length = 2344416, data = 0x0 (virt 0x00000000) Feb 29 10:39:55 ixodes linux: + 60 : select_indirect = 0xc0000004 Feb 29 10:39:56 ixodes linux: + 56 : dsa_cmnd = 0x0 Feb 29 10:39:56 ixodes linux: + 48 : dsa_next = 0x0 Feb 29 10:39:56 ixodes linux: scsi0 : schedule dsa array : Feb 29 10:39:56 ixodes linux: scsi0 : end schedule dsa array Feb 29 10:39:56 ixodes linux: scsi0 : reconnect_dsa_head : Feb 29 10:39:56 ixodes linux: scsi0 : end reconnect_dsa_head Feb 29 10:39:56 ixodes linux: The sti() implicit in a printk() prevents hangs Feb 29 10:39:56 ixodes linux: scsi : aborting command due to timeout : pid 9406, scsi0, channel 0, id 1, lun 0 0x08 00 f8 45 02 00 Feb 29 10:39:56 ixodes linux: scsi0 : did this command ever run? Feb 29 10:39:56 ixodes linux: scsi0 : target 1 accepting asynchronous SCSI Feb 29 10:39:56 ixodes linux: scsi0 : setting target 1 to asynchronous SCSI Feb 29 10:39:56 ixodes linux: scsi : aborting command due to timeout : pid 9405, scsi0, channel 0, id 1, lun 0 0x08 00 f8 3d 08 00 Feb 29 10:39:56 ixodes linux: scsi0 : dropped interrupt for command 9405 Feb 29 10:39:56 ixodes linux: SCSI host 0 abort (pid 9405) timed out - resetting Feb 29 10:39:56 ixodes linux: SCSI bus is being reset for host 0. Feb 29 10:39:56 ixodes linux: scsi0 : DCMD|DBC=0x50000000, DNAD=0x23cc58 (virt 0x0023cc58) Feb 29 10:39:56 ixodes linux: DSA=0x8c1e8 (virt 0x0008c1e8) Feb 29 10:39:56 ixodes linux: DSPS=0x23cd68, TEMP=0x23c7bc (virt 0x0023c7bc), DMODE=0x80 Feb 29 10:39:56 ixodes linux: SXFER=0x0, SCNTL3=0x3 Feb 29 10:39:56 ixodes linux: phase=MSGIN, 0 bytes in SCSI FIFO Feb 29 10:39:56 ixodes linux: STEST0=0x7 Feb 29 10:39:56 ixodes linux: scsi0 : DSP 0x23cc50 (virt 0x0023cc50) -> Feb 29 10:39:56 ixodes linux: 0x23cc50 (virt 0x0023cc50) : 0x50000000 0x0023cd68 (virt 0x0023cd68) Feb 29 10:39:56 ixodes linux: 0x23cc58 (virt 0x0023cc58) : 0x60000200 0x00000000 (virt 0x00000000) Feb 29 10:39:56 ixodes linux: 0x23cc60 (virt 0x0023cc60) : 0x78388000 0x00000000 (virt 0x00000000) Feb 29 10:39:56 ixodes linux: 0x23cc68 (virt 0x0023cc68) : 0x0f000001 0x0023c5d4 (virt 0x0023c5d4) Feb 29 10:39:56 ixodes linux: 0x23cc70 (virt 0x0023cc70) : 0x78389000 0x00000000 (virt 0x00000000) Feb 29 10:39:56 ixodes linux: 0x23cc78 (virt 0x0023cc78) : 0xc0000004 0x0023c5d0 (virt 0x0023c5d0) 0x0000fc34 (virt 0x0000fc34) Feb 29 10:39:56 ixodes linux: scsi0 : connected (SDID=0x1, SSID=0x81) Feb 29 10:39:56 ixodes linux: scsi0 : dsa at phys 0x8c1e8 (virt 0x0008c1e8) Feb 29 10:39:56 ixodes linux: + 64 : dsa_msgout length = 1, data = 0x8c02c (virt 0x0008c02c) Feb 29 10:39:57 ixodes linux: c0 Feb 29 10:39:57 ixodes linux: + 60 : select_indirect = 0x3010000 Feb 29 10:39:57 ixodes linux: + 56 : dsa_cmnd = 0x23a504 result = 0xffff, target = 1, lun = 0, cmd = 0x08 00 f8 45 02 00 Feb 29 10:39:57 ixodes linux: + 48 : dsa_next = 0x0 Feb 29 10:39:57 ixodes linux: scsi0 target 1 : sxfer_sanity = 0x0, scntl3_sanity = 0x3 Feb 29 10:39:57 ixodes linux: script : 0x78030300 0x0 0x78050000 0x0 0x90080000 0x0 0x0 0x0 Feb 29 10:39:57 ixodes linux: scsi0 : saved data pointer at offset 0 Feb 29 10:39:57 ixodes linux: scsi0 : can't determine active data pointer offset Feb 29 10:39:57 ixodes linux: 0x23c850 (virt 0x0023c850) : 0x820b0000 0x0023c848 (virt 0x0023c848) Feb 29 10:39:57 ixodes linux: 0x23c858 (virt 0x0023c858) : 0x8f0b0000 0x0023ca00 (virt 0x0023ca00) Feb 29 10:39:57 ixodes linux: scsi0 : issue queue Feb 29 10:39:57 ixodes linux: scsi0 : dsa at phys 0x88080 (virt 0x00088080) Feb 29 10:39:57 ixodes linux: + 64 : dsa_msgout length = 2344416, data = 0x0 (virt 0x00000000) Feb 29 10:39:57 ixodes linux: + 60 : select_indirect = 0xc0000004 Feb 29 10:39:57 ixodes linux: + 56 : dsa_cmnd = 0x0 Feb 29 10:39:57 ixodes linux: + 48 : dsa_next = 0x0 Feb 29 10:39:57 ixodes linux: scsi0 : schedule dsa array : Feb 29 10:39:57 ixodes linux: scsi0 : end schedule dsa array Feb 29 10:39:57 ixodes linux: scsi0 : reconnect_dsa_head : Feb 29 10:39:57 ixodes linux: scsi0 : dsa at phys 0x8c1e8 (virt 0x0008c1e8) Feb 29 10:39:57 ixodes linux: + 64 : dsa_msgout length = 1, data = 0x8c02c (virt 0x0008c02c) Feb 29 10:39:57 ixodes linux: c0 Feb 29 10:39:57 ixodes linux: + 60 : select_indirect = 0x3010000 Feb 29 10:39:58 ixodes linux: + 56 : dsa_cmnd = 0x23a504 result = 0xffff, target = 1, lun = 0, cmd = 0x08 00 f8 45 02 00 Feb 29 10:39:58 ixodes linux: + 48 : dsa_next = 0x0 Feb 29 10:39:58 ixodes linux: scsi0 target 1 : sxfer_sanity = 0x0, scntl3_sanity = 0x3 Feb 29 10:39:58 ixodes linux: script : 0x78030300 0x0 0x78050000 0x0 0x90080000 0x0 0x0 0x0 Feb 29 10:39:58 ixodes linux: scsi0 : saved data pointer at offset 0 Feb 29 10:39:58 ixodes linux: scsi0 : can't determine active data pointer offset Feb 29 10:39:58 ixodes linux: 0x23c850 (virt 0x0023c850) : 0x820b0000 0x0023c848 (virt 0x0023c848) Feb 29 10:39:58 ixodes linux: 0x23c858 (virt 0x0023c858) : 0x8f0b0000 0x0023ca00 (virt 0x0023ca00) Feb 29 10:39:58 ixodes linux: scsi0 : end reconnect_dsa_head Feb 29 10:39:58 ixodes linux: The sti() implicit in a printk() prevents hangs Feb 29 10:39:58 ixodes linux: scsi0 : target 1 accepting asynchronous SCSI Feb 29 10:39:58 ixodes linux: scsi0 : setting target 1 to asynchronous SCSI Feb 29 10:39:58 ixodes linux: scsi0 : target 0 accepting asynchronous SCSI Feb 29 10:39:58 ixodes linux: scsi0 : setting target 0 to asynchronous SCSI Feb 29 10:39:59 ixodes linux: scsi : aborting command due to timeout : pid 9403, scsi0, channel 0, id 1, lun 0 0x08 00 f8 2d 10 00 Feb 29 10:40:00 ixodes linux: scsi0 : did this command ever run? Feb 29 10:40:35 ixodes halt: halted by jeremy: for maintenance; bounce, bounce Feb 29 10:40:36 ixodes syslogd: exiting on signal 15