From owner-freebsd-acpi@FreeBSD.ORG Mon Apr 18 19:24:11 2005 Return-Path: Delivered-To: freebsd-acpi@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id EFE0216A4CE for ; Mon, 18 Apr 2005 19:24:10 +0000 (GMT) Received: from chimie.u-strasbg.fr (chimie.u-strasbg.fr [130.79.34.77]) by mx1.FreeBSD.org (Postfix) with ESMTP id 7B19443D41 for ; Mon, 18 Apr 2005 19:24:09 +0000 (GMT) (envelope-from gb@isis.u-strasbg.fr) Received: from localhost (localhost.u-strasbg.fr [127.0.0.1]) by chimie.u-strasbg.fr (Postfix) with ESMTP id 660F023EF9 for ; Mon, 18 Apr 2005 21:24:08 +0200 (CEST) Received: from chimie.u-strasbg.fr ([127.0.0.1]) by localhost (chimie [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 10580-06 for ; Mon, 18 Apr 2005 21:24:08 +0200 (CEST) Received: from 6nq.u-strasbg.fr (chimie.u-strasbg.fr [130.79.34.77]) by chimie.u-strasbg.fr (Postfix) with ESMTP id EAF7E23CAA for ; Mon, 18 Apr 2005 21:24:07 +0200 (CEST) Received: by 6nq.u-strasbg.fr (Postfix, from userid 1001) id 5B9FC64BF; Mon, 18 Apr 2005 21:22:35 +0200 (CEST) Date: Mon, 18 Apr 2005 21:22:35 +0200 From: Guy Brand To: freebsd-acpi@freebsd.org Message-ID: <20050418192235.GA867@chimie.u-strasbg.fr> References: <20050417205230.GC837@chimie.u-strasbg.fr> <4263E9BF.4050501@root.org> Mime-Version: 1.0 Content-Type: text/plain; charset=iso-8859-1 Content-Disposition: inline In-Reply-To: <4263E9BF.4050501@root.org> User-Agent: Mutt/1.5.9i X-Virus-Scanned: by ClamAV at chimie.u-strasbg.fr Subject: Re: hw.acpi.battery.life alway at 100 X-BeenThere: freebsd-acpi@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: ACPI and power management development List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 18 Apr 2005 19:24:11 -0000 On 18 avril at 10:09, Nate Lawson wrote: > > hw.acpi.battery.time: 94 > > You'll have to do more investigating. There were no MFCs that I can see > to acpi between those two dates. First, compare the output of dmesg > between the working and non-working kernels and see if anything has changed. OK. So as shown only hw.acpi.battery.life is wrong in sysctl output. An other symptom is related to this problem: just after a boot the system is weird, the keyboard is reactive (changing ttyv ok, typing commands ok) but answers to commands are very very slow. This strange state lasts around 5 minutes during which hw.acpi.battery.life: -1 hw.acpi.battery.time: -1 hw.acpi.battery.state: 7 hw.acpi.battery.units: 1 hw.acpi.battery.info_expire: 5 after this delay, the logs shows a line: acpi_cmbat0: battery initialization done, tried 1 times and the system is responsive as usual, but the life status is wrong (sticked to 100) as written in my previous mail. I diffed the dmesg outputs, here is it: --- mm-prerelease Mon Apr 18 21:14:47 2005 +++ mm-stable Mon Apr 18 21:17:07 2005 @@ -1,22 +1,22 @@ kernel: Copyright (c) 1992-2005 The FreeBSD Project. kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 kernel: The Regents of the University of California. All rights reserved. -kernel: FreeBSD 5.4-PRERELEASE #3: Thu Mar 17 09:54:55 CET 2005 +kernel: FreeBSD 5.4-STABLE #4: Sun Apr 17 01:23:49 CEST 2005 kernel: root@6nq.u-strasbg.fr:/usr/obj/usr/src/sys/GENERIC -kernel: Preloaded elf kernel "/boot/kernel.old/kernel" at 0xc0a57000. -kernel: Preloaded elf module "/boot/kernel/sound.ko" at 0xc0a571d0. -kernel: Preloaded elf module "/boot/kernel/snd_ich.ko" at 0xc0a5727c. -kernel: Preloaded elf module "/boot/kernel/acpi.ko" at 0xc0a57328. +kernel: Preloaded elf kernel "/boot/kernel/kernel" at 0xc0a60000. +kernel: Preloaded elf module "/boot/kernel/sound.ko" at 0xc0a601cc. +kernel: Preloaded elf module "/boot/kernel/snd_ich.ko" at 0xc0a60278. +kernel: Preloaded elf module "/boot/kernel/acpi.ko" at 0xc0a60368. kernel: Table 'FACP' at 0x1f740200 kernel: Table 'APIC' at 0x1f740300 kernel: MADT: Found table at 0x1f740300 kernel: APIC: Using the MADT enumerator. kernel: MADT: Found CPU APIC ID 0 ACPI ID 1: enabled kernel: ACPI APIC Table: -kernel: Calibrating clock(s) ... i8254 clock: 1193178 Hz +kernel: Calibrating clock(s) ... i8254 clock: 1193180 Hz kernel: CLK_USE_I8254_CALIBRATION not specified - using default frequency kernel: Timecounter "i8254" frequency 1193182 Hz quality 0 -kernel: Calibrating TSC clock ... TSC clock: 600023526 Hz +kernel: Calibrating TSC clock ... TSC clock: 600024615 Hz kernel: CPU: Intel(R) Pentium(R) M processor 1600MHz (600.02-MHz 686-class CPU) kernel: Origin = "GenuineIntel" Id = 0x695 Stepping = 5 kernel: Features=0xa7e9fbbf @@ -91,7 +91,7 @@ kernel: acpi0: Power Button (fixed) kernel: acpi_ec0: port 0x66,0x62 on acpi0 kernel: AcpiOsDerivePciId: bus 0 dev 31 func 0 -kernel: ACPI timer: 1/0 1/1 1/1 1/1 1/1 1/0 1/1 1/1 1/1 1/1 -> 10 +kernel: ACPI timer: 1/1 1/1 1/1 1/1 1/1 1/0 1/1 1/1 1/0 1/1 -> 10 kernel: Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000 kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 0xe408-0xe40a on acpi0 kernel: cpu0: on acpi0 @@ -225,8 +225,6 @@ kernel: usb0: USB revision 1.0 kernel: uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 kernel: uhub0: 2 ports with 2 removable, self powered -kernel: ums0: Logitech USB-PS/2 Optical Mouse, rev 2.00/11.10, addr 2, iclass 3/1 -kernel: ums0: 3 buttons and Z dir. kernel: uhci1: port 0xd800-0xd81f irq 19 at device 29.1 on pci0 kernel: uhci1: Reserved 0x20 bytes for rid 0x20 type 4 at 0xd800 kernel: uhci1: [GIANT-LOCKED] @@ -392,8 +390,6 @@ kernel: atapci0: Reserved 0x8 bytes for rid 0x10 type 4 at 0x1f0 kernel: atapci0: Reserved 0x1 bytes for rid 0x14 type 4 at 0x3f6 kernel: ata0: reset tp1 mask=03 ostat0=50 ostat1=00 -kernel: ata0-master: stat=0x80 err=0x80 lsb=0x80 msb=0x80 -last message repeated 2 times kernel: ata0-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00 kernel: ata0-slave: stat=0x00 err=0x01 lsb=0x00 msb=0x00 kernel: ata0: reset tp2 stat0=50 stat1=00 devices=0x1 @@ -415,9 +411,9 @@ kernel: pcm0: kernel: pcm0: Codec features headphone, 6 bit master volume, Analog Devices Phat Stereo kernel: pcm0: Primary codec extended features variable rate PCM -kernel: pcm0: sndbuf_setmap 1eb92000, 4000; 0xdd074000 -> 1eb92000 -kernel: pcm0: sndbuf_setmap 1eb8e000, 4000; 0xdd078000 -> 1eb8e000 -kernel: pci0: at device 31.6 (no driver attached) +kernel: pcm0: sndbuf_setmap 1eb94000, 4000; 0xdd06d000 -> 1eb94000 +kernel: pcm0: sndbuf_setmap 1eb90000, 4000; 0xdd071000 -> 1eb90000 +kernel: pci0: at device 31.6 (no driver attached) kernel: acpi_button0: on acpi0 kernel: acpi_lid0: on acpi0 kernel: acpi_tz0: on acpi0 @@ -512,9 +508,11 @@ kernel: 3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff kernel: vt0: not probed (disabled) kernel: isa_probe_children: probing PnP devices +kernel: ums0: Logitech USB-PS/2 Optical Mouse, rev 2.00/11.10, addr 2, iclass 3/1 +kernel: ums0: 3 buttons and Z dir. kernel: Device configuration finished. kernel: procfs registered -kernel: Timecounter "TSC" frequency 600023526 Hz quality 800 +kernel: Timecounter "TSC" frequency 600024615 Hz quality 800 kernel: Timecounters tick every 10.000 msec kernel: lo0: bpf attached kernel: acpi_acad0: acline initialization start @@ -532,7 +530,7 @@ kernel: ad0: 16 secs/int, 1 depth queue, UDMA100 kernel: GEOM: new disk ad0 kernel: ar: FreeBSD check1 failed -kernel: pcm0: measured ac97 link rate at 48007 Hz, will use 48000 Hz +kernel: pcm0: measured ac97 link rate at 48008 Hz, will use 48000 Hz kernel: [0] f:80 typ:165 s(CHS):0/1/1 e(CHS):1023/254/63 s:63 l:117210177 kernel: [1] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0 kernel: [2] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0 @@ -556,14 +554,15 @@ kernel: [1] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0 kernel: [2] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0 kernel: [3] f:80 typ:165 s(CHS):0/0/1 e(CHS):1023/254/63 s:0 l:50000 +kernel: acpi_ec0: info: new max delay is 100 us kernel: acpi_ec0: info: new max delay is 120 us kernel: acpi_ec0: info: new max delay is 220 us -kernel: (probe0:sbp0:0:0:0): error 22 -kernel: (probe0:sbp0:0:0:0): Unretryable Error kernel: (probe1:sbp0:0:1:0): error 22 kernel: (probe1:sbp0:0:1:0): Unretryable Error kernel: (probe2:sbp0:0:2:0): error 22 kernel: (probe2:sbp0:0:2:0): Unretryable Error +kernel: (probe0:sbp0:0:0:0): error 22 +kernel: (probe0:sbp0:0:0:0): Unretryable Error kernel: (probe3:sbp0:0:3:0): error 22 kernel: (probe3:sbp0:0:3:0): Unretryable Error kernel: (probe4:sbp0:0:4:0): error 22 @@ -587,3 +586,9 @@ kernel: Mounting root from ufs:/dev/ad0s1a kernel: start_init: trying /sbin/init kernel: splash: image decoder found: logo_saver +kernel: acpi_ec0: info: new max delay is 610 us +kernel: acpi_ec0: info: new max delay is 630 us +kernel: acpi_ec0: info: new max delay is 820 us +kernel: acpi_cmbat0: battery initialization failed, giving up +kernel: acpi_ec0: info: new max delay is 930 us gb