Re: Recent update to RELENG_6 creates lots of calcru warnings
James Long wrote: I'm running FreeBSD RELENG_6 on a Compaq DL380 G2 dual PIII-1.4GHz with an SMP kernel. dmesg below. Prior to about August 12, the system had been quite stable. Since that time, I am finding lots of calcru warnings, which often cause system services to fail when they see unorthodox time shifts. Section 5.19 of the handbook appears not to be current any longer, suggesting setting kern.timecounter.method=1. kern.timecounter.hardware=i8254 also does not improve things. Other suggestions? Thanks, Jim Sep 1 18:00:30 ns sudo:james : TTY=ttyp0 ; PWD=/usr/local/www/sites/com.umpquanet/support ; USER=root ; COMMAND=/sbin/sysctl -w kern.timecounter.hardware=i8254 Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 1343309 usec to 1178478 usec for pid 3663 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1274607 usec for pid 3660 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 6470361 usec to 5811413 usec for pid 995 (postgres) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1099076 usec for pid 36 (pagedaemon) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -5928105 usec for pid 35 (fdc0) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1526937 usec for pid 32 (acpi_cooling0) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 8015143 usec to 1437770 usec for pid 15 (yarrow) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -12360933 usec for pid 2 (g_event) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -2106022786 usec for pid 13 (swi4: clock sio) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 295074296 usec to 292484248 usec for pid 1 (init) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 1343309 usec to 1178478 usec for pid 3663 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1274607 usec for pid 3660 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 6470361 usec to 5811413 usec for pid 995 (postgres) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1099076 usec for pid 36 (pagedaemon) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -5928105 usec for pid 35 (fdc0) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1526937 usec for pid 32 (acpi_cooling0) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 8015143 usec to 1437770 usec for pid 15 (yarrow) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -12360933 usec for pid 2 (g_event) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -2106022734 usec for pid 13 (swi4: clock sio) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 295074296 usec to 292484248 usec for pid 1 (init) dmesg output: Copyright (c) 1992-2007 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 is a registered trademark of The FreeBSD Foundation. FreeBSD 6.2-STABLE #0: Mon Aug 27 17:24:37 PDT 2007 [EMAIL PROTECTED]:/usr/obj/usr/src/sys/SMP-UMP Timecounter i8254 frequency 1193182 Hz quality 0 CPU: Intel(R) Pentium(R) III CPU family 1400MHz (1390.66-MHz 686-class CPU) Origin = GenuineIntel Id = 0x6b1 Stepping = 1 Features=0x383fbffFPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE real memory = 1073725440 (1023 MB) avail memory = 1037357056 (989 MB) ACPI APIC Table: COMPAQ 0083 FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): APIC ID: 3 cpu1 (AP): APIC ID: 0 MADT: Forcing active-low polarity and level trigger for SCI ioapic1 Version 1.1 irqs 16-31 on motherboard ioapic0 Version 1.1 irqs 0-15 on motherboard kbd1 at kbdmux0 ath_hal: 0.9.20.3 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413, RF5413) acpi0: COMPAQ P24 on motherboard acpi0: Power Button (fixed) Timecounter ACPI-fast frequency 3579545 Hz quality 1000 acpi_timer0: 24-bit timer at 3.579545MHz port 0x240-0x243 on acpi0 cpu0: ACPI CPU on acpi0 cpu1: ACPI CPU on acpi0 pcib0: ACPI Host-PCI bridge on acpi0 pci0: ACPI PCI bus on pcib0 ciss0: Compaq Smart Array 5i port 0x2000-0x20ff mem 0xf5ec-0xf5ef,0xf3ef-0xf3ef3fff irq 16 at device 1.0 on pci0 ciss0: [GIANT-LOCKED] fxp0: Intel 82559 Pro/100 Ethernet port 0x2400-0x243f mem 0xf5eb-0xf5eb0fff,0xf5d0-0xf5df irq 18 at device 2.0 on pci0 miibus0: MII bus on fxp0 inphy0: i82555 10/100 media interface on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto fxp0: Ethernet address: 00:02:a5:ad:82:d5 fxp1: Intel 82559 Pro/100 Ethernet port 0x2440-0x247f mem 0xf5cf-0xf5cf0fff,0xf5b0-0xf5bf irq 20 at device 4.0 on pci0 miibus1: MII bus on fxp1 inphy1: i82555 10/100 media interface on miibus1 inphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto fxp1: Ethernet address: 00:02:a5:ad:82:d4 pci0: base peripheral at device 6.0 (no driver attached) isab0: PCI-ISA bridge at
Re: Recent update to RELENG_6 creates lots of calcru warnings
James Long wrote: On Sun, Sep 02, 2007 at 01:18:05PM +0200, Kris Kennaway wrote: James Long wrote: I'm running FreeBSD RELENG_6 on a Compaq DL380 G2 dual PIII-1.4GHz with an SMP kernel. dmesg below. Prior to about August 12, the system had been quite stable. Since that time, I am finding lots of calcru warnings, which often cause system services to fail when they see unorthodox time shifts. Section 5.19 of the handbook appears not to be current any longer, suggesting setting kern.timecounter.method=1. kern.timecounter.hardware=i8254 also does not improve things. Other suggestions? Thanks, Jim Sep 1 18:00:30 ns sudo:james : TTY=ttyp0 ; PWD=/usr/local/www/sites/com.umpquanet/support ; USER=root ; COMMAND=/sbin/sysctl -w kern.timecounter.hardware=i8254 Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 1343309 usec to 1178478 usec for pid 3663 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1274607 usec for pid 3660 (hpasmd) ... big snip ... Does reverting to the older kernel work? If so, can you isolate which commit caused the problem by doing a binary search of date ranges? This should be easy to do since the rate of changes to RELENG_6 is low. Kris Please pardon the rookie question, but the machine is not blazingly fast to build (circa 90 minute world, 20 min. kernel). For the purposes of the binary search, may I build just the kernel, and would you suggest leaving the up-to-date world and running against a slightly older kernel as I test, or should I roll the world back to early August, and test against a slightly new kernel -- or will it matter? ___ freebsd-questions@freebsd.org mailing list http://lists.freebsd.org/mailman/listinfo/freebsd-questions To unsubscribe, send any mail to [EMAIL PROTECTED] You should only need to rebuild the kernel when searching. Kris ___ freebsd-questions@freebsd.org mailing list http://lists.freebsd.org/mailman/listinfo/freebsd-questions To unsubscribe, send any mail to [EMAIL PROTECTED]
Recent update to RELENG_6 creates lots of calcru warnings
I'm running FreeBSD RELENG_6 on a Compaq DL380 G2 dual PIII-1.4GHz with an SMP kernel. dmesg below. Prior to about August 12, the system had been quite stable. Since that time, I am finding lots of calcru warnings, which often cause system services to fail when they see unorthodox time shifts. Section 5.19 of the handbook appears not to be current any longer, suggesting setting kern.timecounter.method=1. kern.timecounter.hardware=i8254 also does not improve things. Other suggestions? Thanks, Jim Sep 1 18:00:30 ns sudo:james : TTY=ttyp0 ; PWD=/usr/local/www/sites/com.umpquanet/support ; USER=root ; COMMAND=/sbin/sysctl -w kern.timecounter.hardware=i8254 Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 1343309 usec to 1178478 usec for pid 3663 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1274607 usec for pid 3660 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 6470361 usec to 5811413 usec for pid 995 (postgres) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1099076 usec for pid 36 (pagedaemon) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -5928105 usec for pid 35 (fdc0) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1526937 usec for pid 32 (acpi_cooling0) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 8015143 usec to 1437770 usec for pid 15 (yarrow) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -12360933 usec for pid 2 (g_event) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -2106022786 usec for pid 13 (swi4: clock sio) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 295074296 usec to 292484248 usec for pid 1 (init) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 1343309 usec to 1178478 usec for pid 3663 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1274607 usec for pid 3660 (hpasmd) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 6470361 usec to 5811413 usec for pid 995 (postgres) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1099076 usec for pid 36 (pagedaemon) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -5928105 usec for pid 35 (fdc0) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -1526937 usec for pid 32 (acpi_cooling0) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 8015143 usec to 1437770 usec for pid 15 (yarrow) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -12360933 usec for pid 2 (g_event) Sep 1 18:02:44 ns kernel: calcru: negative runtime of -2106022734 usec for pid 13 (swi4: clock sio) Sep 1 18:02:44 ns kernel: calcru: runtime went backwards from 295074296 usec to 292484248 usec for pid 1 (init) dmesg output: Copyright (c) 1992-2007 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 is a registered trademark of The FreeBSD Foundation. FreeBSD 6.2-STABLE #0: Mon Aug 27 17:24:37 PDT 2007 [EMAIL PROTECTED]:/usr/obj/usr/src/sys/SMP-UMP Timecounter i8254 frequency 1193182 Hz quality 0 CPU: Intel(R) Pentium(R) III CPU family 1400MHz (1390.66-MHz 686-class CPU) Origin = GenuineIntel Id = 0x6b1 Stepping = 1 Features=0x383fbffFPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE real memory = 1073725440 (1023 MB) avail memory = 1037357056 (989 MB) ACPI APIC Table: COMPAQ 0083 FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): APIC ID: 3 cpu1 (AP): APIC ID: 0 MADT: Forcing active-low polarity and level trigger for SCI ioapic1 Version 1.1 irqs 16-31 on motherboard ioapic0 Version 1.1 irqs 0-15 on motherboard kbd1 at kbdmux0 ath_hal: 0.9.20.3 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413, RF5413) acpi0: COMPAQ P24 on motherboard acpi0: Power Button (fixed) Timecounter ACPI-fast frequency 3579545 Hz quality 1000 acpi_timer0: 24-bit timer at 3.579545MHz port 0x240-0x243 on acpi0 cpu0: ACPI CPU on acpi0 cpu1: ACPI CPU on acpi0 pcib0: ACPI Host-PCI bridge on acpi0 pci0: ACPI PCI bus on pcib0 ciss0: Compaq Smart Array 5i port 0x2000-0x20ff mem 0xf5ec-0xf5ef,0xf3ef-0xf3ef3fff irq 16 at device 1.0 on pci0 ciss0: [GIANT-LOCKED] fxp0: Intel 82559 Pro/100 Ethernet port 0x2400-0x243f mem 0xf5eb-0xf5eb0fff,0xf5d0-0xf5df irq 18 at device 2.0 on pci0 miibus0: MII bus on fxp0 inphy0: i82555 10/100 media interface on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto fxp0: Ethernet address: 00:02:a5:ad:82:d5 fxp1: Intel 82559 Pro/100 Ethernet port 0x2440-0x247f mem 0xf5cf-0xf5cf0fff,0xf5b0-0xf5bf irq 20 at device 4.0 on pci0 miibus1: MII bus on fxp1 inphy1: i82555 10/100 media interface on miibus1 inphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto fxp1: Ethernet address: 00:02:a5:ad:82:d4 pci0: base peripheral at device 6.0 (no driver attached) isab0: PCI-ISA bridge at device 15.0 on pci0
