From owner-aic7xxx@FreeBSD.ORG Sat Jan 17 13:09:42 2004 Return-Path: Delivered-To: aic7xxx@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id E06BC16A4CE for ; Sat, 17 Jan 2004 13:09:42 -0800 (PST) Received: from NickAndBarb.net (rrcs-west-66-27-62-2.biz.rr.com [66.27.62.2]) by mx1.FreeBSD.org (Postfix) with ESMTP id 0901543D39 for ; Sat, 17 Jan 2004 13:09:38 -0800 (PST) (envelope-from npasich@cts.com) Received: (from root@localhost) by NickAndBarb.net (8.8.5/8.8.5) id NAA07810; Sat, 17 Jan 2004 13:09:38 -0800 Date: Sat, 17 Jan 2004 13:09:38 -0800 From: Nick Pasich To: aic7xxx@freebsd.org Message-ID: <20040117210938.GA7738@66.27.62.2> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.4.1i Subject: Attempting to queue an ABORT message X-BeenThere: aic7xxx@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Adaptec Device Drivers in FreeBSD and Linux List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 17 Jan 2004 21:09:43 -0000 I have an adaptec 29160 with 5 drives running on Linux 2.4.24. I do an rysnc backup from Disk ID:0 to Disk ID:2 and from Disk ID:1 to Disk ID:3 every night. As of late I've been getting "ABORT" messages in the kernel log. Just wondering what this message is telling me and if the drive is at fault. The rsync backup always completes without any problems. I've attached /proc/scsi, /proc/scsi/aic7xxx/0 and the kernel logs. Thanks, Nick Pasich /proc/scsi shows: ****************************************************************** ****************************************************************** Attached devices: Host: scsi0 Channel: 00 Id: 00 Lun: 00 Vendor: IBM Model: DDYS-T09170N Rev: S96H Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 01 Lun: 00 Vendor: IBM Model: DDYS-T09170N Rev: S96H Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 02 Lun: 00 Vendor: IBM Model: DGHS09U Rev: 03E0 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 03 Lun: 00 Vendor: IBM Model: DGHS09U Rev: 03E0 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 05 Lun: 00 Vendor: ARCHIVE Model: Python 04106-XXX Rev: 7600 Type: Sequential-Access ANSI SCSI revision: 02 Host: scsi0 Channel: 00 Id: 08 Lun: 00 Vendor: IBM Model: DGHS09U Rev: 03E0 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi1 Channel: 00 Id: 00 Lun: 00 Vendor: TDK Model: CDRW241040B Rev: 57S5 Type: CD-ROM ANSI SCSI revision: 02 ****************************************************************** ****************************************************************** /proc/scsi/aic7xxx/0 shows: ****************************************************************** ****************************************************************** Adaptec AIC7xxx driver version: 6.2.36 Adaptec 29160 Ultra160 SCSI adapter aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs Allocated SCBs: 250, SG List Length: 102 Serial EEPROM: 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xb8f4 0x745d 0x2807 0x0010 0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0x0250 0x8b4e Target 0 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit) Curr: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit) Channel A Target 0 Lun 0 Settings Commands Queued 429502 Commands Active 0 Command Openings 253 Max Tagged Openings 253 Device Queue Frozen Count 0 Target 1 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit) Curr: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit) Channel A Target 1 Lun 0 Settings Commands Queued 1326067 Commands Active 0 Command Openings 128 Max Tagged Openings 128 Device Queue Frozen Count 0 Target 2 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Curr: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Channel A Target 2 Lun 0 Settings Commands Queued 20054 Commands Active 0 Command Openings 64 Max Tagged Openings 253 Device Queue Frozen Count 0 Target 3 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Curr: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Channel A Target 3 Lun 0 Settings Commands Queued 284020 Commands Active 0 Command Openings 64 Max Tagged Openings 64 Device Queue Frozen Count 0 Target 4 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 5 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 10.000MB/s transfers (10.000MHz, offset 15) Curr: 10.000MB/s transfers (10.000MHz, offset 15) Channel A Target 5 Lun 0 Settings Commands Queued 433686 Commands Active 0 Command Openings 1 Max Tagged Openings 0 Device Queue Frozen Count 0 Target 6 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 7 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 8 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Goal: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Curr: 40.000MB/s transfers (20.000MHz, offset 15, 16bit) Channel A Target 8 Lun 0 Settings Commands Queued 13873 Commands Active 0 Command Openings 64 Max Tagged Openings 253 Device Queue Frozen Count 0 Target 9 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 10 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 11 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 12 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 13 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 14 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) Target 15 Negotiation Settings User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit) ****************************************************************** ****************************************************************** kernel log shows: ************************************************************************** ************************************************************************** Jan 16 23:35:00 NICK2 kernel: kjournald starting. Commit interval 5 seconds Jan 16 23:35:00 NICK2 kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on sd(8,33), internal journal Jan 16 23:35:00 NICK2 kernel: EXT3-fs: mounted filesystem with writeback data mode. Jan 16 23:36:02 NICK2 kernel: scsi0:0:3:0: Attempting to queue an ABORT message Jan 16 23:36:02 NICK2 kernel: CDB: 0x28 0x0 0x0 0x0 0x0 0x3f 0x0 0x0 0x8 0x0 Jan 16 23:36:02 NICK2 kernel: scsi0: At time of recovery, card was not paused Jan 16 23:36:02 NICK2 kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Jan 16 23:36:02 NICK2 kernel: scsi0: Dumping Card State while idle, at SEQADDR 0x9 Jan 16 23:36:02 NICK2 kernel: Card was paused Jan 16 23:36:02 NICK2 kernel: ACCUM = 0x0, SINDEX = 0x4, DINDEX = 0xe4, ARG_2 = 0x0 Jan 16 23:36:02 NICK2 kernel: HCNT = 0x0 SCBPTR = 0x7 Jan 16 23:36:02 NICK2 kernel: SCSIPHASE[0x0] SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] Jan 16 23:36:02 NICK2 kernel: LASTPHASE[0x1] SCSISEQ[0x12] SBLKCTL[0xa] SCSIRATE[0x0] Jan 16 23:36:02 NICK2 kernel: SEQCTL[0x10] SEQ_FLAGS[0xc0] SSTAT0[0x0] SSTAT1[0x8] Jan 16 23:36:02 NICK2 kernel: SSTAT2[0x0] SSTAT3[0x0] SIMODE0[0x8] SIMODE1[0xa4] Jan 16 23:36:02 NICK2 kernel: SXFRCTL0[0x80] DFCNTRL[0x0] DFSTATUS[0x89] Jan 16 23:36:02 NICK2 kernel: STACK: 0xe1 0x163 0x109 0x3 Jan 16 23:36:02 NICK2 kernel: SCB count = 250 Jan 16 23:36:02 NICK2 kernel: Kernel NEXTQSCB = 21 Jan 16 23:36:02 NICK2 kernel: Card NEXTQSCB = 21 Jan 16 23:36:02 NICK2 kernel: QINFIFO entries: Jan 16 23:36:02 NICK2 kernel: Waiting Queue entries: Jan 16 23:36:02 NICK2 kernel: Disconnected Queue entries: 22:211 Jan 16 23:36:02 NICK2 kernel: QOUTFIFO entries: Jan 16 23:36:02 NICK2 kernel: Sequencer Free SCB List: 7 4 30 12 29 1 9 21 24 0 16 23 19 20 11 6 31 8 13 10 5 25 3 26 28 15 18 14 17 2 27 Jan 16 23:36:02 NICK2 kernel: Sequencer SCB Info: Jan 16 23:36:02 NICK2 kernel: 0 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 1 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 2 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 3 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 4 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 5 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 6 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 7 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 8 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 9 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 10 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 11 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 12 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 13 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 14 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 15 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 16 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 17 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 18 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 19 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 20 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 21 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 22 SCB_CONTROL[0x64] SCB_SCSIID[0x37] SCB_LUN[0x0] SCB_TAG[0xd3] Jan 16 23:36:02 NICK2 kernel: 23 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 24 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 25 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 26 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 27 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 28 SCB_CONTROL[0xe0] SCB_SCSIID[0x27] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 29 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 30 SCB_CONTROL[0xe0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: 31 SCB_CONTROL[0xe0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Jan 16 23:36:02 NICK2 kernel: Pending list: Jan 16 23:36:02 NICK2 kernel: 211 SCB_CONTROL[0x60] SCB_SCSIID[0x37] SCB_LUN[0x0] Jan 16 23:36:02 NICK2 kernel: Kernel Free SCB list: 4 25 247 34 56 124 184 116 232 177 139 80 2 214 196 10 136 204 40 192 134 238 121 180 48 81 241 72 187 76 193 200 28 212 236 145 158 119 37 171 230 131 221 86 226 130 140 7 208 244 89 195 11 148 162 185 127 231 141 52 235 206 79 154 216 133 71 46 54 219 170 189 225 108 182 159 51 32 217 201 172 15 167 165 17 64 203 126 209 191 129 135 149 123 160 224 205 213 125 178 163 143 137 55 176 218 202 183 96 156 234 85 30 249 152 174 61 150 107 115 146 53 161 181 110 142 199 240 8 103 186 13 168 9 227 228 39 164 109 157 239 198 215 68 173 22 229 243 248 242 36 117 210 91 153 1 90 188 63 88 207 197 118 190 220 138 155 16 194 175 19 27 97 223 112 49 50 62 24 132 98 6 147 233 237 144 166 169 179 222 99 26 84 31 70 18 114 66 38 35 23 93 74 59 82 33 3 87 69 14 65 92 100 120 47 104 60 122 106 43 41 44 113 42 12 5 75 67 0 57 111 45 151 102 94 105 128 101 77 95 29 78 58 20 73 83 246 245 Jan 16 23:36:02 NICK2 kernel: DevQ(0:0:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: DevQ(0:1:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: DevQ(0:2:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: DevQ(0:3:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: DevQ(0:5:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: DevQ(0:8:0): 0 waiting Jan 16 23:36:02 NICK2 kernel: Jan 16 23:36:02 NICK2 kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Jan 16 23:36:02 NICK2 kernel: (scsi0:A:3:0): Device is disconnected, re-queuing SCB Jan 16 23:36:02 NICK2 kernel: Recovery code sleeping Jan 16 23:36:02 NICK2 kernel: (scsi0:A:3:0): Abort Tag Message Sent Jan 16 23:36:02 NICK2 kernel: (scsi0:A:3:0): SCB 211 - Abort Tag Completed. Jan 16 23:36:02 NICK2 kernel: Recovery SCB completes Jan 16 23:36:02 NICK2 kernel: Recovery code awake Jan 16 23:36:02 NICK2 kernel: aic7xxx_abort returns 0x2002 Jan 16 23:36:02 NICK2 kernel: kjournald starting. Commit interval 5 seconds Jan 16 23:36:02 NICK2 kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on sd(8,49), internal journal Jan 16 23:36:02 NICK2 kernel: EXT3-fs: mounted filesystem with writeback data mode. ************************************************************************** **************************************************************************