From owner-freebsd-current@FreeBSD.ORG Tue Aug 9 11:57:09 2005 Return-Path: X-Original-To: freebsd-current@freebsd.org Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 6B1DB16A41F for ; Tue, 9 Aug 2005 11:57:09 +0000 (GMT) (envelope-from gavin.atkinson@ury.york.ac.uk) Received: from mail-gw0.york.ac.uk (mail-gw0.york.ac.uk [144.32.128.245]) by mx1.FreeBSD.org (Postfix) with ESMTP id CCC1543D46 for ; Tue, 9 Aug 2005 11:57:08 +0000 (GMT) (envelope-from gavin.atkinson@ury.york.ac.uk) Received: from buffy.york.ac.uk (buffy-128.york.ac.uk [144.32.128.160]) by mail-gw0.york.ac.uk (8.12.10/8.12.10) with ESMTP id j79Bv3Ic007073 for ; Tue, 9 Aug 2005 12:57:03 +0100 (BST) Received: from buffy.york.ac.uk (localhost [127.0.0.1]) by buffy.york.ac.uk (8.13.4/8.13.4) with ESMTP id j79Bv3mm001032 for ; Tue, 9 Aug 2005 12:57:03 +0100 (BST) (envelope-from gavin.atkinson@ury.york.ac.uk) Received: (from ga9@localhost) by buffy.york.ac.uk (8.13.4/8.13.4/Submit) id j79Bv3ji001031 for freebsd-current@freebsd.org; Tue, 9 Aug 2005 12:57:03 +0100 (BST) (envelope-from gavin.atkinson@ury.york.ac.uk) X-Authentication-Warning: buffy.york.ac.uk: ga9 set sender to gavin.atkinson@ury.york.ac.uk using -f From: Gavin Atkinson To: freebsd-current@freebsd.org Content-Type: text/plain Content-Transfer-Encoding: 7bit Date: Tue, 09 Aug 2005 12:57:02 +0100 Message-Id: <1123588622.893.15.camel@buffy.york.ac.uk> Mime-Version: 1.0 X-Mailer: Evolution 2.2.3 FreeBSD GNOME Team Port X-York-MailScanner: Found to be clean X-York-MailScanner-From: gavin.atkinson@ury.york.ac.uk Subject: 6.0-BETA2 DRM/witness panic: Assertion j < 1000 failed at /usr/src/sys/kern/subr_witness.c:1513 X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 09 Aug 2005 11:57:09 -0000 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