SCSI tape errors with pre2.0.[78]

William M. Perkins (bill@grnwood.grnwood.richmond.us.net)
Mon, 27 May 1996 18:03:01 -0400 (EDT)


When trying to do tape backups to a Conner 2150 tape drive using a
ncr53c810 controller using kernel-pre2.0.8, the following kernel
information was logged in /var/log/messages file. The command
used from the root directory was:

tar --create --one-file-system --same-owner --preserve-permissions\
--totals --file /dev/tape .

The dmesg showing the SCSI initialization:

> scsi-ncr53c7,8xx : at PCI bus 0, device 1, function 0
> scsi-ncr53c7,8xx : NCR53c810 at memory 0xffbfff00, io 0xfc00, irq 9
> scsi0 : burst length 8
> scsi0 : reset ccf to 3 from 0
> scsi0 : NCR code relocated to 0x2b0600 (virt 0x002b0600)
> scsi0 : test 1 started
> scsi0 : NCR53c{7,8}xx (rel 17)
> scsi : 1 host.
> scsi0 : target 0 accepting period 100ns offset 8 10.00MHz FAST SCSI-II
> scsi0 : setting target 0 to period 100ns offset 8 10.00MHz FAST SCSI-II
> Vendor: CONNER Model: CFP1060S 1.05GB Rev: 213C
> Type: Direct-Access ANSI SCSI revision: 02
> Detected scsi disk sda at scsi0, channel 0, id 0, lun 0
> scsi0 : target 1 accepting period 100ns offset 8 10.00MHz FAST SCSI-II
> scsi0 : setting target 1 to period 100ns offset 8 10.00MHz FAST SCSI-II
> Vendor: FUJITSU Model: M2684S-512 Rev: 2026
> Type: Direct-Access ANSI SCSI revision: 02
> Detected scsi disk sdb at scsi0, channel 0, id 1, lun 0
> scsi0 : target 2 accepting period 248ns offset 8 4.03MHz synchronous SCSI
> scsi0 : setting target 2 to period 248ns offset 8 4.03MHz synchronous SCSI
> Vendor: QUANTUM Model: PD210S Rev: 527_
> Type: Direct-Access ANSI SCSI revision: 02
> Detected scsi disk sdc at scsi0, channel 0, id 2, lun 0
> scsi0 : target 3 accepting period 236ns offset 8 4.23MHz synchronous SCSI
> scsi0 : setting target 3 to period 248ns offset 8 4.03MHz synchronous SCSI
> Vendor: TOSHIBA Model: CD-ROM XM-3401TA Rev: 2873
> Type: CD-ROM ANSI SCSI revision: 02
> Detected scsi CD-ROM sr0 at scsi0, channel 0, id 3, lun 0
> scsi0 : target 4 accepting asynchronous SCSI
> scsi0 : setting target 4 to asynchronous SCSI
> Vendor: CIPHER Model: ST150S2 Rev: G76M
> Type: Sequential-Access ANSI SCSI revision: 01
> Detected scsi tape st0 at scsi0, channel 0, id 4, lun 0
> scsi : detected 1 SCSI tape 1 SCSI cdrom 3 SCSI disks total.
> SCSI device sda: hdwr sector= 512 bytes. Sectors= 2074880 [1013 MB] [1.0 GB]
> SCSI device sdb: hdwr sector= 512 bytes. Sectors= 1039329 [507 MB] [0.5 GB]
> SCSI device sdc: hdwr sector= 512 bytes. Sectors= 408574 [199 MB] [0.2 GB]

I do not think this is a hardware problem. The same backup done
with pre2.0.6 had no problems using the same tape. This problem
also occurred with the pre2.0.7 kernel.

Finally, the /var/log/messages entries:

> May 27 14:53:29 grnwood kernel: scsi : aborting command due to timeout : pid 30220, scsi0, channel 0, id 0, lun 0 Write (6) 03 27 42 02 00
> May 27 14:53:29 grnwood kernel: scsi0 : DANGER : command running, can not abort.
> May 27 14:53:29 grnwood kernel: scsi : aborting command due to timeout : pid 30221, scsi0, channel 0, id 0, lun 0 Write (6) 03 28 0c 02 00
> May 27 14:53:29 grnwood kernel: scsi0 : found command 30221 in Linux issue queue
> May 27 14:53:29 grnwood kernel: scsi : aborting command due to timeout : pid 30222, scsi0, channel 0, id 0, lun 0 Write (6) 03 67 42 02 00
> May 27 14:53:29 grnwood kernel: scsi0 : found command 30222 in Linux issue queue
> May 27 14:53:45 grnwood kernel: scsi : aborting command due to timeout : pid 30220, scsi0, channel 0, id 0, lun 0 Write (6) 03 27 42 02 00
> May 27 14:53:45 grnwood kernel: scsi0 : DANGER : command running, can not abort.
> May 27 14:53:45 grnwood kernel: SCSI host 0 abort (pid 30220) timed out - resetting
> May 27 14:53:45 grnwood kernel: SCSI bus is being reset for host 0 channel 0.
> May 27 14:53:45 grnwood kernel: scsi0 : DCMD|DBC=0x820b0000, DNAD=0x2b085c (virt 0x002b085c)
> May 27 14:53:45 grnwood kernel: DSA=0x1c821e8 (virt 0x01c821e8)
> May 27 14:53:45 grnwood kernel: DSPS=0x2b084c, TEMP=0x1c820ac (virt 0x01c820ac), DMODE=0x80
> May 27 14:53:45 grnwood kernel: SXFER=0x0, SCNTL3=0x3
> May 27 14:53:46 grnwood kernel: BSY phase=CMDOUT, 0 bytes in SCSI FIFO
> May 27 14:53:46 grnwood kernel: STEST0=0x7
> May 27 14:53:46 grnwood kernel: scsi0 : DSP 0x2b0854 (virt 0x002b0854) ->
> May 27 14:53:46 grnwood kernel: 0x2b0854 (virt 0x002b0854) : 0x820b0000 0x002b084c (virt 0x002b084c)
> May 27 14:53:46 grnwood kernel: 0x2b085c (virt 0x002b085c) : 0x8f0b0000 0x002b0a04 (virt 0x002b0a04)
> May 27 14:53:46 grnwood kernel: 0x2b0864 (virt 0x002b0864) : 0x9e0b0000 0x00000000 (virt 0x00000000)
> May 27 14:53:46 grnwood kernel: 0x2b086c (virt 0x002b086c) : 0x800b0000 0x002b088c (virt 0x002b088c)
> May 27 14:53:46 grnwood kernel: 0x2b0874 (virt 0x002b0874) : 0x810b0000 0x002b08e4 (virt 0x002b08e4)
> May 27 14:53:46 grnwood kernel: 0x2b087c (virt 0x002b087c) : 0x830b0000 0x002b0c0c (virt 0x002b0c0c)
> May 27 14:53:46 grnwood kernel: scsi0 : connected (SDID=0x4, SSID=0x84)
> May 27 14:53:46 grnwood kernel: scsi0 : dsa at phys 0x1c821e8 (virt 0x01c821e8)
> May 27 14:53:46 grnwood kernel: + 64 : dsa_msgout length = 1, data = 0x1c8202c (virt 0x01c8202c)
> May 27 14:53:46 grnwood kernel: Identify disconnect allowed lun 0
> May 27 14:53:46 grnwood kernel: + 60 : select_indirect = 0x3040000
> May 27 14:53:46 grnwood kernel: + 56 : dsa_cmnd = 0x8410 result = 0xffff, target = 4, lun = 0, cmd = Request Sense 00 00 00 10 00
> May 27 14:53:46 grnwood kernel: + 48 : dsa_next = 0x0
> May 27 14:53:46 grnwood kernel: scsi0 target 4 : sxfer_sanity = 0x0, scntl3_sanity = 0x3
> May 27 14:53:46 grnwood kernel: script : 0x78030300 0x0 0x78050000 0x0 0x90080000 0x0 0x0 0x0
> May 27 14:53:47 grnwood kernel: scsi0 : saved data pointer at offset 0
> May 27 14:53:47 grnwood kernel: scsi0 : active data pointer at offset 0
> May 27 14:53:47 grnwood kernel: scsi0 : issue queue
> May 27 14:53:47 grnwood kernel: scsi0 : dsa at phys 0x1c4c080 (virt 0x01c4c080)
> May 27 14:53:47 grnwood kernel: + 64 : dsa_msgout length = 2819556, data = 0x0 (virt 0x00000000)
> May 27 14:53:47 grnwood kernel: + 60 : select_indirect = 0xc0000004
> May 27 14:53:47 grnwood kernel: + 56 : dsa_cmnd = 0x0
> May 27 14:53:47 grnwood kernel: + 48 : dsa_next = 0x0
> May 27 14:53:47 grnwood kernel: scsi0 : dsa at phys 0x1c8a080 (virt 0x01c8a080)
> May 27 14:53:47 grnwood kernel: + 64 : dsa_msgout length = 2819556, data = 0x0 (virt 0x00000000)
> May 27 14:53:47 grnwood kernel: + 60 : select_indirect = 0xc0000004
> May 27 14:53:47 grnwood kernel: + 56 : dsa_cmnd = 0x0
> May 27 14:53:47 grnwood kernel: + 48 : dsa_next = 0x0
> May 27 14:53:47 grnwood kernel: scsi0 : schedule dsa array :
> May 27 14:53:47 grnwood kernel: scsi0 : dsa at phys 0x1f8e080 (virt 0x01f8e080)
> May 27 14:53:47 grnwood kernel: + 64 : dsa_msgout length = 2819556, data = 0x2b2218 (virt 0x002b2218)
> May 27 14:53:47 grnwood kernel: + 60 : select_indirect = 0xc0000004
> May 27 14:53:48 grnwood kernel: + 56 : dsa_cmnd = 0x0
> May 27 14:53:48 grnwood kernel: + 48 : dsa_next = 0x0
> May 27 14:53:48 grnwood kernel: scsi0 : end schedule dsa array
> May 27 14:53:48 grnwood kernel: scsi0 : reconnect_dsa_head :
> May 27 14:53:48 grnwood kernel: scsi0 : end reconnect_dsa_head
> May 27 14:53:48 grnwood kernel: The sti() implicit in a printk() prevents hangs
> May 27 14:53:48 grnwood kernel: scsi : aborting command due to timeout : pid 30221, scsi0, channel 0, id 0, lun 0 Write (6) scsi0 : target 0 accepting period 100ns offset 8 10.00MHz FAST SCSI-II
> May 27 14:53:48 grnwood kernel: scsi0 : setting target 0 to period 100ns offset 8 10.00MHz FAST SCSI-II
> May 27 14:53:48 grnwood kernel: 03 28 0c 02 00
> May 27 14:53:48 grnwood kernel: scsi0 : did this command ever run?
> May 27 14:53:48 grnwood kernel: scsi : aborting command due to timeout : pid 30222, scsi0, channel 0, id 0, lun 0 Write (6) 03 67 42 02 00
> May 27 14:53:48 grnwood kernel: scsi0 : did this command ever run?

After this last message is logged, the shell from which the tar command
was issued, and tar drive are locked up and unusable. All other SCSI
devices are accessible and respend to commands.

Guess I will stick with pre2.0.6 to do my tape backups.

Bill

-- 
William M. Perkins                       Internet - wperkins@us.net
The Greenwood                               or    - bill@cais.com
Commodore is dead.  Long lives the Amiga!  (AmigaOS/Linux/NetBSD)