Skip site navigation (1)Skip section navigation (2)
Date:      Mon, 29 May 2006 17:31:03 +0200
From:      Jeremie Le Hen <jeremie@le-hen.org>
To:        freebsd-current@FreeBSD.org
Subject:   timer goes sluggish
Message-ID:  <20060529153103.GA17178@obiwan.tataz.chchile.org>

next in thread | raw e-mail | index | archive | help

--fUYQa+Pmc3FrFX/N
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline

Hi,

I won't write too much, the following session will tell much more:

% jarjarbinks:root# ntpdate -b chronos.cru.fr
% 29 May 17:08:44 ntpdate[4254]: step time server 195.220.94.163 offset 0.000273 sec
% jarjarbinks:root# sysctl kern.timecounter.hardware
% kern.timecounter.hardware: ACPI-fast
% jarjarbinks:root# while : ; do date ; sleep 1 ; done
% Mon May 29 17:08:56 CEST 2006
% Mon May 29 17:09:03 CEST 2006
% Mon May 29 17:09:09 CEST 2006
% Mon May 29 17:09:16 CEST 2006
% Mon May 29 17:09:22 CEST 2006
% ^C
% jarjarbinks:root# ntpdate -b chronos.cru.fr
% 29 May 17:09:32 ntpdate[4266]: step time server 195.220.94.163 offset 0.000937 sec
% jarjarbinks:root# sysctl kern.timecounter.hardware=TSC
% kern.timecounter.hardware: ACPI-fast -> TSC
% jarjarbinks:root# while : ; do date ; sleep 1 ; done
% Mon May 29 17:09:41 CEST 2006
% Mon May 29 17:09:42 CEST 2006
% Mon May 29 17:09:43 CEST 2006
% Mon May 29 17:09:44 CEST 2006
% Mon May 29 17:09:45 CEST 2006
% ^C
% jarjarbinks:root# ntpdate -b chronos.cru.fr
% 29 May 17:10:16 ntpdate[4278]: step time server 195.220.94.163 offset 29.503343 sec

I know this is a FAQ when CPU frequency throttling is used, but this is
not the case here.  I killed powerd(8) and dev.cpu.0.freq is set to its
highest frequency (1733).

IOW, using ACPI-fast as timecounter leads to have "sleep 1" actually
sleep for 6~7 seconds.  Using TSC as timecounter is even weirder since it
desynchronize the computer's clock.

I attached the dmesg of my computer, and can provide verbose dmesg if
need, or anything else requested to help debugging.

Thank you.
Regards,
-- 
Jeremie Le Hen
< jeremie at le-hen dot org >< ttz at chchile dot org >

--fUYQa+Pmc3FrFX/N
Content-Type: text/plain; charset=us-ascii
Content-Disposition: attachment; filename=dmesg

Copyright (c) 1992-2006 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 7.0-CURRENT #0: Wed May 17 00:52:17 CEST 2006
    root@jarjarbinks.tataz.chchile.org:/usr/obj/usr/src/sys/JARJARBINKS
WARNING: DIAGNOSTIC option enabled, expect reduced performance.
WARNING: debug.mpsafenet forced to 0 as ipsec requires Giant
WARNING: MPSAFE network stack disabled, expect reduced performance.
ACPI APIC Table: <INTEL  ALVISO  >
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel(R) Pentium(R) M processor 1.73GHz (1729.01-MHz 686-class CPU)
  Origin = "GenuineIntel"  Id = 0x6d8  Stepping = 8
  Features=0xafe9fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,TM,PBE>
  Features2=0x180<EST,TM2>
  AMD Features=0x100000<NX>
real memory  = 1072168960 (1022 MB)
avail memory = 1040154624 (991 MB)
    ACPI-0390: *** Info: Table [SSDT] replaced by host OS
    ACPI-0390: *** Info: Table [SSDT] replaced by host OS
    ACPI-0390: *** Info: Table [SSDT] replaced by host OS
ACPI: overriding DSDT/SSDT with custom table
    ACPI-0390: *** Info: Table [DSDT] replaced by host OS
ioapic0 <Version 2.0> irqs 0-23 on motherboard
acpi0: <PTLTD   RSDT> on motherboard
acpi: bad write to port 0x080 (32), val 0xaa
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi_bus_number: can't get _ADR
acpi0: Power Button (fixed)
unknown: I/O range not supported
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
acpi_ec0: <Embedded Controller: GPE 0x1d> port 0x62,0x66 on acpi0
cpu0: <ACPI CPU> on acpi0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
vgapci0: <VGA-compatible display> port 0x3000-0x30ff mem 0xd0000000-0xd7ffffff,0xc8100000-0xc810ffff irq 16 at device 0.0 on pci1
pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.0 on pci0
pci9: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> irq 16 at device 28.1 on pci0
pci10: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> irq 18 at device 28.2 on pci0
pci2: <ACPI PCI bus> on pcib4
uhci0: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-A> port 0x1800-0x181f irq 23 at device 29.0 on pci0
uhci0: [GIANT-LOCKED]
uhci0: LegSup = 0x003b
usb0: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-A> on uhci0
usb0: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub0: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb0
uhub0: 2 ports with 2 removable, self powered
uhci1: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-B> port 0x1820-0x183f irq 19 at device 29.1 on pci0
uhci1: [GIANT-LOCKED]
uhci1: LegSup = 0x0010
usb1: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-B> on uhci1
usb1: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub1: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb1
uhub1: 2 ports with 2 removable, self powered
uhci2: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-C> port 0x1840-0x185f irq 18 at device 29.2 on pci0
uhci2: [GIANT-LOCKED]
uhci2: LegSup = 0x0010
usb2: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-C> on uhci2
usb2: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub2: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb2
uhub2: 2 ports with 2 removable, self powered
uhci3: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-D> port 0x1860-0x187f irq 16 at device 29.3 on pci0
uhci3: [GIANT-LOCKED]
uhci3: LegSup = 0x0010
usb3: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-D> on uhci3
usb3: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub3: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb3
uhub3: 2 ports with 2 removable, self powered
ehci0: <Intel 82801FB (ICH6) USB 2.0 controller> mem 0xc8000000-0xc80003ff irq 23 at device 29.7 on pci0
ehci0: [GIANT-LOCKED]
usb4: EHCI version 1.0
usb4: companion controllers, 2 ports each: usb0 usb1 usb2 usb3
usb4: <Intel 82801FB (ICH6) USB 2.0 controller> on ehci0
usb4: USB revision 2.0
uhub4: <Intel EHCI root hub, class 9/0, rev 2.00/1.00, addr 1> on usb4
uhub4: 8 ports with 8 removable, self powered
pcib5: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci6: <ACPI PCI bus> on pcib5
cbb0: <TI6411 PCI-CardBus Bridge> mem 0xc8216000-0xc8216fff irq 18 at device 1.0 on pci6
cardbus0: <CardBus bus> on cbb0
pccard0: <16-bit PCCard bus> on cbb0
pci6: <serial bus, FireWire> at device 1.2 (no driver attached)
pci6: <mass storage> at device 1.3 (no driver attached)
pci6: <network> at device 3.0 (no driver attached)
bge0: <Broadcom BCM5788 Gigabit Ethernet, ASIC rev. 0x3003> mem 0xc8200000-0xc820ffff irq 16 at device 8.0 on pci6
miibus0: <MII bus> on bge0
brgphy0: <BCM5705 10/100/1000baseTX PHY> on miibus0
brgphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseTX, 1000baseTX-FDX, auto
bge0: Ethernet address: 00:c0:9f:94:39:8f
bge0: [GIANT-LOCKED]
pci0: <multimedia, audio> at device 30.2 (no driver attached)
pci0: <simple comms, generic modem> at device 30.3 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH6 UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x18c0-0x18cf at device 31.1 on pci0
ata0: <ATA channel 0> on atapci0
ata1: <ATA channel 1> on atapci0
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
acpi_acad0: <AC Adapter> on acpi0
battery0: <ACPI Control Method Battery> on acpi0
battery1: <ACPI Control Method Battery> on acpi0
acpi_lid0: <Control Method Lid Switch> on acpi0
acpi_button0: <Power Button> on acpi0
acpi_button1: <Sleep Button> on acpi0
acpi_tz0: <Thermal Zone> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model Generic PS/2 mouse, device ID 0
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xcdfff,0xce000-0xcf7ff,0xe0000-0xe17ff pnpid ORM0000 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 1729012438 Hz quality 800
Timecounters tick every 1.000 msec
IPsec: Initialized Security Association Processing.
ipfw2 initialized, divert loadable, rule-based forwarding enabled, default to accept, logging unlimited
ad0: DMA limited to UDMA33, controller found non-ATA66 cable
ad0: 76319MB <TOSHIBA MK8025GAS KA023A> at ata0-master UDMA33
acd0: DVDR <MATSHITAUJ-845D/D100> at ata0-slave UDMA33
acpi: bad write to port 0x080 (32), val 0x1c
cpu0: Cx states changed
cpu0: Cx states changed
acpi: bad write to port 0x080 (32), val 0x1c
Trying to mount root from ufs:/dev/ad0s1a
acpi: bad write to port 0x080 (32), val 0x1c
cpu0: Cx states changed
bge0: link state changed to UP
Expensive timeout(9) function: 0xc06edb20(0xc08007a0) 0.003415517 s
acpi: bad write to port 0x080 (32), val 0x8a

--fUYQa+Pmc3FrFX/N--



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20060529153103.GA17178>