thr3ads.net - freebsd stable - aic7896 SCB timeout - is this a sign of impending doom? [Oct 2003]

If this information is useful, please help other people find it:
Share via:

Scott Mitchell

2003-Oct-22 14:29 UTC

aic7896 SCB timeout - is this a sign of impending doom?

Hi all,

The attached messages appeared on one of my servers this afternoon.  Its
primarily a CVS server but beyond that I can't say what it was doing or how
busy it was when this happened, as I didn't see the messages until later.

Is this anything to worry about?  AFAICT some command to da4 timed out
(maybe twice?) and the driver eventually reset the bus to get things going
again... this has never happened before, and the machine seems perfectly
fine now.

The machine is running 4.8 on an Intel L440GX+ server board.

Cheers,

	Scott

Attached: dmesg.boot and /var/log/messages extract

-- 
==========================================================================Scott
Mitchell           | PGP Key ID | "Eagles may soar, but weasels
Cambridge, England       | 0x54B171B9 |  don't get sucked into jet
engines"
scott at fishballoon.org | 0xAA775B8B |      -- Anon
-------------- next part --------------
Copyright (c) 1992-2003 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD 4.8-RELEASE #0: Thu Apr  3 10:53:38 GMT 2003
    root@freebsd-stable.sentex.ca:/usr/obj/usr/src/sys/GENERIC
Timecounter "i8254"  frequency 1193182 Hz
CPU: Intel Pentium III (696.41-MHz 686-class CPU)
  Origin = "GenuineIntel"  Id = 0x683  Stepping = 3
 
Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
real memory  = 1073676288 (1048512K bytes)
avail memory = 1039826944 (1015456K bytes)
Preloaded elf kernel "kernel" at 0xc051d000.
Pentium Pro MTRR support enabled
md0: Malloc disk
Using $PIR table, 12 entries at 0xc00fdf00
npx0: <math processor> on motherboard
npx0: INT 16 interface
pcib0: <Intel 82443GX host to PCI bridge> on motherboard
pci0: <PCI bus> on pcib0
agp0: <Intel 82443GX host to PCI bridge> mem 0xf8000000-0xfbffffff at
device 0.0 on pci0
pcib2: <Intel 82443GX (440 GX) PCI-PCI (AGP) bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib2
pcib3: <PCI to PCI bridge (vendor=1011 device=0023)> at device 15.0 on
pci1
pci2: <PCI bus> on pcib3
ahc0: <Adaptec aic7896/97 Ultra2 SCSI adapter> port 0x2000-0x20ff mem
0xf4100000-0xf4100fff irq 11 at device 12.0 on pci0
aic7896/97: Ultra2 Wide Channel A, SCSI Id=7, 32/253 SCBs
ahc1: <Adaptec aic7896/97 Ultra2 SCSI adapter> port 0x2400-0x24ff mem
0xf4101000-0xf4101fff irq 11 at device 12.1 on pci0
aic7896/97: Ultra2 Wide Channel B, SCSI Id=7, 32/253 SCBs
fxp0: <Intel Pro 10/100B/100+ Ethernet> port 0x2800-0x283f mem
0xf4000000-0xf40fffff,0xf4102000-0xf4102fff irq 10 at device 14.0 on pci0
fxp0: Ethernet address 00:d0:b7:89:8f:06
inphy0: <i82555 10/100 media interface> on miibus0
inphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
isab0: <Intel 82371AB PCI to ISA bridge> at device 18.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 ATA33 controller> port 0x2860-0x286f at device 18.1
on pci0
ata0: at 0x1f0 irq 14 on atapci0
ata1: at 0x170 irq 15 on atapci0
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0x2840-0x285f irq 10
at device 18.2 on pci0
usb0: <Intel 82371AB/EB (PIIX4) USB controller> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
chip0: <Intel 82371AB Power management controller> port 0x1040-0x104f at
device 18.3 on pci0
pci0: <Cirrus Logic GD5480 SVGA controller> at 20.0
pcib1: <Intel 82443GX host to AGP bridge> on motherboard
pci3: <PCI bus> on pcib1
orm0: <Option ROMs> at iomem
0xc0000-0xc7fff,0xc8000-0xcdfff,0xce000-0xcf7ff on isa0
fdc0: <NEC 72065B or clone> at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model Generic PS/2 mouse, device ID 0
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A
sio1 at port 0x2f8-0x2ff irq 3 on isa0
sio1: type 16550A
ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
ppc0: Generic chipset (ECP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/8 bytes threshold
plip0: <PLIP network interface> on ppbus0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
ad0: 19458MB <QUANTUM FIREBALLP AS20> [39535/16/63] at ata0-master UDMA33
acd0: CDROM <TOSHIBA CD-ROM XM-6702B> at ata1-master PIO4
Waiting 15 seconds for SCSI devices to settle
(probe5:ahc0:0:6:1): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe5:ahc0:0:6:2): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe5:ahc0:0:6:3): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe5:ahc0:0:6:4): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe5:ahc0:0:6:5): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe0:ahc0:0:6:6): Unexpected busfree in Message-out phase
SEQADDR == 0x172
(probe0:ahc0:0:6:7): Unexpected busfree in Message-out phase
SEQADDR == 0x172
sa0 at ahc1 bus 0 target 3 lun 0
sa0: <HP C5683A C908> Removable Sequential Access SCSI-2 device 
sa0: 40.000MB/s transfers (20.000MHz, offset 32, 16bit)
pass5 at ahc0 bus 0 target 6 lun 0
pass5: <ESG-SHV SCA HSBP M13 0.05> Fixed Processor SCSI-2 device 
pass5: 3.300MB/s transfers
da0 at ahc0 bus 0 target 0 lun 0
da0: <IBM DDYS-T18350M S80D> Fixed Direct Access SCSI-3 device 
da0: 80.000MB/s transfers (40.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da0: 17501MB (35843670 512 byte sectors: 255H 63S/T 2231C)
da1 at ahc0 bus 0 target 1 lun 0
da1: <IBM DPSS-318350M S80D> Fixed Direct Access SCSI-3 device 
da1: 80.000MB/s transfers (40.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da1: 17501MB (35843670 512 byte sectors: 255H 63S/T 2231C)
da2 at ahc0 bus 0 target 2 lun 0
da2: <IBM DPSS-318350M S80D> Fixed Direct Access SCSI-3 device 
da2: 80.000MB/s transfers (40.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da2: 17501MB (35843670 512 byte sectors: 255H 63S/T 2231C)
da3 at ahc0 bus 0 target 3 lun 0
da3: <IBM DPSS-318350M S80D> Fixed Direct Access SCSI-3 device 
da3: 80.000MB/s transfers (40.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da3: 17501MB (35843670 512 byte sectors: 255H 63S/T 2231C)
da4 at ahc0 bus 0 target 4 lun 0
da4: <IBM DPSS-318350M S80D> Fixed Direct Access SCSI-3 device 
da4: 80.000MB/s transfers (40.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da4: 17501MB (35843670 512 byte sectors: 255H 63S/T 2231C)
Mounting root from ufs:/dev/ad0s1a
md1: Malloc disk
-------------- next part --------------
Oct 22 17:34:14 pukeko /kernel: (da4:ahc0:0:4:0): SCB 0x42 - timed out
Oct 22 17:34:14 pukeko /kernel:
>>>>>>>>>>>>>>>>>> Dump
Card State Begins
<<<<<<<<<<<<<<<<<
Oct 22 17:34:14 pukeko /kernel: ahc0: Dumping Card State while idle, at SEQADDR
0x8
Oct 22 17:34:14 pukeko /kernel: Card was paused
Oct 22 17:34:14 pukeko /kernel: ACCUM = 0x0, SINDEX = 0x19, DINDEX = 0xe4, ARG_2
= 0x0
Oct 22 17:34:14 pukeko /kernel: HCNT = 0x0 SCBPTR = 0xb
Oct 22 17:34:14 pukeko /kernel: SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0]
LASTPHASE[0x1]:(P_BUSFREE)
Oct 22 17:34:14 pukeko /kernel: SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI)
SBLKCTL[0xa]:(SELWIDE|SELBUSB)
Oct 22 17:34:14 pukeko /kernel: SCSIRATE[0x0] SEQCTL[0x10]:(FASTMODE)
SEQ_FLAGS[0xc0]:(NO_CDB_SENT|NOT_IDENTIFIED)
Oct 22 17:34:14 pukeko /kernel: SSTAT0[0x0] SSTAT1[0xa]:(PHASECHG|BUSFREE)
SSTAT2[0x0]
Oct 22 17:34:14 pukeko /kernel: SSTAT3[0x0] SIMODE0[0x8]:(ENSWRAP)
SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO)
Oct 22 17:34:14 pukeko /kernel: SXFRCTL0[0x80]:(DFON) DFCNTRL[0x0]
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Oct 22 17:34:14 pukeko /kernel: STACK: 0xd9 0x16a 0x110 0x3
Oct 22 17:34:14 pukeko /kernel: SCB count = 150
Oct 22 17:34:14 pukeko /kernel: Kernel NEXTQSCB = 119
Oct 22 17:34:14 pukeko /kernel: Card NEXTQSCB = 119
Oct 22 17:34:14 pukeko /kernel: QINFIFO entries: 
Oct 22 17:34:14 pukeko /kernel: Waiting Queue entries: 
Oct 22 17:34:14 pukeko /kernel: Disconnected Queue entries: 21:106 16:66 
Oct 22 17:34:14 pukeko /kernel: QOUTFIFO entries: 
Oct 22 17:34:14 pukeko /kernel: Sequencer Free SCB List: 11 25 31 28 30 12 18 22
4 2 14 15 26 17 0 19 7 24 13 9 6 5 20 27 8 10 23 3 1 29
Oct 22 17:34:14 pukeko /kernel: Sequencer SCB Info: 
Oct 22 17:34:14 pukeko /kernel: 0 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 1 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 2 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 3 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 4 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x17]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 5 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 6 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 7 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 8 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 9 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 10
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 11
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 12
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 13
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 14
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 15
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 16
SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0x42] 
Oct 22 17:34:14 pukeko /kernel: 17
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 18
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 19
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 20
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 21
SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0x6a] 
Oct 22 17:34:14 pukeko /kernel: 22
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 23
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 24
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 25
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 26
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 27
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 28
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 29
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 30
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: 31
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:14 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:14 pukeko /kernel: Pending list: 
Oct 22 17:34:14 pukeko /kernel: 106 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB)
SCB_SCSIID[0x47] SCB_LUN[0x0]
Oct 22 17:34:14 pukeko /kernel: 66 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB)
SCB_SCSIID[0x47] SCB_LUN[0x0]
Oct 22 17:34:14 pukeko /kernel: Kernel Free SCB list: 25 48 0 67 132 83 61 4 109
40 51 131 11 133 130 128 26 95 144 62 146 93 107 120 127 81 104 44 149 52 123 53
78 94 101 136 47 38 75 90 15 1 111 116 96 147 145 137 2 97 65 59 117 139 112 148
91 32 68 28 16 82 143 42 39 79 56 18 98 113 121 63 100 126 114 69 108 118 30 5
17 33 3 102 87 29 57 60 74 7 21 135 22 77 12 105 23 20 31 70 86 10 35 34 37 43
124 73 110 88 27 50 14 142 125 92 134 80 49 138 122 9 8 89 6 55 129 13 84 41 58
19 46 76 24 85 115 99 72 64 71 45 36 54 103 141 140
Oct 22 17:34:14 pukeko /kernel: 
Oct 22 17:34:14 pukeko /kernel:
<<<<<<<<<<<<<<<< Dump Card State
Ends >>>>>>>>>>>>>>>>>>
Oct 22 17:34:14 pukeko /kernel: sg[0] - Addr 0x210b1000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[1] - Addr 0x205b2000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[2] - Addr 0x1d673000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[3] - Addr 0x3b914000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[4] - Addr 0x1bb55000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[5] - Addr 0x2d656000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[6] - Addr 0x85d7000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[7] - Addr 0x239f8000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[8] - Addr 0x3ba79000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[9] - Addr 0x3c41a000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[10] - Addr 0x3a5db000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[11] - Addr 0xe87c000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[12] - Addr 0x2f9bd000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: sg[13] - Addr 0x3c5fe000 : Length 4096
Oct 22 17:34:14 pukeko /kernel: (da4:ahc0:0:4:0): Queuing a BDR SCB
Oct 22 17:34:16 pukeko /kernel: (da4:ahc0:0:4:0): SCB 0x42 - timed out
Oct 22 17:34:16 pukeko /kernel:
>>>>>>>>>>>>>>>>>> Dump
Card State Begins
<<<<<<<<<<<<<<<<<
Oct 22 17:34:16 pukeko /kernel: ahc0: Dumping Card State while idle, at SEQADDR
0x172
Oct 22 17:34:16 pukeko /kernel: Card was paused
Oct 22 17:34:16 pukeko /kernel: ACCUM = 0x42, SINDEX = 0x48, DINDEX = 0xe4,
ARG_2 = 0x10
Oct 22 17:34:16 pukeko /kernel: HCNT = 0x0 SCBPTR = 0x10
Oct 22 17:34:16 pukeko /kernel: SCSISIGI[0x14]:(BSYI|ATNI) ERROR[0x0]
SCSIBUSL[0x0]
Oct 22 17:34:16 pukeko /kernel: LASTPHASE[0x1]:(P_BUSFREE)
SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI)
Oct 22 17:34:16 pukeko /kernel: SBLKCTL[0xa]:(SELWIDE|SELBUSB)
SCSIRATE[0x93]:(SINGLE_EDGE|WIDEXFER)
Oct 22 17:34:16 pukeko /kernel: SEQCTL[0x10]:(FASTMODE)
SEQ_FLAGS[0x40]:(NO_CDB_SENT)
Oct 22 17:34:16 pukeko /kernel: SSTAT0[0x0] SSTAT1[0x2]:(PHASECHG) SSTAT2[0x0]
SSTAT3[0x0]
Oct 22 17:34:16 pukeko /kernel: SIMODE0[0x8]:(ENSWRAP)
SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO)
Oct 22 17:34:16 pukeko /kernel: SXFRCTL0[0x88]:(SPIOEN|DFON) DFCNTRL[0x0]
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Oct 22 17:34:16 pukeko /kernel: STACK: 0x35 0xd9 0x16a 0x180
Oct 22 17:34:16 pukeko /kernel: SCB count = 150
Oct 22 17:34:16 pukeko /kernel: Kernel NEXTQSCB = 119
Oct 22 17:34:16 pukeko /kernel: Card NEXTQSCB = 119
Oct 22 17:34:16 pukeko /kernel: QINFIFO entries: 
Oct 22 17:34:16 pukeko /kernel: Waiting Queue entries: 
Oct 22 17:34:16 pukeko /kernel: Disconnected Queue entries: 21:106 
Oct 22 17:34:16 pukeko /kernel: QOUTFIFO entries: 
Oct 22 17:34:16 pukeko /kernel: Sequencer Free SCB List: 11 25 31 28 30 12 18 22
4 2 14 15 26 17 0 19 7 24 13 9 6 5 20 27 8 10 23 3 1 29
Oct 22 17:34:16 pukeko /kernel: Sequencer SCB Info: 
Oct 22 17:34:16 pukeko /kernel: 0 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 1 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 2 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 3 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 4 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x17]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 5 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 6 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 7 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 8 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 9 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB)
SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 10
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 11
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 12
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 13
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 14
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 15
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 16
SCB_CONTROL[0x74]:(DISCONNECTED|MK_MESSAGE|TAG_ENB|DISCENB)
Oct 22 17:34:16 pukeko /kernel: SCB_SCSIID[0x47] SCB_LUN[0x0] SCB_TAG[0x42] 
Oct 22 17:34:16 pukeko /kernel: 17
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 18
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 19
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 20
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x37]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 21
SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0x6a] 
Oct 22 17:34:16 pukeko /kernel: 22
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 23
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 24
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 25
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 26
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 27
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 28
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 29
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 30
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x47]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: 31
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7]
Oct 22 17:34:16 pukeko /kernel: SCB_LUN[0x0] SCB_TAG[0xff] 
Oct 22 17:34:16 pukeko /kernel: Pending list: 
Oct 22 17:34:16 pukeko /kernel: 106 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB)
SCB_SCSIID[0x47] SCB_LUN[0x0]
Oct 22 17:34:16 pukeko /kernel: 66
SCB_CONTROL[0x74]:(DISCONNECTED|MK_MESSAGE|TAG_ENB|DISCENB)
Oct 22 17:34:16 pukeko /kernel: SCB_SCSIID[0x47] SCB_LUN[0x0] 
Oct 22 17:34:16 pukeko /kernel: Kernel Free SCB list: 25 48 0 67 132 83 61 4 109
40 51 131 11 133 130 128 26 95 144 62 146 93 107 120 127 81 104 44 149 52 123 53
78 94 101 136 47 38 75 90 15 1 111 116 96 147 145 137 2 97 65 59 117 139 112 148
91 32 68 28 16 82 143 42 39 79 56 18 98 113 121 63 100 126 114 69 108 118 30 5
17 33 3 102 87 29 57 60 74 7 21 135 22 77 12 105 23 20 31 70 86 10 35 34 37 43
124 73 110 88 27 50 14 142 125 92 134 80 49 138 122 9 8 89 6 55 129 13 84 41 58
19 46 76 24 85 115 99 72 64 71 45 36 54 103 141 140
Oct 22 17:34:16 pukeko /kernel: 
Oct 22 17:34:16 pukeko /kernel:
<<<<<<<<<<<<<<<< Dump Card State
Ends >>>>>>>>>>>>>>>>>>
Oct 22 17:34:16 pukeko /kernel: sg[0] - Addr 0x210b1000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[1] - Addr 0x205b2000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[2] - Addr 0x1d673000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[3] - Addr 0x3b914000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[4] - Addr 0x1bb55000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[5] - Addr 0x2d656000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[6] - Addr 0x85d7000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[7] - Addr 0x239f8000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[8] - Addr 0x3ba79000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[9] - Addr 0x3c41a000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[10] - Addr 0x3a5db000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[11] - Addr 0xe87c000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[12] - Addr 0x2f9bd000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: sg[13] - Addr 0x3c5fe000 : Length 4096
Oct 22 17:34:16 pukeko /kernel: (da4:ahc0:0:4:0): no longer in timeout, status =
34b
Oct 22 17:34:16 pukeko /kernel: ahc0: Issued Channel A Bus Reset. 2 SCBs aborted

Eric Pogroski

2003-Oct-22 15:04 UTC

head link

aic7896 SCB timeout - is this a sign of impending doom?

On Wed, 22 Oct 2003 22:25:56 +0100
Scott Mitchell <scott+freebsd@fishballoon.org> wrote:
> Hi all,
> 
> The attached messages appeared on one of my servers this afternoon. 
> Its primarily a CVS server but beyond that I can't say what it was
> doing or how busy it was when this happened, as I didn't see the
> messages until later.
> 
> Is this anything to worry about?  AFAICT some command to da4 timed out
> (maybe twice?) and the driver eventually reset the bus to get things
> going again... this has never happened before, and the machine seems
> perfectly fine now.
> 
> The machine is running 4.8 on an Intel L440GX+ server board.
> 
> Cheers,
> 
> 	Scott
> 
> Attached: dmesg.boot and /var/log/messages extract
> 
> -- 
> ====================================================================>
====== Scott Mitchell           | PGP Key ID | "Eagles may soar, but
> weasels Cambridge, England       | 0x54B171B9 |  don't get sucked into
> jet engines" scott at fishballoon.org | 0xAA775B8B |      -- Anon
> 
Hi. I've seen my adaptec card's do this when they're overheated. My
solution was to just leave it alone, seeing as it broke nothing. Now I
keep the internal temperature of the case a little more moderate, and
I've yet to see the error's again.

Doug White

2003-Oct-22 15:40 UTC

head link

aic7896 SCB timeout - is this a sign of impending doom?

On Wed, 22 Oct 2003, Scott Mitchell wrote:
> The attached messages appeared on one of my servers this afternoon.  Its
> primarily a CVS server but beyond that I can't say what it was doing or
how
> busy it was when this happened, as I didn't see the messages until
later.
>
> Is this anything to worry about?  AFAICT some command to da4 timed out
> (maybe twice?) and the driver eventually reset the bus to get things going
> again... this has never happened before, and the machine seems perfectly
> fine now.
As mentioned, SCSI timeouts can have multiple causes, most of them not
good.  The thing to watch for is which target the command timed out on.
Command timeouts can come from:

. Bad cabling or termination
. Bad cabling or termination
. Bad cabling or termination (it needs to be said three times)
. Flakey/failing device

If it continues to happen then you should take a look around.  Temperature
wouldn't be a bad thing to check anyway.
> The machine is running 4.8 on an Intel L440GX+ server board.
Good boards. I'm using them as my build farm right now.  600MHz procs
aren't that fast anymore but its a solid machine.

-- 
Doug White                    |  FreeBSD: The Power to Serve
dwhite@gumbysoft.com          |  www.FreeBSD.org

freebsd stable - Oct 2003 - aic7896 SCB timeout - is this a sign of impending doom?

aic7896 SCB timeout - is this a sign of impending doom?

aic7896 SCB timeout - is this a sign of impending doom?

aic7896 SCB timeout - is this a sign of impending doom?