From owner-freebsd-questions Sun Aug 8 23: 3:24 1999 Delivered-To: freebsd-questions@freebsd.org Received: from granite.sentex.net (granite.sentex.ca [199.212.134.1]) by hub.freebsd.org (Postfix) with ESMTP id 7511614E29; Sun, 8 Aug 1999 23:03:18 -0700 (PDT) (envelope-from mike@sentex.net) Received: from gravel (ospf-wat.sentex.net [209.167.248.81]) by granite.sentex.net (8.8.8/8.6.9) with SMTP id CAA05955; Mon, 9 Aug 1999 02:01:18 -0400 (EDT) Message-Id: <4.1.19990808234210.03c6b2d0@granite.sentex.ca> X-Sender: mdtancsa@granite.sentex.ca X-Mailer: QUALCOMM Windows Eudora Pro Version 4.1 Date: Mon, 09 Aug 1999 02:14:07 -0400 To: questions@freebsd.org From: Mike Tancsa Subject: AHC errors. Bad disk or bad firmware, or bug in driver ? Cc: scsi@freebsd.org Mime-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Hi, I am running FreeBSD 2.2.8-STABLE #0: Thu Aug 5 box that ran into some disk problems tonight. All of a sudden on my console, it was filled with the following. Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 36 times Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Bu Aug 8 20:53:12 granite /kernel: sy Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 36 times Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Aug 8 20:53:12 granite /kernel: Busy Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 3 times Aug 8 20:53:12 granite /kernel: ahc0:A:2: ahc_intr - referenced scb not valid during seqint 0x71 scb(7) Aug 8 20:53:12 granite /kernel: ahc0: WARNING no command for scb 7 (cmdcmplt) Aug 8 20:53:12 granite /kernel: QOUTCNT == 1 Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 27 times Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Aug 8 20:53:12 granite /kernel: Busy Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 36 times Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Targ Aug 8 20:53:12 granite /kernel: et Busy Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:12 granite last message repeated 36 times Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): T Aug 8 20:53:12 granite /kernel: arget Busy Aug 8 20:53:12 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:20 granite last message repeated 79 times Aug 8 20:53:21 granite /kernel: sd2(ahc0:2:0): SCB 0xd - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:21 granite /kernel: SEQADDR = 0x8 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:21 granite /kernel: Ordered Tag queued Aug 8 20:53:21 granite /kernel: sd2(ahc0:2:0): SCB 0x0 timedout while recovery in progress Aug 8 20:53:21 granite /kernel: sd2(ahc0:2:0): SCB 0xc timedout while recovery in progress Aug 8 20:53:26 granite /kernel: sd2(ahc0:2:0): SCB 0xd - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:47 granite /kernel: SEQADDR = 0x4 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Queueing an Abort SCB Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Abort Message Sent Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 13 - Abort Tag Completed. Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): no longer in timeout Aug 8 20:53:47 granite /kernel: Ordered Tag sent Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:47 granite last message repeated 3 times Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 0xc - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:47 granite /kernel: SEQADDR = 0x5 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:47 granite /kernel: Ordered Tag queued Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 0x0 timedout while recovery in progress Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 0xc - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:47 granite /kernel: SEQADDR = 0x4 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Queueing an Abort SCB Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Abort Message Sent Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 12 - Abort Tag Completed. Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): no longer in timeout Aug 8 20:53:47 granite /kernel: Ordered Tag sent Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:47 granite last message repeated 3 times Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): SCB 0x0 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:47 granite /kernel: SEQADDR = 0x6 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:47 granite /kernel: Ordered Tag queued Aug 8 20:53:47 granite /kernel: Ordered Tag sent Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Tar Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:47 granite last message repeated 36 times Aug 8 20:53:47 granite /kernel: sd2(ahc0:2:0): Target Bu Aug 8 20:53:47 granite /kernel: sy Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:48 granite last message repeated 36 times Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Target Aug 8 20:53:48 granite /kernel: Busy Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:48 granite last message repeated 11 times Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): SCB 0x0 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0 Aug 8 20:53:48 granite /kernel: SEQADDR = 0x8 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Queueing an Abort SCB Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Abort Message Sent Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): SCB 0 - Abort Tag Completed. Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): no longer in timeout Aug 8 20:53:48 granite /kernel: sd2(ahc0:2:0): Target Busy Aug 8 20:53:48 granite last message repeated 14 times Aug 8 20:53:48 granite /kernel: s And then the target busy just keeps repeating itself over and over. I had to reboot the machine and unfortunately, it had a few errors, pretty well all of which we were able to recover. The drive in question is a Viking II (ahc0:2:0): "QUANTUM VIKING II 4.5WSE 4110" type 0 fixed SCSI 2 sd2(ahc0:2:0): Direct-Access 4350MB (8910423 512 byte sectors) ahc0: target 4 Tagged Queuing Device If it was simply a media error, would it not either recover or panic ? Why the seemingly endless loop of "Target Busy" Thanks, ---Mike ********************************************************************** Mike Tancsa, Network Admin * mike@sentex.net Sentex Communications Corp, * http://www.sentex.net/mike Cambridge, Ontario * 01.519.651.3400 Canada * To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message