Date: Wed, 2 Jul 1997 03:41:43 +0200 (CEST) From: Tor Egge <Tor.Egge@idi.ntnu.no> To: FreeBSD-gnats-submit@FreeBSD.ORG Cc: gibbs@FreeBSD.ORG Subject: i386/4006: panic: ahc_intr: AWAITING_MSG for an SCB that does not have a waiting message Message-ID: <199707020141.DAA00954@skarven.itea.ntnu.no> Resent-Message-ID: <199707020150.SAA06423@hub.freebsd.org>
next in thread | raw e-mail | index | archive | help
>Number: 4006
>Category: i386
>Synopsis: panic: ahc_intr: AWAITING_MSG for an SCB that does not have a waiting message
>Confidential: no
>Severity: serious
>Priority: medium
>Responsible: freebsd-bugs
>State: open
>Class: sw-bug
>Submitter-Id: current-users
>Arrival-Date: Tue Jul 1 18:50:01 PDT 1997
>Last-Modified:
>Originator: Tor Egge
>Organization:
Norwegian University of Science and Technology, Trondheim, Norway
>Release: FreeBSD 3.0-CURRENT i386
>Environment:
FreeBSD skarven.itea.ntnu.no 3.0-CURRENT FreeBSD 3.0-CURRENT #2: Mon Jun 23 02:07:50 CEST 1997 root@skarven.itea.ntnu.no:/usr/src/sys/compile/SKARVEN_SMP i386
(Using sources from Jun 21)
options AHC_TAGENABLE
options AHC_SCBPAGING_ENABLE
options AHC_ALLOW_MEMIO
>Description:
During recovery from scsi errors, sometimes the sequencer and
the driver does not agree upon what scbs are currently active.
This causes a panic.
-----
Jul 2 01:30:30 skarven xntpd[109]: time reset (step) 1.278202 s
Jul 2 01:34:41 skarven /kernel: sd11: SCB 0x14 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0
Jul 2 01:34:41 skarven /kernel: SEQADDR = 0x7 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa
Jul 2 01:34:41 skarven /kernel: Ordered Tag queued
Jul 2 01:34:41 skarven /kernel: sd11: SCB 0x17 timedout while recovery in progress
Jul 2 01:34:41 skarven /kernel: sd12: SCB 0xa timedout while recovery in progress
Jul 2 01:34:41 skarven /kernel: sd12: SCB 0x3 timedout while recovery in progress
Jul 2 01:34:41 skarven /kernel: Ordered Tag sent
Jul 2 01:34:46 skarven /kernel: sd11: SCB 0x14 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0
Jul 2 01:34:46 skarven /kernel: SEQADDR = 0x4 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa
Jul 2 01:34:46 skarven /kernel: sd11: Queueing an Abort SCB
Jul 2 01:34:46 skarven /kernel: sd11: Abort Message Sent
Jul 2 01:34:46 skarven /kernel: sd11: SCB 20 - Abort Tag Completed.
Jul 2 01:34:46 skarven /kernel: sd11: no longer in timeout
Jul 2 01:34:51 skarven /kernel: sd12: SCB 0x3 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0
Jul 2 01:34:51 skarven /kernel: SEQADDR = 0x5 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0xa
Jul 2 01:34:51 skarven /kernel: Ordered Tag queued
Jul 2 01:34:51 skarven /kernel: sd12: SCB 0xa timedout while recovery in progress
Jul 2 01:34:51 skarven /kernel: sd11: SCB 0x17 timedout while recovery in progress
Jul 2 01:34:51 skarven /kernel: Ordered Tag sent
Jul 2 01:34:56 skarven /kernel: sd12: SCB 0x3 - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0xe6
Jul 2 01:34:56 skarven /kernel: SEQADDR = 0x4 SCSISEQ = 0x12 SSTAT0 = 0x27 SSTAT1 = 0xb
Jul 2 01:34:56 skarven /kernel: sd12: Queueing an Abort SCB
Jul 2 01:34:56 skarven /kernel: sd12: Abort Message Sent
Jul 2 01:34:57 skarven /kernel: sd12: SCB 3 - Abort Tag Completed.
Jul 2 01:34:57 skarven /kernel: sd12: no longer in timeout
Jul 2 01:34:57 skarven xntpd[109]: time reset (step) 0.430923 s
Jul 2 01:35:02 skarven /kernel: sd11: SCB 0x17 - timed out in command phase, SCSISIGI == 0x44
Jul 2 01:35:02 skarven /kernel: SEQADDR = 0x4f SCSISEQ = 0x12 SSTAT0 = 0x7 SSTAT1 = 0x13
Jul 2 01:35:02 skarven /kernel: Ordered Tag queued
Jul 2 01:35:02 skarven /kernel: sd12: SCB 0xa timedout while recovery in progress
Jul 2 01:35:02 skarven /kernel: Ordered Tag sent
Jul 2 01:35:07 skarven /kernel: sd11: SCB 0x17 - timed out in command phase, SCSISIGI == 0x84
Jul 2 01:35:07 skarven /kernel: SEQADDR = 0x4f SCSISEQ = 0x12 SSTAT0 = 0x7 SSTAT1 = 0x13
Jul 2 01:35:07 skarven /kernel: sd6: abort message in message buffer
Jul 2 01:35:07 skarven /kernel: sd6: SCB 8 - Abort Tag Completed.
Jul 2 01:35:07 skarven /kernel: sd6: no longer in timeout
Jul 2 01:35:12 skarven /kernel: sd12: SCB 0xa - timed out in datain phase, SCSISIGI == 0x44
Jul 2 01:35:12 skarven /kernel: SEQADDR = 0x128 SCSISEQ = 0x12 SSTAT0 = 0x5 SSTAT1 = 0x3
Jul 2 01:35:12 skarven /kernel: Ordered Tag queued
Jul 2 01:35:12 skarven /kernel: Ordered Tag sent
Jul 2 01:35:17 skarven /kernel: sd12: SCB 0xa - timed out while idle, LASTPHASE == 0x1, SCSISIGI == 0x0
Jul 2 01:45:06 skarven /kernel: URRENT #2: Mon Jun 23 02:07:50 CEST 1997
Jul 2 01:45:06 skarven /kernel: root@skarven.itea.ntnu.no:/usr/src/sys/compile/SKARVEN_SMP
-----
(kgdb) where
#0 boot (howto=260) at ../../kern/kern_shutdown.c:266
#1 0xe0117676 in panic (
fmt=0xe01f07db "ahc_intr: AWAITING_MSG for an SCB that does not have a waiting message") at ../../kern/kern_shutdown.c:393
#2 0xe01f173e in ahc_handle_seqint (ahc=0xe2f9f000, intstat=161 '¡')
at ../../i386/scsi/aic7xxx.c:1429
#3 0xe01f03ce in ahc_intr (arg=0xe2f9f000) at ../../i386/scsi/aic7xxx.c:854
#4 0xe01cce7c in splx (ipl=0) at ../../i386/isa/ipl_funcs.c:93
#5 0xe0119145 in tsleep (ident=0xe6fdb488, priority=16,
wmesg=0xe0131c9b "biowait", timo=0) at ../../kern/kern_synch.c:329
#6 0xe0131ccb in biowait (bp=0xe6fdb488) at ../../kern/vfs_bio.c:1720
#7 0xe012ff34 in bread (vp=0xe3040800, blkno=131152, size=8192, cred=0x0,
bpp=0xe9462d0c) at ../../kern/vfs_bio.c:291
#8 0xe01a101c in ffs_update (ap=0xe9462d44) at ../../ufs/ffs/ffs_inode.c:131
#9 0xe01a34df in ffs_sync (mp=0xe2fa0600, waitfor=2, cred=0xe259b500,
p=0xe021c650) at vnode_if.h:1127
#10 0xe013769f in sync (p=0xe021c650, uap=0x0, retval=0x0)
at ../../kern/vfs_syscalls.c:480
#11 0xe0117251 in boot (howto=256) at ../../kern/kern_shutdown.c:203
#12 0xe0117676 in panic (
fmt=0xe01f07db "ahc_intr: AWAITING_MSG for an SCB that does not have a waiting message") at ../../kern/kern_shutdown.c:393
#13 0xe01f173e in ahc_handle_seqint (ahc=0xe2f9f000, intstat=161 '¡')
at ../../i386/scsi/aic7xxx.c:1429
#14 0xe01f03ce in ahc_intr (arg=0xe2f9f000) at ../../i386/scsi/aic7xxx.c:854
#15 0xe0158540 in tcp_input (m=0xe26e0f80, iphlen=20)
at ../../netinet/tcp_input.c:1300
#16 0xe015435e in ip_input (m=0xe26e0f80) at ../../netinet/ip_input.c:538
#17 0xe01543bb in ipintr () at ../../netinet/ip_input.c:559
#18 0xe01bea1d in ?? ()
#19 0xe01bbf2b in ?? ()
#20 0x89550000 in ?? ()
Cannot access memory at address 0xc3cd.
(kgdb) up
#1 0xe0117676 in panic (
fmt=0xe01f07db "ahc_intr: AWAITING_MSG for an SCB that does not have a waiting message") at ../../kern/kern_shutdown.c:393
(kgdb) up
#2 0xe01f173e in ahc_handle_seqint (ahc=0xe2f9f000, intstat=161 '¡')
at ../../i386/scsi/aic7xxx.c:1429
(kgdb) print ahc->activescbs
$33 = 0 '\000'
(k(kgdb) print scb
$34 = (struct scb *) 0xe2599a00
(kgdb) print *scb
$35 = {hscb = 0xe9c84020, links = {stqe_next = 0xe30dc3a0}, xs = 0xe3106980,
flags = SCB_FREE, ahc_dma = 0xe2fa1300, sense_cmd = {op_code = 3 '\003',
byte2 = 0 '\000', unused = "\000", length = 32 ' ', control = 0 '\000'},
sg_count = 2 '\002', position = 0 '\000'}
(kgdb) print *scb->hscb
$36 = {control = 0 '\000', tcl = 32 ' ', status = 0 '\000',
SG_segment_count = 2 '\002', SG_list_pointer = 58112,
residual_SG_segment_count = 0 '\000', residual_data_count = "\000\000",
data = 360587264, datalen = 4278194176, cmdpointer = 62339544,
cmdlen = 10 '\n', tag = 1 '\001', next = 255 'ÿ', prev = 0 '\000', pad = 0}
(kgdb) print 32>>4
$37 = 2
(kgdb) print ahc->scb_data->scbarray[1]
$38 = (struct scb *) 0xe2599a00
-----------
>How-To-Repeat:
This only seem to occur during high load on the SCSI bus.
>Fix:
>Audit-Trail:
>Unformatted:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?199707020141.DAA00954>