Dump card states w/ increasing frequency (FBSD 4.8, dual Xeon)

Mike Hunter mhunter at ack.Berkeley.EDU
Wed Mar 9 16:54:21 PST 2005


Hi everybody,

About 3 weeks ago, one of my boxes started doing frequent (once or twice a
day) dump card states.  It's a fbsd machine running 4.8-RELEASE-p16.  This 
was a machine that did a lot of dump card states right when I bought it,
which was tracked down to an incompatibility with the builtin adaptec
controller, and I switched to an add-on card (29160/N/LP Ultra160 (AIC-7892A) 
SCSI Host Adapter).  I haven't updated the OS recently so it seems
unlikely that it's FBSD related, unless increasing load on the box is
revealing a previously unseen issue.

All the dump card states relate to da2, a 70G seagate disk.

Would a disk that's flaking out give these kinds of messages, or is this
some kind of controller / OS issue?  I'm wondering if I should try
replacing the disk or if there any other causes I can investigate.

Thanks for any advice,

Mike

Mar  9 09:55:08 mybox0r /kernel: (da2:ahc0:0:2:0): SCB 0x14 - timed out
Mar  9 09:55:23 mybox0r /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
Mar  9 09:55:23 mybox0r /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x8
Mar  9 09:55:23 mybox0r /kernel: Card was paused
Mar  9 09:55:23 mybox0r /kernel: ACCUM = 0x0, SINDEX = 0x3e, DINDEX = 0xe4, ARG_2 = 0x0
Mar  9 09:55:23 mybox0r /kernel: HCNT = 0x0 SCBPTR = 0x1e
Mar  9 09:55:23 mybox0r /kernel: SCSIPHASE[0x0] SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] 
Mar  9 09:55:23 mybox0r /kernel: LASTPHASE[0x1]:(P_BUSFREE) SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI) 
Mar  9 09:55:23 mybox0r /kernel: SBLKCTL[0x6]:(SELWIDE|ENAB20) SCSIRATE[0x0] SEQCTL[0x10]:(FASTMODE) 
Mar  9 09:55:23 mybox0r /kernel: SEQ_FLAGS[0xc0]:(NO_CDB_SENT|NOT_IDENTIFIED) SSTAT0[0x0] 
Mar  9 09:55:23 mybox0r /kernel: SSTAT1[0x8]:(BUSFREE) SSTAT2[0x0] SSTAT3[0x0] SIMODE0[0x8]:(ENSWRAP) 
Mar  9 09:55:23 mybox0r /kernel: SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO) SXFRCTL0[0x80]:(DFON) 
Mar  9 09:55:23 mybox0r /kernel: DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) 
Mar  9 09:55:23 mybox0r /kernel: STACK: 0xe9 0x16b 0x111 0x3
Mar  9 09:55:23 mybox0r /kernel: SCB count = 110
Mar  9 09:55:23 mybox0r /kernel: Kernel NEXTQSCB = 16
Mar  9 09:55:23 mybox0r /kernel: Card NEXTQSCB = 16
Mar  9 09:55:23 mybox0r /kernel: QINFIFO entries: 
Mar  9 09:55:23 mybox0r /kernel: Waiting Queue entries: 
Mar  9 09:55:23 mybox0r /kernel: Disconnected Queue entries: 25:12 28:90 1:82 4:89 24:57 17:35 18:10 2:13 5:77 3:31 11:39 9:38 8:69 23:78 26:7 13:14 14:20 
Mar  9 09:55:23 mybox0r /kernel: QOUTFIFO entries: 
Mar  9 09:55:23 mybox0r /kernel: Sequencer Free SCB List: 30 12 19 27 22 15 10 21 20 16 31 0 7 6 29 
Mar  9 09:55:23 mybox0r /kernel: Sequencer SCB Info: 
Mar  9 09:55:23 mybox0r /kernel: 0 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 1 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x52] 
Mar  9 09:55:23 mybox0r /kernel: 2 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xd] 
Mar  9 09:55:23 mybox0r /kernel: 3 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x1f] 
Mar  9 09:55:23 mybox0r /kernel: 4 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x59] 
Mar  9 09:55:23 mybox0r /kernel: 5 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x4d] 
Mar  9 09:55:23 mybox0r /kernel: 6 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 7 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 8 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x45] 
Mar  9 09:55:23 mybox0r /kernel: 9 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x26] 
Mar  9 09:55:23 mybox0r /kernel: 10 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 11 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x27] 
Mar  9 09:55:23 mybox0r /kernel: 12 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 13 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xe] 
Mar  9 09:55:23 mybox0r /kernel: 14 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x14] 
Mar  9 09:55:23 mybox0r /kernel: 15 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 16 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 17 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x23] 
Mar  9 09:55:23 mybox0r /kernel: 18 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xa] 
Mar  9 09:55:23 mybox0r /kernel: 19 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 20 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 21 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 22 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 23 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x4e] 
Mar  9 09:55:23 mybox0r /kernel: 24 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x39] 
Mar  9 09:55:23 mybox0r /kernel: 25 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xc] 
Mar  9 09:55:23 mybox0r /kernel: 26 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x7] 
Mar  9 09:55:23 mybox0r /kernel: 27 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 28 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x5a] 
Mar  9 09:55:23 mybox0r /kernel: 29 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 30 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: 31 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] 
Mar  9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Mar  9 09:55:23 mybox0r /kernel: Pending list: 
Mar  9 09:55:23 mybox0r /kernel: 12 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 90 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 82 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 89 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 57 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 35 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 10 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 13 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 77 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 31 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 39 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 38 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 69 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 78 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 7 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 14 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: 20 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] 
Mar  9 09:55:23 mybox0r /kernel: Kernel Free SCB list: 62 36 4 8 43 6 29 19 1 32 15 74 30 83 50 37 47 73 54 88 105 79 42 86 5 99 68 94 28 26 11 80 84 95 18 25 81 55 109 91 58 92 44 85 61 64 21 87 75 66 60 65 52 49 24 9 45 40 53 96 22 23 98 27 104 34 56 17 106 2 59 71 107 67 93 72 63 97 3 108 33 51 70 76 46 41 0 48 103 102 101 100 
Mar  9 09:55:23 mybox0r /kernel: 
Mar  9 09:55:23 mybox0r /kernel: <<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
Mar  9 09:55:23 mybox0r /kernel: sg[0] - Addr 0xe421a000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[1] - Addr 0x73dbb000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[2] - Addr 0xb741c000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[3] - Addr 0x28a9d000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[4] - Addr 0x6837e000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[5] - Addr 0x67d1f000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[6] - Addr 0xeda20000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[7] - Addr 0xae41000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[8] - Addr 0xd7f42000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[9] - Addr 0xdb2e3000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[10] - Addr 0xa0ba4000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[11] - Addr 0x74105000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[12] - Addr 0x96e26000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[13] - Addr 0x36b47000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[14] - Addr 0xd348000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: sg[15] - Addr 0x8cd69000 : Length 4096
Mar  9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): Queuing a BDR SCB
Mar  9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): Bus Device Reset Message Sent
Mar  9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): no longer in timeout, status = 34b
Mar  9 09:55:23 mybox0r /kernel: ahc0: Bus Device Reset on A:2. 17 SCBs aborted

----- End forwarded message -----


More information about the freebsd-scsi mailing list