Dump Card State Begins ...

Sun Jul 27 13:04:05 PDT 2003

On Sat, Jul 26, 2003 at 12:03:48PM -0300, Marc G. Fournier wrote:
> 
> Hi ...
> 
>   Can someone tell me whether or not this is indicative of a hardware, or
> software, problem?  It happened a few times today, on two different
> drives, and it seem to "self-recover", since the server is still purring
> along without any noticeable problems:
> 
> neptune# grep "timed out" /var/log/messages
> Jul 25 03:52:51 neptune /kernel: (da2:ahd1:0:2:0): SCB 0x40 - timed out
> Jul 25 03:57:22 neptune /kernel: (da2:ahd1:0:2:0): SCB 0x18 - timed out
> Jul 25 03:58:53 neptune /kernel: (da1:ahd1:0:1:0): SCB 0x1e - timed out
> Jul 26 10:55:46 neptune /kernel: (da2:ahd1:0:2:0): SCB 0x39 - timed out
> 
>   The drives are all U320 Seagate Cheetah 70G ... no RAID involved, its
> just straight drives using the motherboard's onboard SCSI controller ...
> the motherboard is the Intel SE7501, in the SR2300 chassis ...
> 
>   It did it back on the 19th as well:
> 
> Jul 19 19:37:16 neptune /kernel: (da2:ahd1:0:2:0): SCB 0x46 - timed out
> Jul 19 19:38:46 neptune /kernel: (da1:ahd1:0:1:0): SCB 0x2d - timed out
> 

Time outs may be a case of bad cabling or termination.
Check them...

>   But again, appears to have recovered with no ill effects ...
> 
> 
> Jul 25 03:52:51 neptune /kernel: (da2:ahd1:0:2:0): SCB 0x40 - timed out
> Jul 25 03:53:06 neptune /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
> Jul 25 03:53:06 neptune /kernel: ahd1: Dumping Card State at program address 0x15 Mode 0x22
> Jul 25 03:53:06 neptune /kernel: Card was paused
> Jul 25 03:53:06 neptune /kernel: HS_MAILBOX[0x0] INTCTL[0xc0] SEQINTSTAT[0x0] SAVED_MODE[0x11]
> Jul 25 03:53:06 neptune /kernel: DFFSTAT[0x31] SCSISIGI[0x0] SCSIPHASE[0x0] SCSIBUS[0x0]
> Jul 25 03:53:06 neptune /kernel: LASTPHASE[0x1] SCSISEQ0[0x0] SCSISEQ1[0x12] SEQCTL0[0x10]
> Jul 25 03:53:06 neptune /kernel: SEQINTCTL[0x0] SEQ_FLAGS[0xc0] SEQ_FLAGS2[0x0] SSTAT0[0x0]
> Jul 25 03:53:06 neptune /kernel: SSTAT1[0x8] SSTAT2[0x0] SSTAT3[0x0] PERRDIAG[0x8]
> Jul 25 03:53:06 neptune /kernel: SIMODE1[0xa4] LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x0]
> Jul 25 03:53:06 neptune /kernel: LQOSTAT0[0x0] LQOSTAT1[0x0] LQOSTAT2[0x1]
> Jul 25 03:53:06 neptune /kernel:
> Jul 25 03:53:06 neptune /kernel: SCB Count = 96 CMDS_PENDING = 29 LASTSCB 0x22 CURRSCB 0x22 NEXTSCB 0xff00
> Jul 25 03:53:06 neptune /kernel: qinstart = 65252 qinfifonext = 65252
> Jul 25 03:53:06 neptune /kernel: QINFIFO:
> Jul 25 03:53:06 neptune /kernel: WAITING_TID_QUEUES:
> Jul 25 03:53:06 neptune /kernel: Pending list:
> Jul 25 03:53:06 neptune /kernel: 21 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:06 neptune /kernel: 29 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:06 neptune /kernel: 63 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:06 neptune /kernel: 65 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:06 neptune /kernel: 24 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:06 neptune /kernel: 10 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 15 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 47 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 59 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 26 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 77 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 54 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 42 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 57 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 55 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 92 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 78 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 12 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 27 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 28 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 32 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 95 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 53 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 25 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 52 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 1 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 38 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 93 FIFO_USE[0x0] SCB_CONTROL[0x62] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: 64 FIFO_USE[0x0] SCB_CONTROL[0x60] SCB_SCSIID[0x27]
> Jul 25 03:53:07 neptune /kernel: Total 29
> Jul 25 03:53:07 neptune /kernel: Kernel Free SCB list: 7 56 34 19 4 37 20 46 40 61 11 39 31 45 58 23 73 30 5 62 8 41 18 16 13 66 51 14 44 49 36 50 70 35 9 76 74 2 48 43 3 33 79 71 75 60 67 69 91 94 6 72 0 68 17 22 90 89 88 87 86 85 84 83 82 81 80
> Jul 25 03:53:07 neptune /kernel: Sequencer Complete DMA-inprog list:
> Jul 25 03:53:07 neptune /kernel: Sequencer Complete list:
> Jul 25 03:53:07 neptune /kernel: Sequencer DMA-Up and Complete list:
> Jul 25 03:53:07 neptune /kernel:
> Jul 25 03:53:07 neptune /kernel: ahd1: FIFO0 Free, LONGJMP == 0x80ff, SCB 0x22
> Jul 25 03:53:07 neptune /kernel: SEQIMODE[0x3f] SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]
> Jul 25 03:53:07 neptune /kernel: SG_CACHE_SHADOW[0x2] SG_STATE[0x0] DFFSXFRCTL[0x0]
> Jul 25 03:53:07 neptune /kernel: SOFFCNT[0x0] MDFFSTAT[0x5] SHADDR = 0x00, SHCNT = 0x0
> Jul 25 03:53:07 neptune /kernel: HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]
> Jul 25 03:53:07 neptune /kernel: ahd1: FIFO1 Free, LONGJMP == 0x8277, SCB 0x7
> Jul 25 03:53:07 neptune /kernel: SEQIMODE[0x3f] SEQINTSRC[0x0] DFCNTRL[0x4] DFSTATUS[0x89]
> Jul 25 03:53:07 neptune /kernel: SG_CACHE_SHADOW[0x2] SG_STATE[0x0] DFFSXFRCTL[0x0]
> Jul 25 03:53:07 neptune /kernel: SOFFCNT[0x0] MDFFSTAT[0x5] SHADDR = 0x00, SHCNT = 0x0
> Jul 25 03:53:07 neptune /kernel: HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]
> Jul 25 03:53:07 neptune /kernel: LQIN: 0x55 0x0 0x0 0x7 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
> Jul 25 03:53:07 neptune /kernel: ahd1: LQISTATE = 0x0, LQOSTATE = 0x0, OPTIONMODE = 0x42
> Jul 25 03:53:07 neptune /kernel: ahd1: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x1
> Jul 25 03:53:07 neptune /kernel: SIMODE0[0xc]
> Jul 25 03:53:07 neptune /kernel: CCSCBCTL[0x0]
> Jul 25 03:53:07 neptune /kernel: ahd1: REG0 == 0x22, SINDEX = 0x122, DINDEX = 0x102
> Jul 25 03:53:07 neptune /kernel: ahd1: SCBPTR == 0x7, SCB_NEXT == 0x49, SCB_NEXT2 == 0xfff1
> Jul 25 03:53:07 neptune /kernel: CDB 2a 0 7 80 a0 ca
> Jul 25 03:53:07 neptune /kernel: STACK: 0x125 0x125 0x125 0x257 0x257 0x257 0x29 0x15
> Jul 25 03:53:07 neptune /kernel: <<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
> Jul 25 03:53:07 neptune /kernel: Copied 18 bytes of sense data offset 12: 0x70 0x0 0x6 0x0 0x0 0x0 0x0 0xa 0x0 0x0 0x0 0x0 0x29 0x2 0x2 0x0 0x0 0x0
> Jul 25 03:53:07 neptune /kernel: Copied 18 bytes of sense data offset 12: 0x70 0x0 0x6 0x0 0x0 0x0 0x0 0xa 0x0 0x0 0x0 0x0 0x29 0x2 0x2 0x0 0x0 0x0
> Jul 25 03:53:07 neptune /kernel: Copied 18 bytes of sense data offset 12: 0x70 0x0 0x6 0x0 0x0 0x0 0x0 0xa 0x0 0x0 0x0 0x0 0x29 0x2 0x2 0x0 0x0 0x0
> _______________________________________________

This looks like, your drive da2 is daying.
I had the same sort of errors few weeks ago.
You may not be able to unmount the drives properly now. Try to boot into
single user mode and work on that drive from there. If you are lucky,
you will have a chance to get the data back.

Good luck,
	gregory
--
Grzegorz Czaplinski <gregory at prioris.mini.pw.edu.pl>
"The Power to Serve, Right for the Power Users!" - http://www.FreeBSD.org/
 Fingerprint: EB77 E19D CFA2 5736 810F  847C A70F A275 2489 469F
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 187 bytes
Desc: not available
Url : http://lists.freebsd.org/pipermail/freebsd-scsi/attachments/20030727/59f665a8/attachment.bin