From owner-aic7xxx Wed Sep 5 6:55:55 2001 Delivered-To: aic7xxx@freebsd.org Received: from mail.kwaak.net (fia171-26.dsl.hccnet.nl [62.251.26.171]) by hub.freebsd.org (Postfix) with ESMTP id F1A1537B406 for ; Wed, 5 Sep 2001 06:55:44 -0700 (PDT) Received: by mail.kwaak.net (Postfix, from userid 501) id 7DC922CD9D; Wed, 5 Sep 2001 15:55:27 +0200 (CEST) Date: Wed, 5 Sep 2001 15:55:26 +0200 To: aic7xxx@freebsd.org Subject: ABORT message after hours of working Message-ID: <20010905155526.C31067@kwaak.net> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.3.15i From: ard@kwaak.net (Ard van Breemen) Sender: owner-aic7xxx@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.org Hi, I've seen the abort message problems from other people, but we have this problem: After a days work of database reading/writing postgres@reiserfs I get that famous ABORT message :(. I do not know who wants to queue that ABORT message, but the problem is, that the device get's kicked out of the raid configuration. But this is just plain weird... Anybody clues? The fun part of it is that the machine tries to renegotiate, and it goes back to 3.3MB/s on a 80MB/s bus... /proc/scsi/aic7xxx/0 -------------------------------------------------------------------------------- Adaptec AIC7xxx driver version: 6.2.1 aic7896/97: Ultra2 Wide Channel A, SCSI Id=7, 32/255 SCBs Channel A Target 0 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Goal: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Curr: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Channel A Target 0 Lun 0 Settings Commands Queued 7814559 Commands Active 0 Command Openings 49 Max Tagged Openings 49 Device Queue Frozen Count 0 Channel A Target 1 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Goal: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Curr: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Channel A Target 1 Lun 0 Settings Commands Queued 8677078 Commands Active 0 Command Openings 49 Max Tagged Openings 49 Device Queue Frozen Count 0 Channel A Target 2 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Goal: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Curr: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Channel A Target 2 Lun 0 Settings Commands Queued 8227843 Commands Active 0 Command Openings 49 Max Tagged Openings 253 Device Queue Frozen Count 0 Channel A Target 3 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Goal: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Curr: 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Channel A Target 3 Lun 0 Settings Commands Queued 8365706 Commands Active 0 Command Openings 50 Max Tagged Openings 253 Device Queue Frozen Count 0 Channel A Target 4 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 5 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 6 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 7 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 8 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 9 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 10 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 11 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 12 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 13 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 14 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) Channel A Target 15 Negotiation Settings User: 80.000MB/s transfers (40.000MHz, offset 255, 16bit) /proc/scsi/scsi -------------------------------------------------------------------------------- Attached devices: Host: scsi0 Channel: 00 Id: 00 Lun: 00 Vendor: SEAGATE Model: ST318404LW Rev: 0002 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 01 Lun: 00 Vendor: SEAGATE Model: ST318404LW Rev: 0002 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 02 Lun: 00 Vendor: SEAGATE Model: ST318404LW Rev: 0006 Type: Direct-Access ANSI SCSI revision: 03 Host: scsi0 Channel: 00 Id: 03 Lun: 00 Vendor: SEAGATE Model: ST318404LW Rev: 0002 Type: Direct-Access ANSI SCSI revision: 03 Kernel log: -------------------------------------------------------------------------------- Sep 3 18:56:18 rs0 kernel: scsi0:0:0:0: Attempting to queue an ABORT message Sep 3 18:56:18 rs0 kernel: scsi0: Dumping Card State while idle, at SEQADDR 0x8 Sep 3 18:56:18 rs0 kernel: ACCUM = 0x0, SINDEX = 0x2d, DINDEX = 0xe4, ARG_2 = 0x0 Sep 3 18:56:18 rs0 kernel: HCNT = 0x0 Sep 3 18:56:18 rs0 kernel: SCSISEQ = 0x12, SBLKCTL = 0xa Sep 3 18:56:18 rs0 kernel: DFCNTRL = 0x0, DFSTATUS = 0x89 Sep 3 18:56:18 rs0 kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80 Sep 3 18:56:18 rs0 kernel: SSTAT0 = 0x0, SSTAT1 = 0xa Sep 3 18:56:18 rs0 kernel: STACK == 0x3, 0x107, 0x15f, 0x0 Sep 3 18:56:18 rs0 kernel: SCB count = 240 Sep 3 18:56:18 rs0 kernel: Kernel NEXTQSCB = 120 Sep 3 18:56:18 rs0 kernel: Card NEXTQSCB = 120 Sep 3 18:56:18 rs0 kernel: QINFIFO entries: Sep 3 18:56:18 rs0 kernel: Waiting Queue entries: Sep 3 18:56:18 rs0 kernel: Disconnected Queue entries: 24:92 Sep 3 18:56:18 rs0 kernel: QOUTFIFO entries: Sep 3 18:56:18 rs0 kernel: Sequencer Free SCB List: 25 8 2 13 29 17 27 4 9 7 26 18 14 19 23 12 6 5 3 31 11 0 30 22 21 10 16 28 1 15 20 Sep 3 18:56:18 rs0 kernel: Pending list: 92 Sep 3 18:56:18 rs0 kernel: Kernel Free SCB list: 45 48 142 135 40 64 235 78 206 198 105 80 2 209 146 102 33 14 6 179 182 188 69 208 10 96 110 155 111 199 187 123 107 61 118 55 195 17 46 12 90 228 95 205 62 229 133 19 159 54 150 153 200 56 4 165 140 158 99 191 160 52 59 170 21 180 139 176 234 11 162 178 177 41 60 193 29 42 83 30 163 70 157 196 141 137 127 201 126 87 203 144 227 81 225 169 84 174 175 28 161 9 27 44 173 213 108 106 145 38 88 230 181 104 77 231 172 67 91 58 36 65 89 103 190 212 115 194 20 13 220 97 8 130 138 73 68 5 154 98 117 184 75 112 124 72 224 23 116 129 22 26 3 210 186 221 204 197 35 236 132 109 82 131 134 50 93 79 0 47 49 7 185 66 94 37 143 101 85 192 16 100 74 121 152 119 215 211 136 214 114 171 113 1 226 222 183 31 166 15 202 43 24 164 148 122 125 219 147 76 63 237 238 239 232 233 223 216 217 218 51 86 39 25 53 149 18 151 167 189 128 34 57 207 156 32 168 71 Sep 3 18:56:18 rs0 kernel: DevQ(0:0:0): 0 waiting Sep 3 18:56:18 rs0 kernel: DevQ(0:1:0): 0 waiting Sep 3 18:56:18 rs0 kernel: DevQ(0:2:0): 0 waiting Sep 3 18:56:18 rs0 kernel: DevQ(0:3:0): 0 waiting Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Queuing a recovery SCB Sep 3 18:56:18 rs0 kernel: scsi0:0:0:0: Device is disconnected, re-queuing SCB Sep 3 18:56:18 rs0 kernel: Recovery code sleeping Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Abort Tag Message Sent Sep 3 18:56:18 rs0 kernel: Recovery code awake Sep 3 18:56:18 rs0 kernel: Timer Expired Sep 3 18:56:18 rs0 kernel: aic7xxx_abort returns 8195 Sep 3 18:56:18 rs0 kernel: scsi0:0:0:0: Attempting to queue a TARGET RESET message Sep 3 18:56:18 rs0 kernel: aic7xxx_dev_reset returns 8195 Sep 3 18:56:18 rs0 kernel: Recovery SCB completes Sep 3 18:56:18 rs0 kernel: (scsi0:A:0): 40.000MB/s transfers (40.000MHz, offset 63) Sep 3 18:56:18 rs0 kernel: scsi0: target 0 using 8bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:0): 3.300MB/s transfers Sep 3 18:56:18 rs0 kernel: scsi0: target 0 using asynchronous transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:1): 40.000MB/s transfers (40.000MHz, offset 63) Sep 3 18:56:18 rs0 kernel: scsi0: target 1 using 8bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:1): 3.300MB/s transfers Sep 3 18:56:18 rs0 kernel: scsi0: target 1 using asynchronous transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:2): 40.000MB/s transfers (40.000MHz, offset 63) Sep 3 18:56:18 rs0 kernel: scsi0: target 2 using 8bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:2): 3.300MB/s transfers Sep 3 18:56:18 rs0 kernel: scsi0: target 2 using asynchronous transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:3): 40.000MB/s transfers (40.000MHz, offset 63) Sep 3 18:56:18 rs0 kernel: scsi0: target 3 using 8bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:3): 3.300MB/s transfers Sep 3 18:56:18 rs0 kernel: scsi0: target 3 using asynchronous transfers Sep 3 18:56:18 rs0 kernel: scsi0: SCSI bus reset delivered. 1 SCBs aborted. Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:0): 6.600MB/s transfers (16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 0 using 16bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:0): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 0 synchronous at 40.0MHz, offset = 0x3f Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:0:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:1:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:1:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:1): 6.600MB/s transfers (16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 1 using 16bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:1): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 1 synchronous at 40.0MHz, offset = 0x3f Sep 3 18:56:18 rs0 kernel: (scsi0:A:2:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:2:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:2): 6.600MB/s transfers (16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 2 using 16bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:2): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 2 synchronous at 40.0MHz, offset = 0x3f Sep 3 18:56:18 rs0 kernel: (scsi0:A:3:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:3:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:3): 6.600MB/s transfers (16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 3 using 16bit transfers Sep 3 18:56:18 rs0 kernel: (scsi0:A:3): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) Sep 3 18:56:18 rs0 kernel: scsi0: target 3 synchronous at 40.0MHz, offset = 0x3f Sep 3 18:56:18 rs0 kernel: (scsi0:A:1:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:1:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:2:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:2:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:3:0): Sending PPR bus_width 1, period a, offset 3f, ppr_options 0 Sep 3 18:56:18 rs0 kernel: (scsi0:A:3:0): Received PPR width 1, period a, offset 3f,options 0 Sep 3 18:56:18 rs0 kernel: ^IFiltered to width 1, period a, offset 3f, options 0 -- mail up 2+17:56, 4 users, load 0.20, 0.25, 0.19 mistar1 up 20+16:36, 4 users, load 0.11, 0.07, 0.01 Let your government know you value your freedom: sign the petition: http://petition.eurolinux.org To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe aic7xxx" in the body of the message