After upgrading from a 5.1-RELEASE GENERIC kernel to RELENG_5_2 checked out 13.Dec I get constant messages like this: calcru: negative time of -1850925 usec for pid 9077 (vdelivermail) calcru: negative time of -1780981 usec for pid 9077 (vdelivermail) calcru: negative time of -780102 usec for pid 9084 (vdelivermail) calcru: negative time of -711217 usec for pid 9084 (vdelivermail) calcru: negative time of -2783882 usec for pid 9087 (vdelivermail) calcru: negative time of -2713929 usec for pid 9087 (vdelivermail) calcru: negative time of -988197 usec for pid 9086 (sh) calcru: negative time of -929586 usec for pid 9086 (sh) calcru: negative time of -969496 usec for pid 9069 (qmail-local) It seems processes die now and then as well. Also, I had a total system lockup, machine didn't even respond to BRK on the serial-console. Machine is an Enterprise 450 with 2x250 (248Mhz) CPUs, 384mb ram. Only the internal SCSI controllers are used. 1 hme interface. Serial console only. From dmesg.boot: Timecounter "tick" frequency 248000000 Hz quality 0 initialializing counter-timer Timecounter "counter-timer" frequency 1000000 Hz quality 0 (Repeated 3 times) Timecounters tick every 10.000 msec
I installed FreeBSD 5.2 -RELEASE on an old celeron with a small disk. observed the same behavior as this report. the machine will lock up at random, ect. i do not have a serial cable to provide more info via a serial port. I can provide any info about this as needed to fix it, tho I hope this is of some help. the output of dmesg: Copyright (c) 1992-2004 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.2-RELEASE #0: Sun Jan 11 04:21:45 GMT 2004 root@wv1u.btc.adaptec.com:/usr/obj/usr/src/sys/GENERIC Preloaded elf kernel "/boot/kernel/kernel" at 0xc0a33000. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc0a331f4. Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: Pentium II/Pentium II Xeon/Celeron (333.37-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x665 Stepping = 5 Features=0x183f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR> real memory = 260034560 (247 MB) avail memory = 242946048 (231 MB) Pentium Pro MTRR support enabled npx0: [FAST] npx0: <math processor> on motherboard npx0: INT 16 interface acpi0: <ASUS MES-N > on motherboard pcibios: BIOS version 2.10 Using $PIR table, 7 entries at 0xc00f0ac0 acpi0: Power Button (fixed) Timecounter "ACPI-safe" frequency 3579545 Hz quality 1000 acpi_timer0: <24-bit timer at 3.579545MHz> port 0xec08-0xec0b on acpi0 acpi_cpu0: <CPU> on acpi0 pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0 pci0: <ACPI PCI bus> on pcib0 pcib0: slot 1 INTA is routed to irq 11 pcib0: slot 3 INTA is routed to irq 10 pcib0: slot 6 INTA is routed to irq 15 agp0: <SIS Generic host to PCI bridge> mem 0xe0000000-0xe3ffffff at device 0.0 on pci0 atapci0: <SiS 620 UDMA66 controller> port 0xd000-0xd00f,0xd400-0xd403,0xd800-0xd807,0xe000-0xe003,0xe400-0xe407 irq 14 at device 0.1 on pci0 ata0: at 0x1f0 irq 14 on atapci0 ata0: [MPSAFE] ata1: at 0x170 irq 15 on atapci0 ata1: [MPSAFE] isab0: <PCI-ISA bridge> at device 1.0 on pci0 isa0: <ISA bus> on isab0 pci0: <unknown> at device 1.1 (no driver attached) ohci0: <SiS 5571 USB controller> mem 0xdf800000-0xdf800fff irq 11 at device 1.2 on pci0 usb0: OHCI version 1.0, legacy support usb0: <SiS 5571 USB controller> on ohci0 usb0: USB revision 1.0 uhub0: SiS OHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub0: 2 ports with 2 removable, self powered pcib1: <PCI-PCI bridge> at device 2.0 on pci0 pci1: <PCI bus> on pcib1 pcib0: slot 2 INTA is routed to irq 11 pcib1: slot 0 INTA is routed to irq 11 pci1: <display, VGA> at device 0.0 (no driver attached) fxp0: <Intel 82559 Pro/100 Ethernet> port 0xa800-0xa83f mem 0xde000000-0xde0fffff,0xde800000-0xde800fff irq 10 at device 3.0 on pci0 fxp0: Ethernet address 00:e0:18:01:65:06 miibus0: <MII bus> on fxp0 inphy0: <i82555 10/100 media interface> on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto pci0: <multimedia, audio> at device 6.0 (no driver attached) fdc0: <Enhanced floppy controller (i82077, NE72065 or clone)> port 0x3f7,0x3f2-0x3f5 irq 6 drq 2 on acpi0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 ppc0 port 0x778-0x77f,0x378-0x37f irq 7 drq 3 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/8 bytes threshold ppbus0: <Parallel port bus> on ppc0 plip0: <PLIP network interface> on ppbus0 lpt0: <Printer> on ppbus0 lpt0: Interrupt-driven port ppi0: <Parallel I/O> on ppbus0 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A sio1 port 0x2f8-0x2ff irq 3 on acpi0 sio1: type 16550A atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0 atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0 kbd0 at atkbd0 orm0: <Option ROM> at iomem 0xc0000-0xc7fff on isa0 pmtimer0 on isa0 sc0: <System console> at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 Timecounter "TSC" frequency 333370975 Hz quality 800 Timecounters tick every 10.000 msec GEOM: create disk ad0 dp=0xc2d0a760 ad0: 2441MB <WDC AC32500H> [4960/16/63] at ata0-master WDMA2 Mounting root from ufs:/dev/ad0s1a calcru: negative time of -1950986 usec for pid 54 (rcorder) calcru: negative time of -1195529 usec for pid 69 (sysctl) calcru: negative time of -641869 usec for pid 58 (sh) calcru: negative time of -664837 usec for pid 75 (sh) calcru: negative time of -665933 usec for pid 77 (sh) calcru: negative time of -663495 usec for pid 81 (sh) calcru: negative time of -1267344 usec for pid 90 (fsck_ufs) calcru: negative time of -677471 usec for pid 111 (rm) calcru: negative time of -677377 usec for pid 113 (rm) calcru: negative time of -672541 usec for pid 125 (chmod) calcru: negative time of -677325 usec for pid 128 (rm) calcru: negative time of -675387 usec for pid 129 (rm) calcru: negative time of -680332 usec for pid 130 (sh) calcru: negative time of -676421 usec for pid 135 (sysctl) calcru: negative time of -673619 usec for pid 149 (dd) calcru: negative time of -674154 usec for pid 153 (dd) calcru: negative time of -673291 usec for pid 155 (dd) calcru: negative time of -673398 usec for pid 162 (cat) calcru: negative time of -1305154 usec for pid 144 (sh) calcru: negative time of -1345889 usec for pid 94 (sh)
This problem seemed to be completely fixed by an upgrade (source, or otherwise) to 5.3-RELEASE. Tried it on both sparc64 and x86, fixed it completely. -R. Tyler Ballance
State Changed From-To: open->closed Glad to hear that the problem is fixed with 5.3. Thanks for the update.