From owner-freebsd-current@FreeBSD.ORG Mon May 29 15:30:47 2006 Return-Path: X-Original-To: freebsd-current@FreeBSD.org Delivered-To: freebsd-current@FreeBSD.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 742D816AA53 for ; Mon, 29 May 2006 15:30:47 +0000 (UTC) (envelope-from tataz@tataz.chchile.org) Received: from smtp5-g19.free.fr (smtp5-g19.free.fr [212.27.42.35]) by mx1.FreeBSD.org (Postfix) with ESMTP id C239E43D53 for ; Mon, 29 May 2006 15:30:46 +0000 (GMT) (envelope-from tataz@tataz.chchile.org) Received: from tatooine.tataz.chchile.org (tataz.chchile.org [82.233.239.98]) by smtp5-g19.free.fr (Postfix) with ESMTP id 0E33C2770F for ; Mon, 29 May 2006 17:30:46 +0200 (CEST) Received: from obiwan.tataz.chchile.org (unknown [192.168.1.25]) by tatooine.tataz.chchile.org (Postfix) with ESMTP id 5A4E29B4E0 for ; Mon, 29 May 2006 15:31:03 +0000 (UTC) Received: by obiwan.tataz.chchile.org (Postfix, from userid 1000) id 4B0C14071; Mon, 29 May 2006 17:31:03 +0200 (CEST) Date: Mon, 29 May 2006 17:31:03 +0200 From: Jeremie Le Hen To: freebsd-current@FreeBSD.org Message-ID: <20060529153103.GA17178@obiwan.tataz.chchile.org> Mime-Version: 1.0 Content-Type: multipart/mixed; boundary="fUYQa+Pmc3FrFX/N" Content-Disposition: inline User-Agent: Mutt/1.5.11 Cc: Subject: timer goes sluggish X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 29 May 2006 15:30:56 -0000 --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: 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 Features2=0x180 AMD Features=0x100000 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 irqs 0-23 on motherboard acpi0: 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: port 0x62,0x66 on acpi0 cpu0: on acpi0 est0: on cpu0 p4tcc0: on cpu0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: irq 16 at device 1.0 on pci0 pci1: on pcib1 vgapci0: port 0x3000-0x30ff mem 0xd0000000-0xd7ffffff,0xc8100000-0xc810ffff irq 16 at device 0.0 on pci1 pcib2: irq 17 at device 28.0 on pci0 pci9: on pcib2 pcib3: irq 16 at device 28.1 on pci0 pci10: on pcib3 pcib4: irq 18 at device 28.2 on pci0 pci2: on pcib4 uhci0: port 0x1800-0x181f irq 23 at device 29.0 on pci0 uhci0: [GIANT-LOCKED] uhci0: LegSup = 0x003b usb0: on uhci0 usb0: USB revision 1.0 usbd_get_string: getting lang failed, using 0 uhub0: on usb0 uhub0: 2 ports with 2 removable, self powered uhci1: port 0x1820-0x183f irq 19 at device 29.1 on pci0 uhci1: [GIANT-LOCKED] uhci1: LegSup = 0x0010 usb1: on uhci1 usb1: USB revision 1.0 usbd_get_string: getting lang failed, using 0 uhub1: on usb1 uhub1: 2 ports with 2 removable, self powered uhci2: port 0x1840-0x185f irq 18 at device 29.2 on pci0 uhci2: [GIANT-LOCKED] uhci2: LegSup = 0x0010 usb2: on uhci2 usb2: USB revision 1.0 usbd_get_string: getting lang failed, using 0 uhub2: on usb2 uhub2: 2 ports with 2 removable, self powered uhci3: port 0x1860-0x187f irq 16 at device 29.3 on pci0 uhci3: [GIANT-LOCKED] uhci3: LegSup = 0x0010 usb3: on uhci3 usb3: USB revision 1.0 usbd_get_string: getting lang failed, using 0 uhub3: on usb3 uhub3: 2 ports with 2 removable, self powered ehci0: 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: on ehci0 usb4: USB revision 2.0 uhub4: on usb4 uhub4: 8 ports with 8 removable, self powered pcib5: at device 30.0 on pci0 pci6: on pcib5 cbb0: mem 0xc8216000-0xc8216fff irq 18 at device 1.0 on pci6 cardbus0: on cbb0 pccard0: <16-bit PCCard bus> on cbb0 pci6: at device 1.2 (no driver attached) pci6: at device 1.3 (no driver attached) pci6: at device 3.0 (no driver attached) bge0: mem 0xc8200000-0xc820ffff irq 16 at device 8.0 on pci6 miibus0: on bge0 brgphy0: 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: at device 30.2 (no driver attached) pci0: at device 30.3 (no driver attached) isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x18c0-0x18cf at device 31.1 on pci0 ata0: on atapci0 ata1: on atapci0 pci0: at device 31.3 (no driver attached) acpi_acad0: on acpi0 battery0: on acpi0 battery1: on acpi0 acpi_lid0: on acpi0 acpi_button0: on acpi0 acpi_button1: on acpi0 acpi_tz0: on acpi0 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] psm0: irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: model Generic PS/2 mouse, device ID 0 pmtimer0 on isa0 orm0: at iomem 0xc0000-0xcdfff,0xce000-0xcf7ff,0xe0000-0xe17ff pnpid ORM0000 on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: 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 at ata0-master UDMA33 acd0: DVDR 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--