DAT timeouts (was: Async negotiation)

[Dirk Lutzebaeck]

Doug Ledford writes:
 > Now, having said that, the Amanda backup program appears to cause
 > several problems, and this one especially appears to be an Amanda
 > related problem if tars work fine.  Not only that, but the st0 error
 > code after the reset indicates DID_TIME_OUT so the tape drive has
 > essentially gone off-line and isn't responding to our attempts at
 > selection any more.  That's almost a full 20 seconds after the reset the
 > tape drive still won't select.  I would think this might very well be an
 > Amanda issue.  I would make sure of a few things.  One, make sure
 > disconnection is enabled on all devices in the Adaptec BIOS setup.  Two,
 > make sure that tagged queueing is enabled on your hard drive (check the
 > README.aic7xxx file).  Three, grab my 5.1.6 driver patch (for shits and
 > grins, just in case there's something wrong with the version you are
 > using now).

Did that: discon enabled, tag queueing enabled, updated to 5.1.6 but
there is still the same problem (besides the no_reset prob mentioned
in a post earlier):

Dec 14 14:01:43 kamet kernel: scsi : aborting command due to timeout : pid 286553, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 56 7a fb 00 00 02 00 

[ ... on and on ... ]

Dec 14 14:16:42 kamet kernel: scsi : aborting command due to timeout : pid 286553, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 56 7a fb 00 00 02 00 
Dec 14 14:16:42 kamet kernel: scsi : aborting command due to timeout : pid 286557, scsi0, channel 0, id 0, lun 0 Write (6) 02 52 a3 02 00 
Dec 14 14:16:42 kamet kernel: SCSI host 0 abort (pid 286546) timed out - resetting
Dec 14 14:16:42 kamet kernel: SCSI bus is being reset for host 0 channel 0.
Dec 14 14:16:42 kamet kernel: (scsi0:0:0:0) Synchronous at 40.0 Mbyte/sec, offset 15.
Dec 14 14:16:42 kamet kernel: st0: Error 26030000.
Dec 14 14:16:44 kamet kernel: st0: Error 26030000.

 > After you do that, see what happens.  If Amanda still
 > pukes, then I would start talking to the Amanda authors to see what
 > might be up.  Find out if they do anything special with sg devices, or
 > special scsi_ioctl calls to pass through commands the device might not
 > like, that sort of thing.

I'm not sure if it's amanda. I did see this once last week during a
regular tar. What's also interesting is that amanda says this:

INFO taper tape aeccDailyTape01 kb 1895264 fm 2 writing file: Input/output error
FAIL taper kamet sda2 0 [rewinding tape: Input/output error]

It seems that the write fails then amanda tries to rewind the tape but it
can't.

I'm digging the amanda mailing list archives also now...

Dirk

PS. Here are the scsi stats:

# cat /proc/scsi/aic7xxx/0
Adaptec AIC7xxx driver version: 5.1.6/3.2.4
Compile Options:
  AIC7XXX_RESET_DELAY    : 15
  AIC7XXX_TAGGED_QUEUEING: Adapter Support Enabled
                             Check below to see which
                             devices use tagged queueing
  AIC7XXX_PAGE_ENABLE    : Enabled (This is no longer an option)
  AIC7XXX_PROC_STATS     : Enabled

Adapter Configuration:
           SCSI Adapter: Adaptec AIC-7890/1 Ultra2 SCSI host adapter
                           Ultra2-LVD/SE Wide Controller
    PCI MMAPed I/O Base: 0xe1800000
 Adapter SEEPROM Config: SEEPROM found and used.
      Adaptec SCSI BIOS: Enabled
                    IRQ: 11
                   SCBs: Active 0, Max Active 6,
                         Allocated 15, HW 32, Page 255
             Interrupts: 291731
      BIOS Control Word: 0x18a6
   Adapter Control Word: 0x1c5e
   Extended Translation: Enabled
Disconnect Enable Flags: 0xffff
     Ultra Enable Flags: 0x0000
 Tag Queue Enable Flags: 0x0001
Ordered Queue Tag Flags: 0x0001
Default Tag Queue Depth: 5
    Tagged Queue By Device array for aic7xxx host instance 0:
      {0,0,0,255,255,255,255,255,255,255,255,255,255,255,255,255}
    Actual queue depth per device for aic7xxx host instance 0:
      {5,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1}

Statistics:
(scsi0:0:0:0)
  Device using Wide/Sync transfers at
  40.0 MByte/sec, offset 15
    Device Negotiation Settings
        Period Offset Bus Width
User       010    127        1
Goal       010    127        1
Current    012    015        1
    Total transfers 232356 (209123 read;23233 written)
      blks(512) rd=4052399; blks(512) wr=122872
        < 512 512-1K   1-2K   2-4K   4-8K  8-16K 16-32K 32-64K 64-128K >128K
 Reads:     0      1  85970  24140  33555  36293  10317   8794  10053      0 
Writes:     0      0  15362   3671   3117    737    198     41    107      0 

(scsi0:0:6:0)
  Device using Narrow/Sync transfers at
  6.67 MByte/sec, offset 15
    Device Negotiation Settings
        Period Offset Bus Width
User       010    127        1
Goal       010    127        0
Current    032    015        0
    Total transfers 59231 (2 read;59229 written)
      blks(512) rd=128; blks(512) wr=3790656
        < 512 512-1K   1-2K   2-4K   4-8K  8-16K 16-32K 32-64K 64-128K >128K
 Reads:     0      0      0      0      0      0      0      2      0      0 
Writes:     0      0      0      0      0      0      0  59229      0      0 

# cat /proc/scsi/scsi
Attached devices: 
Host: scsi0 Channel: 00 Id: 00 Lun: 00
  Vendor: IBM      Model: DDRS-34560W      Rev: S97B
  Type:   Direct-Access                    ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 04 Lun: 00
  Vendor: IOMEGA   Model: ZIP 100          Rev: J.03
  Type:   Direct-Access                    ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 05 Lun: 00
  Vendor: PIONEER  Model: CD-ROM DR-U16S   Rev: 1.01
  Type:   CD-ROM                           ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 06 Lun: 00
  Vendor: SEAGATE  Model: DAT    02779-XXX Rev: 658A
  Type:   Sequential-Access                ANSI SCSI revision: 02



To Unsubscribe: send mail to majordomo at FreeBSD.org
with "unsubscribe aic7xxx" in the body of the message