ABORT message after hours of working
Ard van Breemen
ard at kwaak.net
Wed Sep 5 06:55:55 PDT 2001
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 at 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 at FreeBSD.org
with "unsubscribe aic7xxx" in the body of the message
More information about the aic7xxx
mailing list