From owner-freebsd-current Mon Feb 18 12:56:43 2002 Delivered-To: freebsd-current@freebsd.org Received: from mailout06.sul.t-online.com (mailout06.sul.t-online.com [194.25.134.19]) by hub.freebsd.org (Postfix) with ESMTP id 07B5D37B402 for ; Mon, 18 Feb 2002 12:56:21 -0800 (PST) Received: from fwd01.sul.t-online.de by mailout06.sul.t-online.com with smtp id 16cupf-0004LC-05; Mon, 18 Feb 2002 21:56:19 +0100 Received: from dialin.t-online.de (340029380333-0001@[80.128.218.159]) by fmrl01.sul.t-online.com with esmtp id 16cupV-1CH83GC; Mon, 18 Feb 2002 21:56:09 +0100 Received: from t-online.de (server [172.23.7.1]) by dialin.t-online.de (8.11.6/8.11.6/Rock) with ESMTP id g1IKtpL20892 for ; Mon, 18 Feb 2002 21:55:51 +0100 (MET) Message-ID: <3C716A4D.A0D32693@t-online.de> Date: Mon, 18 Feb 2002 21:55:41 +0100 From: Daniel Rock X-Mailer: Mozilla 4.76 [de] (X11; U; SunOS 5.8 i86pc) X-Accept-Language: de, en MIME-Version: 1.0 To: current@freebsd.org Subject: two panics with recent -CURRENT Content-Type: multipart/signed; protocol="application/x-pkcs7-signature"; micalg=sha1; boundary="------------ms859BFAD8838F87FA22C56DEB" X-Sender: 340029380333-0001@t-dialin.net Sender: owner-freebsd-current@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.ORG Dies ist eine kryptographisch unterzeichnete Nachricht im MIME-Format. --------------ms859BFAD8838F87FA22C56DEB Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit Hi, during a "make release" run I got two panics in -CURRENT (from Feb 16). Both panics occured during high I/O rates. The first one occured while mkisofs'ing the resulting release CD tree, The second one while "rm -rf RELEASEDIR". I am now running a kernel from Feb 08, which I believe is OK. I restarted a "make release" and will inform you if this kernel also panics. The relevant file system has soft updates enabled and was newfs'd with default argumengs (just the inode count was reduced). Background fsck is turned off, write cache is disabled. If you need more information, I can give additional detail. Thanks, Daniel ----- Kernel stacktrace from the first panic: IdlePTD at phsyical address 0x004c3000 initial pcb at physical address 0x003f3440 panicstr: allocbuf: buffer not busy panic messages: --- panic: softdep_disk_write_complete: lock is held syncing disks... panic: allocbuf: buffer not busy Uptime: 7h50m21s dumping to dev ad0b, offset 545184 [...] #0 0xc01e7cca in sysctl_kern_dumpdev (oidp=0xc0355c48, arg1=0x104, arg2=-1014061372, req=0x246) at ../../../kern/kern_shutdown.c:483 #1 0xc115754c in ?? () #2 0xc01e7a67 in boot (howto=260) at ../../../kern/kern_shutdown.c:333 #3 0xc01e7ef1 in panic (fmt=0xc0355c48 "allocbuf: buffer not busy") at ../../../kern/kern_shutdown.c:619 #4 0xc021c3ad in allocbuf (bp=0xc38ea6c4, size=16384) at ../../../kern/vfs_bio.c:2418 #5 0xc021c32d in getblk (vp=0xd8334ec0, blkno=11305088, size=16384, slpflag=0, slptimeo=0) at ../../../kern/vfs_bio.c:2361 #6 0xc0219fdc in breadn (vp=0xd8334ec0, blkno=11305088, size=16384, rablkno=0x0, rabsize=0x0, cnt=0, cred=0x0, bpp=0xd7faeb14) at ../../../kern/vfs_bio.c:601 #7 0xc0219fa9 in bread (vp=0xd8334ec0, blkno=11305088, size=16384, cred=0x0, bpp=0xd7faeb14) at ../../../kern/vfs_bio.c:585 #8 0xc02bacd4 in ffs_update (vp=0xd8846900, waitfor=0) at ../../../ufs/ffs/ffs_inode.c:101 #9 0xc02c814a in ffs_fsync (ap=0xd7faeb8c) at ../../../ufs/ffs/ffs_vnops.c:293 #10 0xc02c6712 in ffs_sync (mp=0xc113c400, waitfor=2, cred=0xc0b2ec00, td=0xc03b7780) at ../../../ufs/ffs/ffs_vfsops.c:1050 #11 0xc02272b1 in sync (td=0xc03b7780, uap=0x0) at ../../../kern/vfs_syscalls.c:669 #12 0xc01e767f in boot (howto=256) at ../../../kern/kern_shutdown.c:237 #13 0xc01e7ef1 in panic ( fmt=0xc036d440 "softdep_disk_write_complete: lock is held") at ../../../kern/kern_shutdown.c:619 #14 0xc02c2368 in softdep_disk_write_complete (bp=0xc38e29a4) at ../../../ufs/ffs/ffs_softdep.c:3466 #15 0xc021ca49 in bufdone (bp=0xc38e29a4) at ../../../kern/vfs_bio.c:2799 #16 0xc021f195 in cluster_callback (bp=0xc38d656c) at ../../../kern/vfs_cluster.c:551 #17 0xc021ca27 in bufdone (bp=0xc38d656c) at ../../../kern/vfs_bio.c:2789 #18 0xc021c982 in bufwait (bp=0xc38d656c) at ../../../kern/vfs_bio.c:2730 #19 0xc0173bdd in ad_interrupt (request=0xc187eb00) at ../../../sys/bio.h:106 #20 0xc0168fd2 in ata_intr (data=0xc10dd500) at ../../../dev/ata/ata-all.c:578 #21 0xc01d9ccf in ithread_loop (arg=0xc10e2900) at ../../../kern/kern_intr.c:529 #22 0xc01d8ec8 in fork_exit (callout=0xc01d9b34 , arg=0xc10e2900, frame=0xd7faed48) at ../../../kern/kern_fork.c:777 ----- the second panic: IdlePTD at phsyical address 0x004c3000 initial pcb at physical address 0x003f3440 panicstr: bwrite: buffer is not busy??? panic messages: --- panic: double fault syncing disks... panic: bwrite: buffer is not busy??? Uptime: 11h18m21s dumping to dev ad0b, offset 545184 [...] #0 0xc01e7cca in sysctl_kern_dumpdev (oidp=0xc0355918, arg1=0x104, arg2=537006244, req=0x4046) at ../../../kern/kern_shutdown.c:483 #1 0xc115754c in ?? () #2 0xc01e7a67 in boot (howto=260) at ../../../kern/kern_shutdown.c:333 #3 0xc01e7ef1 in panic (fmt=0xc0355918 "bwrite: buffer is not busy???") at ../../../kern/kern_shutdown.c:619 #4 0xc021a1f3 in bwrite (bp=0xc38da6d4) at ../../../sys/systm.h:241 #5 0xc021b476 in vfs_bio_awrite (bp=0xc38da6d4) at ../../../kern/vfs_bio.c:1535 #6 0xc01b3c1c in spec_fsync (ap=0xc040ce24) at ../../../fs/specfs/spec_vnops.c:351 #7 0xc01b3805 in pfs_write (va=0xc040ce24) at ../../../fs/pseudofs/pseudofs_vnops.c:777 #8 0xc02c6930 in ffs_sync (mp=0xc113c400, waitfor=2, cred=0xc0b2ec00, td=0xc03b7780) at ../../../ufs/ffs/ffs_vfsops.c:1086 #9 0xc02272b1 in sync (td=0xc03b7780, uap=0x0) at ../../../kern/vfs_syscalls.c:669 #10 0xc01e767f in boot (howto=256) at ../../../kern/kern_shutdown.c:237 #11 0xc01e7ef1 in panic (fmt=0xc0379343 "double fault") at ../../../kern/kern_shutdown.c:619 #12 0xc03106cb in dblfault_handler () at ../../../i386/i386/trap.c:871 ----- My dmesg output (from a kernel which i believe is OK): Copyright (c) 1992-2002 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 5.0-CURRENT #624: Sat Feb 9 18:03:58 CET 2002 root@gate.rock.net:/usr/src/sys/i386/compile/ROCK Preloaded elf kernel "/boot/kernel/kernel" at 0xc04a3000. Calibrating clock(s) ... TSC clock: 300680590 Hz, i8254 clock: 1193178 Hz Timecounter "i8254" frequency 1193178 Hz CLK_USE_TSC_CALIBRATION not specified - using old calibration method Timecounter "TSC" frequency 300681343 Hz CPU: AMD-K6(tm) 3D processor (300.68-MHz 586-class CPU) Origin = "AuthenticAMD" Id = 0x580 Stepping = 0 Features=0x8001bf AMD Features=0x80000800 Data TLB: 128 entries, 2-way associative Instruction TLB: 64 entries, 1-way associative L1 data cache: 32 kbytes, 32 bytes/line, 2 lines/tag, 2-way associative L1 instruction cache: 32 kbytes, 32 bytes/line, 2 lines/tag, 2-way associative Write Allocate Enable Limit: 128M bytes Write Allocate 15-16M bytes: Enable Hardware Write Allocate Control: Disable real memory = 134152192 (131008K bytes) Physical memory chunk(s): 0x00001000 - 0x0009ffff, 651264 bytes (159 pages) 0x004ca000 - 0x07fe5fff, 129089536 bytes (31516 pages) avail memory = 125706240 (122760K bytes) bios32: Found BIOS32 Service Directory header at 0xc00fb040 bios32: Entry = 0xfb4c0 (c00fb4c0) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0xf0000+0xb4f0 pnpbios: Found PnP BIOS data at 0xc00fc130 pnpbios: Entry = f0000:c158 Rev = 1.0 Other BIOS signatures found: random: mem: VESA: information block 56 45 53 41 00 02 0e 6f 00 c0 01 00 00 00 22 00 00 01 40 00 02 01 23 6f 00 c0 2a 6f 00 c0 38 6f 00 c0 00 01 01 01 02 01 03 01 05 01 07 01 08 01 09 01 0b 01 0c 01 10 01 11 01 12 01 13 01 14 01 VESA: 24 mode(s) found VESA: v2.0, 4096k memory, flags:0x1, mode table:0xc0412402 (1000022) VESA: Matrox Graphics Inc. VESA: Matrox MILLENNIUM II 00 null: pci_open(1): mode 1 addr port (0x0cf8) is 0x80005800 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=154110b9) Using $PIR table, 8 entries at 0xc00fdef0 acpi0: on motherboard acpi0: power button is handled as a fixed feature programming model. acpi_cpu0: on acpi0 acpi_button0: on acpi0 acpi_pcib0: port 0x4d6,0x40b,0x480-0x48f,0x5000-0x501f,0x4000- 0x403f,0xcf8-0xcff on acpi0 pci0: physical bus=0 map[10]: type 1, range 32, base d0000000, size 28, enabled found-> vendor=0x10b9, dev=0x1541, revid=0x04 bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=0 found-> vendor=0x10b9, dev=0x5243, revid=0x04 bus=0, slot=1, func=0 class=06-04-00, hdrtype=0x01, mfdev=0 map[10]: type 1, range 32, base ee102000, size 12, enabled found-> vendor=0x10b9, dev=0x5237, revid=0x03 bus=0, slot=2, func=0 class=0c-03-10, hdrtype=0x00, mfdev=0 intpin=a, irq=11 found-> vendor=0x10b9, dev=0x1533, revid=0xc3 bus=0, slot=7, func=0 class=06-01-00, hdrtype=0x00, mfdev=0 map[10]: type 4, range 32, base 0000e000, size 8, enabled map[14]: type 1, range 32, base ee101000, size 8, enabled found-> vendor=0x1000, dev=0x0004, revid=0x04 bus=0, slot=8, func=0 class=01-00-00, hdrtype=0x00, mfdev=0 intpin=a, irq=10 map[10]: type 3, range 32, base ee100000, size 12, enabled map[14]: type 4, range 32, base 0000e400, size 5, enabled map[18]: type 1, range 32, base ee000000, size 20, enabled found-> vendor=0x8086, dev=0x1229, revid=0x05 bus=0, slot=9, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 intpin=a, irq=12 powerspec 1 supports D0 D1 D2 D3 current D0 map[10]: type 4, range 32, base 0000e800, size 8, enabled map[14]: type 1, range 32, base ee103000, size 8, enabled found-> vendor=0x10ec, dev=0x8139, revid=0x10 bus=0, slot=10, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 intpin=a, irq=9 powerspec 2 supports D0 D1 D2 D3 current D0 map[10]: type 3, range 32, base ea000000, size 24, enabled map[14]: type 1, range 32, base eb000000, size 14, enabled map[18]: type 1, range 32, base ec000000, size 23, enabled found-> vendor=0x102b, dev=0x051b, revid=0x00 bus=0, slot=11, func=0 class=03-00-00, hdrtype=0x00, mfdev=0 intpin=a, irq=9 map[20]: type 4, range 32, base 0000f000, size 4, enabled found-> vendor=0x10b9, dev=0x5229, revid=0xc1 bus=0, slot=15, func=0 class=01-01-fa, hdrtype=0x00, mfdev=0 intpin=a, irq=0 pci0: on acpi_pcib0 agp0: mem 0xd0000000-0xdfffffff at device 0.0 on pci0 agp0: allocating GATT for aperture of size 256M pcib1: at device 1.0 on pci0 pcib1: secondary bus 1 pcib1: subordinate bus 1 pcib1: I/O decode 0xf000-0xfff pcib1: memory decode 0xfff00000-0xfffff pcib1: prefetched decode 0xfff00000-0xfffff pci1: physical bus=1 pci1: on pcib1 ohci0: mem 0xee102000-0xee102fff irq 11 at device 2.0 on pci0 usb0: OHCI version 1.0, legacy support usb0: on ohci0 usb0: USB revision 1.0 uhub0: AcerLabs OHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub0: 2 ports with 2 removable, self powered isab0: at device 7.0 on pci0 isa0: on isab0 sym0: <815> port 0xe000-0xe0ff mem 0xee101000-0xee1010ff irq 10 at device 8.0 on pci0 sym0: Symbios NVRAM, ID 7, Fast-10, SE, parity checking sym0: open drain IRQ line driver sym0: using NCR-generic firmware. sym0: initial SCNTL3/DMODE/DCNTL/CTEST3/4/5 = (hex) 03/8a/80/00/00/00 sym0: final SCNTL3/DMODE/DCNTL/CTEST3/4/5 = (hex) 03/ca/00/00/08/00 sym0: Delay (GEN=11): 281 msec, 31631 KHz sym0: Delay (GEN=11): 281 msec, 31631 KHz sym0: Delay (GEN=11): 281 msec, 31631 KHz fxp0: port 0xe400-0xe41f mem 0xee000000-0xee0f ffff,0xee100000-0xee100fff irq 12 at device 9.0 on pci0 fxp0: using memory space register mapping fxp0: Ethernet address 00:a0:c9:ef:69:8d fxp0: PCI IDs: 8086 1229 8086 0009 0005 fxp0: Dynamic Standby mode is disabled inphy0: on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto bpf: fxp0 attached rl0: port 0xe800-0xe8ff mem 0xee103000-0xee1030ff ir q 9 at device 10.0 on pci0 rl0: Realtek 8139B detected. Warning, this may be unstable in autoselect mode rl0: Ethernet address: 00:e0:7d:75:fd:fb miibus1: on rl0 rlphy0: on miibus1 rlphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto bpf: rl0 attached pci0: at device 11.0 (no driver attached) atapci0: port 0xf000-0xf00f irq 0 at device 15.0 on pci0 ata0: iobase=0x01f0 altiobase=0x03f6 bmaddr=0xf000 ata0: mask=03 ostat0=50 ostat2=00 ata0-master: ATAPI 00 00 ata0-slave: ATAPI 00 00 ata0: mask=03 stat0=50 stat1=00 ata0-master: ATA 01 a5 ata0: devices=01 ata0: at 0x1f0 irq 14 on atapci0 ata1: iobase=0x0170 altiobase=0x0376 bmaddr=0xf008 ata1: mask=03 ostat0=50 ostat2=00 ata1-master: ATAPI 00 00 ata1-slave: ATAPI 00 00 ata1: mask=03 stat0=50 stat1=00 ata1-master: ATA 01 a5 ata1: devices=01 ata1: at 0x170 irq 15 on atapci0 fdc0: port 0x3f7,0x3f2-0 x3f5 irq 6 on acpi0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 sio0: irq maps: 0x201 0x211 0x201 0x201 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A, console sio1: irq maps: 0x201 0x209 0x201 0x201 sio1 port 0x2f8-0x2ff irq 3 on acpi0 sio1: type 16550A unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) ppc0: using extended I/O port range ppc0: ECP SPP ECP+EPP SPP ppc0 port 0x778-0x77b,0x378-0x37f irq 7 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/1 bytes threshold lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: on ppbus0 unknown: not probed (disabled) atkbdc0: port 0x64,0x60 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 kbd0: atkbd0, generic (0), config:0x0, flags:0x1f0000 unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) npx0: on motherboard npx0: INT 16 interface sio: sio0 already exists; skipping it Trying Read_Port at 203 SAG0001: start dependent (1) SAG0001: adding io range 0x100-0x3f7, size=0x8, align=0x8 SAG0001: adding irq mask 0xbcb8 SAG0001: start dependent (1) SAG0001: adding io range 0x100-0x3f7, size=0x8, align=0x8 SAG0001: adding irq mask 0x20 SAG0001: start dependent (1) SAG0001: adding io range 0x100-0x3f7, size=0x8, align=0x8 SAG0001: adding irq mask 0x80 SAG0001: end dependent isa_probe_children: disabling PnP devices isa_probe_children: probing non-PnP devices orm0: