Date: Tue, 01 Oct 2002 12:17:09 +0100 From: David Malone <dwmalone@maths.tcd.ie> To: freebsd-scsi@freebsd.org Subject: timed out Message-ID: <200210011217.aa29164@salmon.maths.tcd.ie>
next in thread | raw e-mail | index | archive | help
Someone's news server hung yesterday with the following messages. It looks like da2 timed out, a BDR was issued but some of the other disk never came back again. The machine is running 4.6-STABLE - I'm guessing that some piece of hardware just went to sleep, but maybe someone could glance at the messages and see if that makes sense. Boot messages are included below the log messages. David. Sep 30 13:39:11 x /kernel: (da2:ahc0:0:2:0): SCB 0x98 - timed out Sep 30 13:39:11 x /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x9 Sep 30 13:39:11 x /kernel: ACCUM = 0x4, SINDEX = 0x64, DINDEX = 0x65, ARG_2 = 0x13 Sep 30 13:39:11 x /kernel: HCNT = 0x0 SCBPTR = 0xd Sep 30 13:39:11 x /kernel: SCSISEQ = 0x12, SBLKCTL = 0xa Sep 30 13:39:11 x /kernel: DFCNTRL = 0x0, DFSTATUS = 0x89 Sep 30 13:39:11 x /kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80 Sep 30 13:39:11 x /kernel: SSTAT0 = 0x0, SSTAT1 = 0x8 Sep 30 13:39:11 x /kernel: SCSIPHASE = 0x0 Sep 30 13:39:11 x /kernel: STACK == 0x3, 0x175, 0x160, 0xe7 Sep 30 13:39:11 x /kernel: SCB count = 254 Sep 30 13:39:11 x /kernel: Kernel NEXTQSCB = 47 Sep 30 13:39:11 x /kernel: Card NEXTQSCB = 47 Sep 30 13:39:11 x /kernel: QINFIFO entries: Sep 30 13:39:11 x /kernel: Waiting Queue entries: Sep 30 13:39:11 x /kernel: Disconnected Queue entries: 13:79 9:152 Sep 30 13:39:11 x /kernel: QOUTFIFO entries: Sep 30 13:39:11 x /kernel: Sequencer Free SCB List: 16 21 25 24 5 8 6 12 7 19 0 29 31 30 27 3 20 23 18 1 2 4 17 14 10 11 15 26 22 28 Sep 30 13:39:11 x /kernel: Sequencer SCB Info: 0(c 0x60, s 0x7, l 0, t 0xff) 1(c 0x60, s 0x7, l 0, t 0xff) 2(c 0x60, s 0x7, l 0, t 0xff) 3(c 0x60, s 0x7, l 0, t 0xff) 4(c 0x60, s 0x7, l 0, t 0xff) 5(c 0x60, s 0x7, l 0, t 0xff) 6(c 0x60, s 0x7, l 0, t 0xff) 7(c 0x60, s 0x7, l 0, t 0xff) 8(c 0x60, s 0x37, l 0, t 0xff) 9(c 0x64, s 0x27, l 0, t 0x98) 10(c 0x60, s 0x7, l 0, t 0xff) 11(c 0x60, s 0x7, l 0, t 0xff) 12(c 0x60, s 0x17, l 0, t 0xff) 13(c 0x64, s 0x27, l 0, t 0x4f) 14(c 0x60, s 0x37, l 0, t 0xff) 15(c 0x60, s 0x7, l 0, t 0xff) 16(c 0x60, s 0x17, l 0, t 0xff) 17(c 0x60, s 0x7, l 0, t 0xff) 18(c 0x60, s 0x7, l 0, t 0xff) 19(c 0x60, s 0x7, l 0, t 0xff) 20(c 0x60, s 0x7, l 0, t 0xff) 21(c 0x60, s 0x17, l 0, t 0xff) 22(c 0x60, s 0x7, l 0, t 0xff) 23(c 0x60, s 0x7, l 0, t 0xff) 24(c 0x60, s 0x7, l 0, t 0xff) 25(c 0x60, s 0x17, l 0, t 0xff) 26(c 0x60, s 0x7, l 0, t 0xff) 27(c 0x60, s 0x7, l 0, t 0xff) 28(c 0x60, s 0x7, l 0, t 0xff) 29(c 0x60, s 0x7, l 0, t 0xff) 30(c 0x60, s 0x 7, l 0, t 0xff) 31(c 0x60, s 0x7, l 0, t 0xff) Sep 30 13:39:11 x /kernel: Pending list: 79(c 0x60, s 0x27, l 0), 152(c 0x60, s 0x27, l 0) Sep 30 13:39:11 x /kernel: Kernel Free SCB list: 186 213 14 175 94 32 221 156 183 38 174 129 88 67 179 84 197 41 102 141 224 192 48 96 161 159 44 111 234 202 40 252 58 95 63 53 34 132 2 9 105 87 191 4 0 251 249 220 109 189 168 69 81 127 187 246 201 145 171 208 93 181 29 121 178 163 184 229 169 17 206 91 50 56 19 116 101 42 165 157 64 80 153 66 46 85 134 100 52 24 205 199 13 7 119 115 142 22 65 215 76 77 122 203 20 23 31 164 250 207 107 170 245 37 68 139 27 237 210 158 226 233 39 8 125 154 70 150 89 176 98 128 151 55 12 236 166 185 235 25 227 124 123 5 172 143 160 140 194 225 135 240 223 209 180 136 108 43 92 113 10 62 242 243 133 97 90 35 228 120 248 49 167 51 112 219 3 15 117 211 204 222 193 103 1 74 231 217 78 18 118 110 61 86 198 155 148 83 126 99 21 59 6 241 147 253 104 30 190 230 137 195 36 114 26 131 216 71 212 11 106 28 196 60 54 72 146 239 200 188 162 138 73 218 238 16 75 214 173 33 232 45 82 182 144 130 247 149 177 57 244 Sep 30 13:39:11 x /kernel: sg[0] - Addr 0xbc5d000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[1] - Addr 0xbde000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[2] - Addr 0x396df000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[3] - Addr 0xde0000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[4] - Addr 0x17a61000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[5] - Addr 0xbd62000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[6] - Addr 0x2c263000 : Length 4096 Sep 30 13:39:11 x /kernel: sg[7] - Addr 0x30e4000 : Length 4096 Sep 30 13:39:11 x /kernel: (da2:ahc0:0:2:0): Queuing a BDR SCB Sep 30 13:39:11 x /kernel: (da2:ahc0:0:2:0): Bus Device Reset Message Sent Sep 30 13:39:11 x /kernel: (da2:ahc0:0:2:0): no longer in timeout, status = 34b Sep 30 13:39:11 x /kernel: ahc0: Bus Device Reset on A:2. 1 SCBs aborted Sep 30 14:16:14 x /kernel: (da2:ahc0:0:2:0): Unexpected busfree in Data-out phase Sep 30 14:16:14 x /kernel: SEQADDR == 0x55 Sep 30 14:41:48 x /kernel: (da3:ahc0:0:3:0): SCB 0x40 - timed out Sep 30 14:41:48 x /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x9 Sep 30 14:41:48 x /kernel: ACCUM = 0x4, SINDEX = 0x64, DINDEX = 0x65, ARG_2 = 0x3e Sep 30 14:41:48 x /kernel: HCNT = 0x0 SCBPTR = 0x19 Sep 30 14:41:48 x /kernel: SCSISEQ = 0x12, SBLKCTL = 0xa Sep 30 14:41:48 x /kernel: DFCNTRL = 0x0, DFSTATUS = 0x89 Sep 30 14:41:48 x /kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80 Sep 30 14:41:48 x /kernel: SSTAT0 = 0x0, SSTAT1 = 0x8 Sep 30 14:41:48 x /kernel: SCSIPHASE = 0x0 Sep 30 14:41:48 x /kernel: STACK == 0x3, 0x175, 0x160, 0x89 Sep 30 14:41:48 x /kernel: SCB count = 254 Sep 30 14:41:48 x /kernel: Kernel NEXTQSCB = 234 Sep 30 14:41:48 x /kernel: Card NEXTQSCB = 234 Sep 30 14:41:48 x /kernel: QINFIFO entries: Sep 30 14:41:48 x /kernel: Waiting Queue entries: Sep 30 14:41:48 x /kernel: Disconnected Queue entries: 25:251 8:64 Sep 30 14:41:48 x /kernel: QOUTFIFO entries: Sep 30 14:41:48 x /kernel: Sequencer Free SCB List: 22 2 23 9 3 21 10 1 7 17 11 4 6 16 0 27 20 5 14 29 15 26 19 30 31 12 13 28 18 24 Sep 30 14:41:48 x /kernel: Sequencer SCB Info: 0(c 0x60, s 0x17, l 0, t 0xff) 1(c 0x60, s 0x17, l 0, t 0xff) 2(c 0x60, s 0x27, l 0, t 0xff) 3(c 0x60, s 0x17, l 0, t 0xff) 4(c 0x60, s 0x27, l 0, t 0xff) 5(c 0x60, s 0x17, l 0, t 0xff) 6(c 0x60, s 0x37, l 0, t 0xff) 7(c 0x60, s 0x37, l 0, t 0xff) 8(c 0x64, s 0x37, l 0, t 0x40) 9(c 0x60, s 0x37, l 0, t 0xff) 10(c 0x60, s 0x7, l 0, t 0xff) 11(c 0x60, s 0x17, l 0, t 0xff) 12(c 0x60, s 0x17, l 0, t 0xff) 13(c 0x60, s 0x37, l 0, t 0xff) 14(c 0x60, s 0x27, l 0, t 0xff) 15(c 0x60, s 0x27, l 0, t 0xff) 16(c 0x60, s 0x17, l 0, t 0xff) 17(c 0x60, s 0x27, l 0, t 0xff) 18(c 0x60, s 0x37, l 0, t 0xff) 19(c 0x60, s 0x17, l 0, t 0xff) 20(c 0x60, s 0x37, l 0, t 0xff) 21(c 0x60, s 0x27, l 0, t 0xff) 22(c 0x60, s 0x27, l 0, t 0xff) 23(c 0x60, s 0x17, l 0, t 0xff) 24(c 0x60, s 0x17, l 0, t 0xff) 25(c 0x64, s 0x7, l 0, t 0xfb) 26(c 0x60, s 0x17, l 0, t 0xff) 27(c 0x60, s 0x27, l 0, t 0xff) 28(c 0x60, s 0x27, l 0, t 0xff) 29(c 0x60, s 0x17, l 0, t 0 xff) 30(c 0x60, s 0x17, l 0, t 0xff) 31(c 0x60, s 0x Sep 30 14:41:48 x /kernel: t 0xff) Sep 30 14:41:48 x /kernel: Pending list: 251(c 0x60, s 0x7, l 0), 64(c 0x60, s 0x37, l 0) Sep 30 14:41:48 x /kernel: Kernel Free SCB list: 171 32 187 2 53 201 202 19 169 7 84 152 161 38 183 48 58 100 29 134 220 102 63 127 179 115 85 132 95 199 88 215 159 119 101 246 46 41 67 24 208 252 42 50 206 96 69 105 121 175 13 186 116 66 79 165 141 249 168 9 4 91 142 181 111 14 189 44 94 17 56 178 156 191 145 213 65 163 40 221 184 22 47 93 81 76 229 80 109 77 87 205 192 157 129 34 174 0 224 197 153 52 122 203 20 23 31 164 250 207 107 170 245 37 68 139 27 237 210 158 226 233 39 8 125 154 70 150 89 176 98 128 151 55 12 236 166 185 235 25 227 124 123 5 172 143 160 140 194 225 135 240 223 209 180 136 108 43 92 113 10 62 242 243 133 97 90 35 228 120 248 49 167 51 112 219 3 15 117 211 204 222 193 103 1 74 231 217 78 18 118 110 61 86 198 155 148 83 126 99 21 59 6 241 147 253 104 30 190 230 137 195 36 114 26 131 216 71 212 11 106 28 196 60 54 72 146 239 200 188 162 138 73 218 238 16 75 214 173 33 232 45 82 182 144 130 247 149 177 57 244 Sep 30 14:41:48 x /kernel: sg[0] - Addr 0x26c6f000 : Length 4096 Sep 30 14:41:48 x /kernel: sg[1] - Addr 0x307f0000 : Length 4096 Sep 30 14:41:48 x /kernel: (da3:ahc0:0:3:0): Queuing a BDR SCB Sep 30 14:41:48 x /kernel: (da3:ahc0:0:3:0): Bus Device Reset Message Sent Sep 30 14:41:48 x /kernel: (da3:ahc0:0:3:0): no longer in timeout, status = 34b Sep 30 14:41:48 x /kernel: ahc0: Bus Device Reset on A:3. 1 SCBs aborted Boot messages: ahc0: <Adaptec aic7899 Ultra160 SCSI adapter> port 0xcc00-0xccff mem 0xfe7ff000-0xfe7fffff irq 5 at device 4.0 on pci3 aic7899: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs ahc1: <Adaptec aic7899 Ultra160 SCSI adapter> port 0xc800-0xc8ff mem 0xfe7fe000-0xfe7fefff irq 11 at device 4.1 on pci3 aic7899: Ultra160 Wide Channel B, SCSI Id=7, 32/253 SCBs pass4 at ahc0 bus 0 target 6 lun 0 pass4: <DELL 1x4 U2W SCSI BP 1.30> Fixed Processor SCSI-2 device pass4: 3.300MB/s transfers da0 at ahc0 bus 0 target 0 lun 0 da0: <HITACHI DK32DJ-18MC D4D4> Fixed Direct Access SCSI-3 device da0: 160.000MB/s transfers (80.000MHz, offset 100, 16bit), Tagged Queueing Enabled da0: 17366MB (35566478 512 byte sectors: 255H 63S/T 2213C) da1 at ahc0 bus 0 target 1 lun 0 da1: <HITACHI DK32DJ-36MC D4D4> Fixed Direct Access SCSI-3 device da1: 160.000MB/s transfers (80.000MHz, offset 100, 16bit), Tagged Queueing Enabled da1: 34732MB (71132959 512 byte sectors: 255H 63S/T 4427C) da2 at ahc0 bus 0 target 2 lun 0 da2: <HITACHI DK32DJ-36MC D4D4> Fixed Direct Access SCSI-3 device da2: 160.000MB/s transfers (80.000MHz, offset 100, 16bit), Tagged Queueing Enabled da2: 34732MB (71132959 512 byte sectors: 255H 63S/T 4427C) da3 at ahc0 bus 0 target 3 lun 0 da3: <HITACHI DK32DJ-36MC D4D4> Fixed Direct Access SCSI-3 device da3: 160.000MB/s transfers (80.000MHz, offset 100, 16bit), Tagged Queueing Enabled da3: 34732MB (71132959 512 byte sectors: 255H 63S/T 4427C) To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-scsi" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi? <200210011217.aa29164>