Hi,
On this machine i'm running -CURRENT from around the end of april, so
apologies if this has already been corrected, however i haven't seen
anything similar reported before. Panic while writing a core file for a
program that died on signal 11. Toshiba laptop, usually perfectly stable,
resumed from suspend about two minutes before and was in the process of
loading KDE... This panic has only ever happened once. there were lots of
errors about calcru being negetive on the console and ATA interrupts
arriving early, immediately before the panic. There was also a brief
arcing type sound from the speakers as KDE loaded, 10 seconds before the
panic. Console log, dmesg and backtrace attached. I'll keep the core file
if anyone wants to see it or wants any information from it.
Gavin
/var/log/messages
May 17 10:16:25 epsilon kernel: wakeup from sleeping state (slept 12:37:54)
May 17 10:16:25 epsilon kernel: ata0: resetting devices .. done
May 17 10:16:25 epsilon kernel: ata1: resetting devices .. done
May 17 10:16:25 epsilon kernel: system power profile changed to 'economy'
May 17 10:16:40 epsilon kernel: ep0: <3Com 3c589 10Mbps Ethernet> at port 0x100-0x10f
irq 11 function 0 config 1 on pccard0
May 17 10:16:40 epsilon kernel: ep0: Ethernet address 00:a0:24:e3:3c:c6
May 17 10:17:21 epsilon kernel: system power profile changed to 'performance'
May 17 10:18:28 epsilon kernel: lock order reversal
May 17 10:18:28 epsilon kernel: 1st 0xc8e08580 pcm0 (sound softc) @
/usr/src/sys/modules/sound/pcm/../../../dev/sound/pcm/sound.c:134
May 17 10:18:28 epsilon kernel: 2nd 0xc8e510c0 pcm0:play:0 (pcm channel) @
/usr/src/sys/modules/sound/pcm/../../../dev/sound/pcm/channel.c:441
May 17 10:18:31 epsilon kernel: calcru: negative time of -388077 usec for pid 2498
(artsd)
May 17 10:19:37 epsilon kernel: calcru: negative time of -376553 usec for pid 2498
(artsd)
May 17 10:19:39 epsilon kernel: calcru: negative time of -1491358277 usec for pid 245
(dnetc)
May 17 10:19:40 epsilon kernel: calcru: negative time of -1491302617 usec for pid 245
(dnetc)
May 17 10:19:40 epsilon kernel: calcru: negative time of -1491290967 usec for pid 245
(dnetc)
May 17 10:19:40 epsilon kernel: calcru: negative time of -1491842832 usec for pid 245
(dnetc)
May 17 10:20:20 epsilon kernel: calcru: negative time of -1492962087 usec for pid 245
(dnetc)
May 17 10:20:54 epsilon kernel: calcru: negative time of -1492922647 usec for pid 245
(dnetc)
May 17 10:20:54 epsilon kernel: calcru: negative time of -1493441241 usec for pid 245
(dnetc)
May 17 10:20:54 epsilon kernel: calcru: negative time of -1493462215 usec for pid 245
(dnetc)
May 17 10:20:54 epsilon kernel: calcru: negative time of -1493403019 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon syslogd: kernel boot file is /boot/kernel/kernel
May 17 10:21:41 epsilon kernel: 534 usec for pid 245 (dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1705561199 usec for pid 245
(dnetc)
[~100 similar lines snipped]
May 17 10:21:41 epsilon kernel: ad0: READ command timeout tag=0 serv=0 - resetting
May 17 10:21:41 epsilon kernel: ata0: resetting devices .. done
May 17 10:21:41 epsilon kernel: ad0: read interrupt arrived earlyad0: read error
detected (too) latespec_getpages:(ad0s1g) I/O read failure: (error=5) bp 0xc3ffce70 vp
0xc939ca50
May 17 10:21:41 epsilon kernel: size: 40960, resid: 16384, a_count: 40960, valid: 0x0
May 17 10:21:41 epsilon kernel: nread: 24576, reqpage: 7, pindex: 79, pcount: 10
May 17 10:21:41 epsilon kernel: vm_fault: pager read error, pid 2503 (kdeinit)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1750099965 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1750645169 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1752260850 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1752238389 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1752822526 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1752717624 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1244498 usec for pid 2498
(artsd)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1235784 usec for pid 2498
(artsd)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1753302582 usec for pid 245
(dnetc)
May 17 10:21:41 epsilon kernel: calcru: negative time of -1753272571 usec for pid 245
(dnetc)
[~100 similar lines snipped]
May 17 10:21:42 epsilon kernel: ad0: WRITE command timeout tag=0 serv=0 - resetting
May 17 10:21:42 epsilon kernel: ata0: resetting devices .. done
May 17 10:21:42 epsilon kernel: calcru: negative time of -1193253 usec for pid 2498
(artsd)
May 17 10:21:42 epsilon kernel: calcru: negative time of -1187881 usec for pid 2498
(artsd)
May 17 10:21:42 epsilon kernel: panic: ffs_clusteralloc: map mismatch
May 17 10:21:42 epsilon kernel: panic: from debugger
May 17 10:21:42 epsilon kernel: Uptime: 14h18m16s
May 17 10:21:42 epsilon kernel: Dumping 127 MB
May 17 10:21:42 epsilon kernel: ata0: resetting devices .. done
May 17 10:21:42 epsilon kernel: ad0: timeout waiting for DRQ - resetting
May 17 10:21:42 epsilon kernel: ata0: resetting devices .. done
May 17 10:21:42 epsilon kernel: 16 32 48 64 80 96 112
May 17 10:21:42 epsilon kernel: Dump complete
dmesg
Timecounter "i8254" frequency 1193182 Hz
CPU: Pentium III/Pentium III Xeon/Celeron (547.31-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x683 Stepping = 3
Features=0x383f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
real memory = 134086656 (130944K bytes)
avail memory = 124715008 (121792K bytes)
Pentium Pro MTRR support enabled
VESA: v2.0, 8192k memory, flags:0x0, mode table:0xc03eb122 (1000022)
VESA: S3 Incorporated. M7 BIOS
Using $PIR table, 9 entries at 0xc00f0190
apm0: <APM BIOS> on motherboard
apm0: found APM BIOS v1.2, connected at v1.2
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: Other PM system enabled.
pcib0: <Intel 82443BX (440 BX) host to PCI bridge> at pcibus 0 on motherboard
pci0: <PCI bus> on pcib0
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
pci1: <display, VGA> at device 0.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 5.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 ATA33 controller> port 0xfff0-0xffff at device 5.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 0xff80-0xff9f irq 11 at device
5.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
pci0: <bridge, PCI-unknown> at device 5.3 (no driver attached)
pci0: <simple comms> at device 7.0 (no driver attached)
pci0: <unknown> at device 9.0 (no driver attached)
pccbb0: <ToPIC95B PCI-CardBus Bridge> irq 11 at device 11.0 on pci0
cardbus0: <CardBus bus> on pccbb0
pccard0: <16-bit PCCard bus> on pccbb0
pccbb1: <ToPIC95B PCI-CardBus Bridge> irq 11 at device 11.1 on pci0
cardbus1: <CardBus bus> on pccbb1
pccard1: <16-bit PCCard bus> on pccbb1
pcm0: <Yamaha DS-1E (YMF744)> port 0xfefc-0xfeff,0xff00-0xff3f mem
0xefff8000-0xefffffff irq 11 at device 12.0 on pci0
eisa0: <EISA bus> on motherboard
eisa0: unknown card @@@0000 (0x00000000) at slot 1
orm0: <Option ROM> at iomem 0xc0000-0xcbfff on isa0
atkbdc0: <Keyboard controller (i8042)> at port 0x64,0x60 on isa0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model IntelliMouse, device ID 3
fdc0: <enhanced floppy controller (i82077, NE72065 or clone)> at port
0x3f7,0x3f0-0x3f5 irq 6 drq 2 on isa0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
pmtimer0 on isa0
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
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
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ep0: <3Com 3c589 10Mbps Ethernet> at port 0x100-0x10f irq 11 function 0 config 1 on
pccard0
ep0: Ethernet address 00:a0:24:e3:3c:c6
ad0: 19077MB <IC25N020ATDA04-0> [38760/16/63] at ata0-master UDMA33
acd0: CDROM <CD-224E-B> at ata1-master PIO4
Mounting root from ufs:/dev/ad0s1a
lock order reversal
1st 0xc8ea43e4 PIPE (UMA zone) @ /usr/src/sys/vm/uma_core.c:527
2nd 0xc082a724 PCPU KMAP ENTRY (UMA cpu) @ /usr/src/sys/vm/uma_core.c:1301
swi_net: unregistered isr number: 18.
swi_net: unregistered isr number: 18.
swi_net: unregistered isr number: 18.
gdb 'where'
#0 doadump () at /usr/src/sys/kern/kern_shutdown.c:213
#1 0xc01db108 in boot (howto=260) at /usr/src/sys/kern/kern_shutdown.c:346
#2 0xc01db2b1 in panic (fmt=0xc02fadaa "from debugger")
at /usr/src/sys/kern/kern_shutdown.c:490
#3 0xc0133911 in db_panic (addr=-1070771256, have_addr=0, count=-1,
modif=0xc99b7758 "") at /usr/src/sys/ddb/db_command.c:449
#4 0xc01338af in db_command (last_cmdp=0xc034ec04, cmd_table=0xc034ea24,
aux_cmd_tablep=0xc03488e4, aux_cmd_tablep_end=0xc03488e8)
at /usr/src/sys/ddb/db_command.c:345
#5 0xc013397b in db_command_loop () at /usr/src/sys/ddb/db_command.c:471
#6 0xc0135d0f in db_trap (type=3, code=0) at /usr/src/sys/ddb/db_trap.c:72
#7 0xc02d515e in kdb_trap (type=3, code=0, regs=0xc99b7854)
at /usr/src/sys/i386/i386/db_interface.c:161
#8 0xc02e2610 in trap (frame={tf_fs = 24, tf_es = -919076848,
tf_ds = -919076848, tf_edi = 4112, tf_esi = 256, tf_ebp = -912557924,
tf_isp = -912557952, tf_ebx = -1070384032, tf_edx = -1072983520,
tf_ecx = 32, tf_eax = 18, tf_trapno = 3, tf_err = 0,
tf_eip = -1070771256, tf_cs = 8, tf_eflags = 582, tf_esp = -1070331457,
tf_ss = -1070470052}) at /usr/src/sys/i386/i386/trap.c:585
#9 0xc02d53c8 in Debugger (msg=0xc031ec5c "panic") at machine/cpufunc.h:68
#10 0xc01db29c in panic (fmt=0xc0333c60 "ffs_clusteralloc: map mismatch")
at /usr/src/sys/kern/kern_shutdown.c:477
#11 0xc02920d5 in ffs_clusteralloc (ip=0xc97d5c00, cg=78, bpref=0, len=6)
at /usr/src/sys/ufs/ffs/ffs_alloc.c:1316
#12 0xc0291426 in ffs_hashalloc (ip=0xc97d5c00, cg=75, pref=6835208, size=6,
allocator=0xc0291eac <ffs_clusteralloc>)
at /usr/src/sys/ufs/ffs/ffs_alloc.c:896
#13 0xc0290b8f in ffs_reallocblks (ap=0xc99b7a2c)
at /usr/src/sys/ufs/ffs/ffs_alloc.c:461
#14 0xc0211952 in cluster_write (bp=0xc402d038, filesize=98304, seqcount=0)
at vnode_if.h:861
#15 0xc02a089b in ffs_write (ap=0xc99b7abc)
at /usr/src/sys/ufs/ufs/ufs_readwrite.c:548
#16 0xc021ff6a in vn_rdwr (rw=UIO_WRITE, vp=0xc981ac30, base=0x28066000 "",
len=32768, offset=86016, segflg=UIO_USERSPACE, ioflg=257, cred=0xc9881b80,
aresid=0x0, td=0xc995e100) at vnode_if.h:303
#17 0xc0220031 in vn_rdwr_inchunks (rw=UIO_WRITE, vp=0xc981ac30,
base=0x28066000 "", len=32768, offset=86016, segflg=UIO_USERSPACE,
ioflg=257, cred=0xc9881b80, aresid=0x0, td=0xc995e100)
at /usr/src/sys/kern/vfs_vnops.c:419
#18 0xc01bf2c5 in elf_coredump (td=0xc995e100, vp=0xc981ac30,
limit=9223372036854775807) at /usr/src/sys/kern/imgact_elf.c:802
#19 0xc01de63a in coredump (td=0xc995e100) at /usr/src/sys/kern/kern_sig.c:2039
#20 0xc01ddeb8 in sigexit (td=0xc995e100, sig=11)
at /usr/src/sys/kern/kern_sig.c:1844
#21 0xc01ddbe3 in postsig (sig=11) at /usr/src/sys/kern/kern_sig.c:1750
#22 0xc01f22d2 in ast (framep=0xc99b7d48) at /usr/src/sys/kern/subr_trap.c:204
#23 0xc02d7884 in doreti_ast ()
#24 0x280551a6 in ?? ()
#25 0x286656bf in ?? ()
#26 0x28665f74 in ?? ()
#27 0x286660bb in ?? ()
#28 0x28667247 in ?? ()
#29 0x804c5b9 in ?? ()
#30 0x804d74e in ?? ()
#31 0x804dc10 in ?? ()
#32 0x804ec91 in ?? ()
#33 0x804ad95 in ?? ()
(kgdb) f 11
#11 0xc02920d5 in ffs_clusteralloc (ip=0xc97d5c00, cg=78, bpref=0, len=6)
at /usr/src/sys/ufs/ffs/ffs_alloc.c:1316
1316 panic("ffs_clusteralloc: map mismatch");
(kgdb) l
1311 * Allocate the cluster that we have found.
1312 */
1313 blksfree = cg_blksfree(cgp);
1314 for (i = 1; i <= len; i++)
1315 if (!ffs_isblock(fs, blksfree, got - run + i))
1316 panic("ffs_clusteralloc: map mismatch");
1317 bno = cg * fs->fs_fpg + blkstofrags(fs, got - run + 1);
1318 if (dtog(fs, bno) != cg)
1319 panic("ffs_clusteralloc: allocated out of group");
1320 len = blkstofrags(fs, len);
(kgdb)
