6.0-BETA2 DRM/witness panic: Assertion j < 1000 failed at /usr/src/sys/kern/subr_witness.c:1513

From: Gavin Atkinson <gavin.atkinson_at_ury.york.ac.uk>
Date: Tue, 09 Aug 2005 12:57:02 +0100
Hi,

I have a regular panic (3 times a day) on a system running 6.0-BETA2
which was very stable under 5.4. dmesg available at
http://www.devrandom.co.uk/freebsd/dmesg-buffy-20050809 . The panic
seems to relate to DRM, I have a ATI Radeon QY RV100 7000/VE card.

The machine only ever panics while I'm running X and not in front of it
- it seems to be one of the X screen savers that triggers it. This time
it was "atlantis" from the xscreensaver-gnome-4.22 port, I don't know if
others also trigger it.  Most 3D screensavers complain of not seeing
interrupts from the video card, but under 5.4 this just meant they ran
slower.  I don't know if this is a contributing factor to the panic I
see - but the backtrace does suggest DRM was waiting for an interrupt.

Bizarrely, when it panics, it does not respond to any keypresses over
the serial console, but will happily respond to the USB keyboard plugged
into the machine.  I guess that means the machine is busy servicing
interrupts of a higher priority than the serial port but lower than the
USB port?

The machine is GENERIC (with witness, etc), with the addition of ehci
and without PREEMPTION.  Sources current as of yesterday.

panic: Assertion j < 1000 failed at /usr/src/sys/kern/subr_witness.c:1513
cpuid = 0
KDB: enter: panic
[thread pid 1310 tid 100164 ]
Stopped at      kdb_enter+0x2b: nop
db> tr
Tracing pid 1310 tid 100164 td 0xc3022180
kdb_enter(c08504a0) at kdb_enter+0x2b
panic(c084c7f5,c08544ff,c0853d8d,5e9,c0966694) at panic+0x127
isitmydescendant(c0928160,c0929f88) at isitmydescendant+0x55
witness_checkorder(c241d8c4,9,c0850c27,ee) at witness_checkorder+0x36f
_mtx_lock_flags(c241d8c4,0,c0850c27,ee,0) at _mtx_lock_flags+0x5b
msleep(c2410cf8,c241d8c4,154,c0aaef78,bb8) at msleep+0x3db
radeon_wait_irq(c241d800,20e,eb9c2c74,c0ac21c0,c2402300) at radeon_wait_irq+0xcf
radeon_irq_wait(c2402300,80046457,c26cd210,3,c3022180) at radeon_irq_wait+0x58
drm_ioctl(c2402300,80046457,c26cd210,3,c3022180) at drm_ioctl+0x294
devfs_ioctl_f(c33ba900,80046457,c26cd210,c2cbf080,c3022180) at devfs_ioctl_f+0x7
ioctl(c3022180,eb9c2d04,3,40,282) at ioctl+0x370
syscall(3b,bfbf003b,bfbf003b,80c7000,80c7000) at syscall+0x22f
Xint0x80_syscall() at Xint0x80_syscall+0x1f
--- syscall (54, FreeBSD ELF32, ioctl), eip = 0x28344b37, esp = 0xbfbfe0bc, ebp-
db> ps
  pid   proc     uid  ppid  pgrp  flag   stat  wmesg    wchan  cmd
 1310 c30d1a3c 56957   753   740 0004000 [CPU 0] atlantis
 1272 c33f6418 56957  1271  1271 0004002 [SLPQ select 0xc0966764][SLP] ssh
 1271 c30d0c48 56957   794  1271 0004002 [SLPQ pause 0xc30d0c7c][SLP] tcsh
 1259 c33f6624 56957  1258  1258 0004002 [SLPQ select 0xc0966764][SLP] ssh
 1258 c29dc418 56957   794  1258 0004002 [SLPQ pause 0xc29dc44c][SLP] tcsh
 1125 c33f6000 56957   794  1125 0004002 [SLPQ ttyin 0xc24a5410][SLP] tcsh
 1037 c30d0418 56957   854  1037 0004003 [SLPQ select 0xc0966764][SLP] opera
  987 c3020624 56957     1   740 000c081 (threaded)  evolution-alarm-not
   thread 0xc2ee8d80 ksegrp 0xc2996de0 [SLPQ kserel 0xc2996e14][SLP]
   thread 0xc30d6600 ksegrp 0xc2996de0 [SLPQ select 0xc0966764][SLP]
   thread 0xc3022a80 ksegrp 0xc2996de0 [SLPQ select 0xc0966764][SLP]
   thread 0xc3022d80 ksegrp 0xc2996300 [SLPQ ksesigwait 0xc302075c][SLP]
  984 c3020000 56957     1   740 000c081 (threaded)  evolution-exchange-
   thread 0xc30d6780 ksegrp 0xc2fff000 [SLPQ kserel 0xc2fff034][SLP]
   thread 0xc30d6c00 ksegrp 0xc2fff000 [SLPQ select 0xc0966764][SLP]
   thread 0xc30d6900 ksegrp 0xc2fff000 [SLPQ select 0xc0966764][SLP]
   thread 0xc30d5180 ksegrp 0xc29963c0 [SLPQ ksesigwait 0xc3020138][SLP]
  982 c301c624 56957     1   740 000c081 (threaded)  evolution-data-serv
   thread 0xc2ee9480 ksegrp 0xc2fff180 [SLPQ kserel 0xc2fff1b4][SLP]
   thread 0xc3401000 ksegrp 0xc2fff180 [SLPQ select 0xc0966764][SLP]
   thread 0xc3402480 ksegrp 0xc2fff180 [SLPQ select 0xc0966764][SLP]
   thread 0xc3402180 ksegrp 0xc2fff180 [SLPQ select 0xc0966764][SLP]
   thread 0xc300a300 ksegrp 0xc2996420 [SLPQ ksesigwait 0xc301c75c][SLP]
  980 c2cf4a3c 56957   859   980 000c083 (threaded)  evolution-2.2
   thread 0xc3022300 ksegrp 0xc2349420 [SLPQ kserel 0xc2349454][SLP]
   thread 0xc300bd80 ksegrp 0xc2349420 [SLPQ select 0xc0966764][SLP]
   thread 0xc300a180 ksegrp 0xc2349420 [SLPQ select 0xc0966764][SLP]
   thread 0xc3401c00 ksegrp 0xc2349420 [SLPQ select 0xc0966764][SLP]
   thread 0xc299d600 ksegrp 0xc2996360 [SLPQ ksesigwait 0xc2cf4b74][SLP]
  895 c30d4000 56957     1   826 0000001 [SLPQ select 0xc0966764][SLP] kdeinit
  881 c2994830    0   679   881 0004000 [SLPQ select 0xc0966764][SLP] bootpd
  874 c30d120c 56957   796   874 0004002 [SLPQ ttyin 0xc24c6410][SLP] tcsh
  859 c2cf7c48 56957   792   859 0004002 [SLPQ ttyin 0xc24a4810][SLP] tcsh
  854 c30d0a3c 56957   798   854 0004002 [SLPQ ttyin 0xc24ef410][SLP] tcsh
  848 c30d0830 56957     1   826 0000001 [SLPQ select 0xc0966764][SLP] kdeinit
  839 c30d1c48 56957   826   826 0000001 [SLPQ select 0xc0966764][SLP] kdeinit
  837 c30d420c 56957     1   824 0000001 [SLPQ select 0xc0966764][SLP] kdeinit
  835 c3020418 56957     1   823 0000001 [SLPQ select 0xc0966764][SLP] kdeinit
  826 c301c20c 56957     1   826 0000000 [SLPQ select 0xc0966764][SLP] kdeinit
  812 c3020830 56957     1   790 0004000 [SLPQ select 0xc0966764][SLP] mapping-n
  810 c3020c48 56957     1   740 0004001 [SLPQ select 0xc0966764][SLP] mixer_ap2
  808 c2cf420c 56957     1   740 0004001 [SLPQ select 0xc0966764][SLP] clock-apt
  804 c2cf4624 56957     1   740 000c081 (threaded)  gnome-vfs-daemon
   thread 0xc3401d80 ksegrp 0xc23494e0 [SLPQ kserel 0xc2349514][SLP]
   thread 0xc3401180 ksegrp 0xc23494e0 [SLPQ select 0xc0966764][SLP]
   thread 0xc3021780 ksegrp 0xc23494e0 [SLPQ select 0xc0966764][SLP]
   thread 0xc300b600 ksegrp 0xc2fff300 [SLPQ ksesigwait 0xc2cf475c][SLP]
  802 c2cf7624 56957     1   740 0004001 [SLPQ select 0xc0966764][SLP] wnck-appt
  798 c29dd418 56957     1   798 0004001 [SLPQ select 0xc0966764][SLP] konsole
  796 c2cf7000 56957     1   796 0004001 [SLPQ select 0xc0966764][SLP] konsole
  794 c29ddc48 56957     1   794 0004001 [SLPQ select 0xc0966764][SLP] konsole
  792 c29dd830 56957     1   792 0004001 [SLPQ select 0xc0966764][SLP] konsole
  790 c29dda3c 56957     1   790 000c081 (threaded)  nautilus
   thread 0xc3401600 ksegrp 0xc23496c0 [SLPQ kserel 0xc23496f4][SLP]
   thread 0xc300bc00 ksegrp 0xc23496c0 [SLPQ select 0xc0966764][SLP]
   thread 0xc2995d80 ksegrp 0xc23496c0 [SLPQ select 0xc0966764][SLP]
   thread 0xc3021a80 ksegrp 0xc2996c60 [SLPQ ksesigwait 0xc29ddb74][SLP]
  788 c29dd624 56957     1   788 000c081 (threaded)  gnome-panel
   thread 0xc3401480 ksegrp 0xc2349780 [SLPQ kserel 0xc23497b4][SLP]
   thread 0xc2ee8300 ksegrp 0xc2349780 [SLPQ select 0xc0966764][SLP]
   thread 0xc3021300 ksegrp 0xc2349780 [SLPQ select 0xc0966764][SLP]
   thread 0xc299d780 ksegrp 0xc2fff360 [SLPQ ksesigwait 0xc29dd75c][SLP]
  786 c2cf7418 56957     1   786 0004000 [SLPQ select 0xc0966764][SLP] metacity
  784 c2cf4418 56957     1   784 0004000 [SLPQ select 0xc0966764][SLP] gnome-smy
  753 c2cf4000 56957     1   740 0004100 [SLPQ select 0xc0966764][SLP] xscreensr
  742 c2cf4830 56957     1   740 0004001 [SLPQ select 0xc0966764][SLP] gnome-sem
  740 c2cf4c48 56957     1   740 0004000 [SLPQ select 0xc0966764][SLP] bonobo-as
  738 c2cf720c 56957     1   732 0000002 [SLPQ select 0xc0966764][SLP] gnome-keo
  735 c2cf7830 56957     1   732 0004003 [SLPQ select 0xc0966764][SLP] gconfd-2
  733 c2994418 56957   732   732 0004003 [SLPQ select 0xc0966764][SLP] gnome-sen
  732 c269ac48 56957   728   732 0004002 [SLPQ wait 0xc269ac48][SLP] sh
  729 c269d20c 56957   728   729 0004102 [SLPQ select 0xc0966764][SLP] Xorg
  728 c29dc20c 56957   718   718 0004002 [SLPQ wait 0xc29dc20c][SLP] xinit
  718 c299720c 56957   704   718 0004002 [SLPQ wait 0xc299720c][SLP] sh
  712 c29dd20c    0   702   712 0004002 [SLPQ ttyin 0xc24eac10][SLP] csh
  704 c2997a3c 56957   693   704 0004002 [SLPQ pause 0xc2997a70][SLP] tcsh
  703 c2994a3c    0     1   703 0004002 [SLPQ ttyin 0xc24c5410][SLP] getty
  702 c29dca3c    0     1   702 0004102 [SLPQ wait 0xc29dca3c][SLP] login
  701 c23fc624    0     1   701 0004002 [SLPQ ttyin 0xc24eb010][SLP] getty
  700 c299820c    0     1   700 0004002 [SLPQ ttyin 0xc24eb410][SLP] getty
  699 c2998418    0     1   699 0004002 [SLPQ ttyin 0xc24e2410][SLP] getty
  698 c2998624    0     1   698 0004002 [SLPQ ttyin 0xc24e0c10][SLP] getty
  697 c2998830    0     1   697 0004002 [SLPQ ttyin 0xc24e1810][SLP] getty
  696 c29dc000    0     1   696 0004002 [SLPQ ttyin 0xc24e1010][SLP] getty
  695 c2997c48    0     1   695 0004002 [SLPQ ttyin 0xc24df810][SLP] getty
  694 c2998000    0     1   694 0004002 [SLPQ ttyin 0xc24df410][SLP] getty
  693 c29dcc48    0     1   693 0004102 [SLPQ wait 0xc29dcc48][SLP] login
  679 c29dc624    0     1   679 0000000 [SLPQ select 0xc0966764][SLP] inetd
  639 c2997624    0     1   639 0000000 [SLPQ accept 0xc29b15ca][SLP] nessusd
  618 c29dc830 1001     1   618 0000000 [SLPQ select 0xc0966764][SLP] cvsupd
  615 c29dd000  999     1   615 0000000 [RUNQ] dnetc
  593 c299420c    0     1   593 0000000 [SLPQ nanslp 0xc091944c][SLP] cron
  580 c2994000   25     1   580 0000100 [SLPQ pause 0xc2994034][SLP] sendmail
  574 c2994624    0     1   574 0000100 [SLPQ select 0xc0966764][SLP] sendmail
  568 c2997418    0     1   568 0000100 [SLPQ select 0xc0966764][SLP] sshd
  545 c2997000    0     1   545 0000000 [SLPQ select 0xc0966764][SLP] ntpd
  528 c2994c48    0     1   528 0000000 [SLPQ select 0xc0966764][SLP] lpd
  510 c2997830    0     1   510 0000000 [SLPQ select 0xc0966764][SLP] usbd
  488 c23fc418    0   483   483 0000100 [SLPQ nfslockd 0xc096eea8][SLP] rpc.locd
  483 c269d830    0     1   483 0000000 [SLPQ select 0xc0966764][SLP] rpc.lockd
  478 c269dc48    0     1   478 0000000 [SLPQ select 0xc0966764][SLP] rpc.statd
  473 c269da3c    0   468   468 0000000 [SLPQ - 0xc297fa00][SLP] nfsd
  472 c269d624    0   468   468 0000000 [SLPQ - 0xc297fc00][SLP] nfsd
  471 c269a000    0   468   468 0000000 [SLPQ - 0xc297fe00][SLP] nfsd
  470 c269d418    0   468   468 0000000 [SLPQ - 0xc2980000][SLP] nfsd
  468 c23fc20c    0     1   468 0000000 [SLPQ select 0xc0966764][SLP] nfsd
  466 c269a418    0     1   466 0000000 [SLPQ select 0xc0966764][SLP] mountd
  403 c269aa3c    0     1   403 0000000 [SLPQ select 0xc0966764][SLP] ypbind
  390 c269a830    0     1   390 0000000 [SLPQ select 0xc0966764][SLP] rpcbind
  371 c269a20c    0     1   371 0000000 [SLPQ select 0xc0966764][SLP] syslogd
  331 c269a624    0     1   331 0000000 [SLPQ select 0xc0966764][SLP] devd
  289 c269d000    0     1   289 0000000 [SLPQ select 0xc0966764][SLP] moused
   60 c23fc830    0     0     0 0000204 [SLPQ - 0xe5074d04][SLP] schedcpu
   59 c23fca3c    0     0     0 0000204 [SLPQ - 0xc096ec4c][SLP] nfsiod 3
   58 c23fcc48    0     0     0 0000204 [SLPQ - 0xc096ec48][SLP] nfsiod 2
   57 c2636000    0     0     0 0000204 [SLPQ - 0xc096ec44][SLP] nfsiod 1
   56 c263620c    0     0     0 0000204 [SLPQ - 0xc096ec40][SLP] nfsiod 0
   55 c2636418    0     0     0 0000204 [SLPQ vlruwt 0xc2636418][SLP] vnlru
   54 c2636624    0     0     0 0000204 [SLPQ syncer 0xc09191bc][SLP] syncer
   53 c2636830    0     0     0 0000204 [SLPQ psleep 0xc0966cac][SLP] bufdaemon
   52 c2636a3c    0     0     0 000020c [RUNQ] pagezero
   51 c2636c48    0     0     0 0000204 [SLPQ psleep 0xc0974d54][SLP] vmdaemon
   50 c2343624    0     0     0 0000204 [SLPQ psleep 0xc0974d10][SLP] pagedaemon
   49 c2343830    0     0     0 0000204 [IWAIT] swi0: sio
   48 c2343a3c    0     0     0 0000204 [SLPQ - 0xc243bc3c][SLP] fdc0
   47 c2343c48    0     0     0 0000204 [SLPQ usbevt 0xc2443210][SLP] usb3
   46 c23fb000    0     0     0 0000204 [SLPQ usbevt 0xc2427210][SLP] usb2
   45 c23fb20c    0     0     0 0000204 [SLPQ usbevt 0xc242e210][SLP] usb1
   44 c23fb418    0     0     0 0000204 [SLPQ usbtsk 0xc0914124][SLP] usbtask
   43 c23fb624    0     0     0 0000204 [SLPQ usbevt 0xc23ef210][SLP] usb0
    9 c23fb830    0     0     0 0000204 [SLPQ - 0xc23eae80][SLP] acpi_task2
    8 c23fba3c    0     0     0 0000204 [SLPQ - 0xc23eae80][SLP] acpi_task1
    7 c23fbc48    0     0     0 0000204 [SLPQ - 0xc23eae80][SLP] acpi_task0
   42 c23fc000    0     0     0 0000204 [IWAIT] swi6:+
   41 c2335c48    0     0     0 0000204 [IWAIT] swi6: task queue
    6 c2342000    0     0     0 0000204 [SLPQ - 0xc23eb200][SLP] kqueue taskq
   40 c234220c    0     0     0 0000204 [IWAIT] swi2: cambio
   39 c2342418    0     0     0 0000204 [IWAIT] swi5:+
    5 c2342624    0     0     0 0000204 [SLPQ - 0xc22d5b00][SLP] thread taskq
   38 c2342830    0     0     0 0000204 [SLPQ - 0xc0911e40][SLP] yarrow
    4 c2342a3c    0     0     0 0000204 [SLPQ - 0xc0916948][SLP] g_down
    3 c2342c48    0     0     0 0000204 [SLPQ - 0xc0916944][SLP] g_up
    2 c2343000    0     0     0 0000204 [SLPQ - 0xc091693c][SLP] g_event
   37 c234320c    0     0     0 0000204 [IWAIT] swi3: vm
   36 c2343418    0     0     0 000020c [IWAIT] swi4: clock sio
   35 c232b624    0     0     0 0000204 [IWAIT] swi1: net
   34 c232b830    0     0     0 0000204 [IWAIT] irq23:
   33 c232ba3c    0     0     0 0000204 [IWAIT] irq22:
   32 c232bc48    0     0     0 0000204 [IWAIT] irq21:
   31 c2335000    0     0     0 0000204 [IWAIT] irq20:
   30 c233520c    0     0     0 0000204 [IWAIT] irq19: uhci1
   29 c2335418    0     0     0 0000204 [IWAIT] irq18: em0 uhci2+
   28 c2335624    0     0     0 0000204 [IWAIT] irq17: pcm0 fxp0
   27 c2335830    0     0     0 0000204 [IWAIT] irq16: uhci0 uhci3
   26 c2335a3c    0     0     0 0000204 [IWAIT] irq15: ata1
   25 c22db20c    0     0     0 0000204 [IWAIT] irq14: ata0
   24 c22db418    0     0     0 0000204 [IWAIT] irq13:
   23 c22db624    0     0     0 0000204 [IWAIT] irq12: psm0
   22 c22db830    0     0     0 0000204 [IWAIT] irq11:
   21 c22dba3c    0     0     0 0000204 [IWAIT] irq10:
   20 c22dbc48    0     0     0 0000204 [IWAIT] irq9: acpi0
   19 c232b000    0     0     0 0000204 [IWAIT] irq8:
   18 c232b20c    0     0     0 0000204 [IWAIT] irq7: ppc0
   17 c232b418    0     0     0 0000204 [IWAIT] irq6: fdc0
   16 c22d6000    0     0     0 0000204 [IWAIT] irq5:
   15 c22d620c    0     0     0 0000204 [IWAIT] irq4: sio0
   14 c22d6418    0     0     0 0000204 [IWAIT] irq3:
   13 c22d6624    0     0     0 0000204 [IWAIT] irq0:
   12 c22d6830    0     0     0 0000204 [IWAIT] irq1: atkbd0
   11 c22d6a3c    0     0     0 000020c [Can run] idle: cpu0
    1 c22d6c48    0     0     1 0004200 [SLPQ wait 0xc22d6c48][SLP] init
   10 c22db000    0     0     0 0000204 [SLPQ ktrace 0xc0917398][SLP] ktrace
    0 c0916a40    0     0     0 0000200 [IWAIT] swapper
db>

I can't obtain a coredump, but as it panics semi-regularly, I can do
anything requested from within DDB.

Gavin
Received on Tue Aug 09 2005 - 09:57:09 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:41 UTC