lkml.org 
[lkml]   [1996]   [Apr]   [9]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
/
From
SubjectRe: Backup to Exabyte aborts (1.3.??)
Date

Stephen Davies writes:

> For some time now I have been having apparently random failures of my
> file system backups to tape.
>
> Initially I thought that it might have been a media problem as I got
> better results if I backed up one disk at a time rather than doing
> all three to one tape but lately (1.3.78 - 1.3.84) I get failures on
> even quite small backups.
>
> The failures only seem to happen while backing up my scsi disk; I can
> backup more than 450 Mb from IDE drives but now cannot backup 300Mb
> from a scsi disk.
>
> Here is the console log from last night's effort:

[Console logs elided]


I too have been having problems with SCSI tape backup in the 1.3.X series
of kernels. I wouldn't call my tape backup failures random, however. They
are quite reproducible. My backups work without failure when running
kernel version 1.2.13.

I have a Conner 4326 4mm DAT and an NCR 53C810 SCSI controller. I too am
backing up from SCSI disks (Seagate and Micropolis). The tar command that
I use to do the backup hangs (it appears to be hanging on the first write
to tape) and cannot be killed. After a longish period of time (maybe 15 to
20 mins), I am able to manually restart the command and everything gets
backed up properly.

Here's the relevant portion of /var/log/syslog (kernel versino 1.3.85)...

Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97120, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf ad 10 00
Apr 9 07:54:40 redrock kernel: scsi0 : DANGER : command running, can not abort.
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97121, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf bd 18 00
Apr 9 07:54:40 redrock kernel: scsi0 : found command 97121 in Linux issue queue
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97122, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf d5 10 00
Apr 9 07:54:40 redrock kernel: scsi0 : found command 97122 in Linux issue queue
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97124, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 7c f9 c2 00 00 08 00
Apr 9 07:54:40 redrock kernel: scsi0 : DANGER : command running, can not abort.
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97120, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf ad 10 00
Apr 9 07:54:40 redrock kernel: scsi0 : DANGER : command running, can not abort.
Apr 9 07:54:40 redrock kernel: SCSI host 0 abort (pid 97120) timed out - resetting
Apr 9 07:54:40 redrock kernel: SCSI bus is being reset for host 0.
Apr 9 07:54:40 redrock kernel: scsi0 : DCMD|DBC=0x8004380, DNAD=0x13480 (virt 0x00013480)
Apr 9 07:54:40 redrock kernel: DSA=0x1eb41e8 (virt 0x01eb41e8)
Apr 9 07:54:40 redrock kernel: DSPS=0x10000, TEMP=0x88890 (virt 0x00088890), DMODE=0x80
Apr 9 07:54:40 redrock kernel: SXFER=0x0, SCNTL3=0x3
Apr 9 07:54:40 redrock kernel: BSY REQ phase=DATAOUT, 0 bytes in SCSI FIFO
Apr 9 07:54:40 redrock kernel: STEST0=0x7
Apr 9 07:54:40 redrock kernel: scsi0 : DSP 0x1eb4260 (virt 0x01eb4260) ->
Apr 9 07:54:40 redrock kernel: 0x1eb4260 (virt 0x01eb4260) : 0x08007800 0x00010000 (virt 0x00010000)
Apr 9 07:54:40 redrock kernel: 0x1eb4268 (virt 0x01eb4268) : 0x80080000 0x000889a8 (virt 0x000889a8)
Apr 9 07:54:40 redrock kernel: 0x1eb4270 (virt 0x01eb4270) : 0x09000400 0x014c5400 (virt 0x014c5400)
Apr 9 07:54:40 redrock kernel: 0x1eb4278 (virt 0x01eb4278) : 0x89030000 0x00088970 (virt 0x00088970)
Apr 9 07:54:40 redrock kernel: 0x1eb4280 (virt 0x01eb4280) : 0x09000400 0x014c5800 (virt 0x014c5800)
Apr 9 07:54:40 redrock kernel: 0x1eb4288 (virt 0x01eb4288) : 0x89030000 0x00088970 (virt 0x00088970)
Apr 9 07:54:40 redrock kernel: scsi0 : connected (SDID=0x5, SSID=0x80)
Apr 9 07:54:40 redrock kernel: scsi0 : dsa at phys 0x1eb41e8 (virt 0x01eb41e8)
Apr 9 07:54:40 redrock kernel: + 64 : dsa_msgout length = 1, data = 0x1eb402c (virt 0x01eb402c)
Apr 9 07:54:40 redrock kernel: Identify disconnect allowed lun 0
Apr 9 07:54:40 redrock kernel: + 60 : select_indirect = 0x3050000
Apr 9 07:54:40 redrock kernel: + 56 : dsa_cmnd = 0x877f8 result = 0xffff, target = 5, lun = 0, cmd = Write (6) 01 00 00 3c 00
Apr 9 07:54:40 redrock kernel: + 48 : dsa_next = 0x0
Apr 9 07:54:40 redrock kernel: scsi0 target 5 : sxfer_sanity = 0x0, scntl3_sanity = 0x3
Apr 9 07:54:40 redrock kernel: script : 0x78030300 0x0 0x78050000 0x0 0x90080000 0x0 0x0 0x0
Apr 9 07:54:40 redrock kernel: scsi0 : saved data pointer at offset 0
Apr 9 07:54:40 redrock kernel: scsi0 : active data pointer at offset 493992
Apr 9 07:54:40 redrock kernel: scsi0 : issue queue
Apr 9 07:54:40 redrock kernel: scsi0 : dsa at phys 0xfee080 (virt 0x00fee080)
Apr 9 07:54:40 redrock kernel: + 64 : dsa_msgout length = 558560, data = 0x0 (virt 0x00000000)
Apr 9 07:54:40 redrock kernel: + 60 : select_indirect = 0xc0000004
Apr 9 07:54:40 redrock kernel: + 56 : dsa_cmnd = 0x0
Apr 9 07:54:40 redrock kernel: + 48 : dsa_next = 0x0
Apr 9 07:54:40 redrock kernel: scsi0 : dsa at phys 0x10d8080 (virt 0x010d8080)
Apr 9 07:54:40 redrock kernel: + 64 : dsa_msgout length = 558560, data = 0x0 (virt 0x00000000)
Apr 9 07:54:40 redrock kernel: + 60 : select_indirect = 0xc0000004
Apr 9 07:54:40 redrock kernel: + 56 : dsa_cmnd = 0x0
Apr 9 07:54:40 redrock kernel: + 48 : dsa_next = 0x0
Apr 9 07:54:40 redrock kernel: scsi0 : schedule dsa array :
Apr 9 07:54:40 redrock kernel: scsi0 : dsa at phys 0xa7c080 (virt 0x00a7c080)
Apr 9 07:54:40 redrock kernel: + 64 : dsa_msgout length = 558560, data = 0x8a214 (virt 0x0008a214)
Apr 9 07:54:40 redrock kernel: + 60 : select_indirect = 0xc0000004
Apr 9 07:54:40 redrock kernel: + 56 : dsa_cmnd = 0x0
Apr 9 07:54:40 redrock kernel: + 48 : dsa_next = 0x0
Apr 9 07:54:40 redrock kernel: scsi0 : end schedule dsa array
Apr 9 07:54:40 redrock kernel: scsi0 : reconnect_dsa_head :
Apr 9 07:54:40 redrock kernel: scsi0 : dsa at phys 0xfea1e8 (virt 0x00fea1e8)
Apr 9 07:54:40 redrock kernel: + 64 : dsa_msgout length = 1, data = 0xfea02c (virt 0x00fea02c)
Apr 9 07:54:40 redrock kernel: Identify disconnect allowed lun 0
Apr 9 07:54:40 redrock kernel: + 60 : select_indirect = 0x3000000
Apr 9 07:54:40 redrock kernel: + 56 : dsa_cmnd = 0x87210 result = 0xffff, target = 0, lun = 0, cmd = Read (6) 05 bf ad 10 00
Apr 9 07:54:40 redrock kernel: + 48 : dsa_next = 0x0
Apr 9 07:54:40 redrock kernel: scsi0 target 0 : sxfer_sanity = 0x0, scntl3_sanity = 0x3
Apr 9 07:54:40 redrock kernel: script : 0x78030300 0x0 0x78050000 0x0 0x90080000 0x0 0x0 0x0
Apr 9 07:54:40 redrock kernel: scsi0 : saved data pointer at offset 0
Apr 9 07:54:40 redrock kernel: scsi0 : can't determine active data pointer offset
Apr 9 07:54:40 redrock kernel: 0x88850 (virt 0x00088850) : 0x820b0000 0x00088848 (virt 0x00088848)
Apr 9 07:54:40 redrock kernel: 0x88858 (virt 0x00088858) : 0x8f0b0000 0x00088a00 (virt 0x00088a00)
Apr 9 07:54:40 redrock kernel: scsi0 : end reconnect_dsa_head
Apr 9 07:54:40 redrock kernel: The sti() implicit in a printk() prevents hangs
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97121, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf bd 18 00
Apr 9 07:54:40 redrock kernel: scsi0 : did this command ever run?
Apr 9 07:54:40 redrock kernel: scsi0 : target 0 accepting asynchronous SCSI
Apr 9 07:54:40 redrock kernel: scsi0 : setting target 0 to asynchronous SCSI
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97122, scsi0, channel 0, id 0, lun 0 Read (6) 05 bf d5 10 00
Apr 9 07:54:40 redrock kernel: scsi0 : did this command ever run?
Apr 9 07:54:40 redrock kernel: scsi : aborting command due to timeout : pid 97124, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 7c f9 c2 00 00 08 00
Apr 9 07:54:40 redrock kernel: scsi0 : did this command ever run?
Apr 9 07:54:40 redrock kernel: scsi0 : target 1 accepting asynchronous SCSI
Apr 9 07:54:40 redrock kernel: scsi0 : setting target 1 to asynchronous SCSI


Here are the messages from the evenutal timeout after which I can restart
the backup command...

Apr 9 08:09:19 redrock kernel: scsi : aborting command due to timeout : pid 97123, scsi0, channel 0, id 5, lun 0 Write (6) 01 00 00 3c 00
Apr 9 08:09:19 redrock kernel: scsi0 : did this command ever run?
Apr 9 08:09:19 redrock kernel: scsi0 : target 5 accepting asynchronous SCSI
Apr 9 08:09:19 redrock kernel: scsi0 : setting target 5 to asynchronous SCSI
Apr 9 08:09:19 redrock kernel: st0: Error with sense data: extra data not valid Current error st09:00: sense key Unit Attention
Apr 9 08:09:19 redrock kernel: Additional sense indicates Power on, reset, or bus device reset occurred



\
 
 \ /
  Last update: 2005-03-22 13:36    [W:0.061 / U:0.072 seconds]
©2003-2020 Jasper Spaans|hosted at Digital Ocean and TransIP|Read the blog|Advertise on this site