Bug 5902 - When playing DVB stream on P4 celeron (128kB cache) picture got broken reading info from battery
Summary: When playing DVB stream on P4 celeron (128kB cache) picture got broken readin...
Status: CLOSED CODE_FIX
Alias: None
Product: ACPI
Classification: Unclassified
Component: Power-Battery (show other bugs)
Hardware: i386 Linux
: P2 normal
Assignee: acpi_power-battery
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2006-01-16 03:54 UTC by Cijoml Cijomlovic Cijomlov
Modified: 2007-05-23 10:31 UTC (History)
3 users (show)

See Also:
Kernel Version: 2.6.18 and all lower
Subsystem:
Regression: ---
Bisected commit-id:


Attachments
acpidump (94.74 KB, application/octet-stream)
2006-05-20 13:58 UTC, Cijoml Cijomlovic Cijomlov
Details
dmesg-2.6.18-rc1 (17.84 KB, text/plain)
2006-07-09 12:27 UTC, Cijoml Cijomlovic Cijomlov
Details
/tmp/pstree -a (2.83 KB, application/octet-stream)
2006-07-11 23:49 UTC, Cijoml Cijomlovic Cijomlov
Details
C prog (1.84 KB, text/plain)
2006-07-18 11:21 UTC, Vladimir Lebedev
Details
Makefile (68 bytes, text/plain)
2006-07-18 11:23 UTC, Vladimir Lebedev
Details
bat_time.c (1.84 KB, text/plain)
2006-07-18 11:29 UTC, Vladimir Lebedev
Details
debug patch (3.93 KB, patch)
2006-07-19 02:08 UTC, Vladimir Lebedev
Details | Diff
catacpi (232 bytes, text/plain)
2006-07-24 09:00 UTC, Vladimir Lebedev
Details
bat_spy.diff (5.81 KB, patch)
2006-07-25 09:53 UTC, Vladimir Lebedev
Details | Diff
bat_spy.diff (13.04 KB, patch)
2006-07-26 14:41 UTC, Vladimir Lebedev
Details | Diff
bat_spy1.diff (15.21 KB, patch)
2006-07-29 08:41 UTC, Vladimir Lebedev
Details | Diff
bat_spy2.diff (15.97 KB, patch)
2006-07-31 16:28 UTC, Vladimir Lebedev
Details | Diff
Optimized ec. (44.00 KB, patch)
2006-09-25 22:37 UTC, Vladimir Lebedev
Details | Diff
dsdt_patch_1 (1.46 KB, patch)
2006-10-27 03:39 UTC, Vladimir Lebedev
Details | Diff
dsdt.dsl with errors (192.75 KB, application/octet-stream)
2006-10-27 09:06 UTC, Cijoml Cijomlovic Cijomlov
Details
dmesg for 2.6.18.1 when udev was installed instead of hotplug (17.56 KB, application/octet-stream)
2006-10-28 02:55 UTC, Cijoml Cijomlovic Cijomlov
Details
acpidump-acer-TM240-BIOS-1.15 (94.74 KB, application/octet-stream)
2006-10-28 04:04 UTC, Cijoml Cijomlovic Cijomlov
Details
dsdt_patch_2 (2.18 KB, patch)
2006-10-29 06:09 UTC, Vladimir Lebedev
Details | Diff

Description Cijoml Cijomlovic Cijomlov 2006-01-16 03:54:21 UTC
Most recent kernel where this bug did not occur:
Distribution: Debian testing
Hardware Environment: Intel Pentium 4 celeron, i852 chipset
Software Environment: Debian testing, kernel 2.6.15
Problem Description:

When playing on P4 Celeron, picture often got bad and in dmesg I see:
Jan 13 20:50:52 notas kernel: pluto2 0000:03:00.0: overflow irq (10)
Jan 13 20:50:53 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 20:50:56 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 20:50:56 notas kernel: pluto2 0000:03:00.0: overflow irq (8)
Jan 13 20:50:57 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 20:50:57 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 20:50:57 notas kernel: pluto2 0000:03:00.0: overflow irq (8)
Jan 13 20:51:00 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 20:51:05 notas kernel: pluto2 0000:03:00.0: overflow irq (9)
Jan 13 20:51:08 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:04 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:13 notas kernel: pluto2 0000:03:00.0: overflow irq (11)
Jan 13 22:51:13 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:21 notas kernel: pluto2 0000:03:00.0: overflow irq (11)
Jan 13 22:51:21 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:22 notas kernel: pluto2 0000:03:00.0: overflow irq (11)
Jan 13 22:51:24 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:25 notas kernel: pluto2 0000:03:00.0: overflow irq (11)
Jan 13 22:51:25 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:25 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:25 notas kernel: pluto2 0000:03:00.0: overflow irq (10)
Jan 13 22:51:28 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:28 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:28 notas kernel: pluto2 0000:03:00.0: overflow irq (12)
Jan 13 22:51:29 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:29 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:29 notas kernel: pluto2 0000:03:00.0: overflow irq (11)
Jan 13 22:51:29 notas kernel: pluto2 0000:03:00.0: overflow irq (1)
Jan 13 22:51:29 notas kernel: pluto2 0000:03:00.0: overflow irq (1)



Steps to reproduce:
Play on the same hardware (Acer TravelMate 242)

notas:/home/cijoml# lspci -vvv
0000:00:00.0 Host bridge: Intel Corporation 82852/82855 GM/GME/PM/GMV Processor
to I/O Controller (rev 02)
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR+ FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort+ >SERR- <PERR-
        Latency: 0
        Region 0: Memory at <unassigned> (32-bit, prefetchable)
        Capabilities: [40] #09 [0105]

0000:00:00.1 System peripheral: Intel Corporation 82852/82855 GM/GME/PM/GMV
Processor to I/O Controller (rev 02)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR-
        Latency: 0

0000:00:00.3 System peripheral: Intel Corporation 82852/82855 GM/GME/PM/GMV
Processor to I/O Controller (rev 02)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR-
        Latency: 0

0000:00:02.0 VGA compatible controller: Intel Corporation 82852/855GM Integrated
Graphics Device (rev 02) (prog-if 00 [VGA])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin A routed to IRQ 11
        Region 0: Memory at e8000000 (32-bit, prefetchable) [size=128M]
        Region 1: Memory at e0000000 (32-bit, non-prefetchable) [size=512K]
        Region 2: I/O ports at 1800 [size=8]
        Capabilities: [d0] Power Management version 1
                Flags: PMEClk- DSI+ D1+ D2- AuxCurrent=0mA
PME(D0-,D1-,D2-,D3hot-,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

0000:00:02.1 Display controller: Intel Corporation 82852/855GM Integrated
Graphics Device (rev 02)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR-
        Region 0: Memory at f0000000 (32-bit, prefetchable) [size=128M]
        Region 1: Memory at e0080000 (32-bit, non-prefetchable) [size=512K]
        Capabilities: [d0] Power Management version 1
                Flags: PMEClk- DSI+ D1+ D2- AuxCurrent=0mA
PME(D0-,D1-,D2-,D3hot-,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

0000:00:1d.0 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #1 (rev 03) (prog-if 00 [UHCI])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin A routed to IRQ 11
        Region 4: I/O ports at 1820 [size=32]

0000:00:1d.1 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #2 (rev 03) (prog-if 00 [UHCI])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin B routed to IRQ 11
        Region 4: I/O ports at 1840 [size=32]

0000:00:1d.2 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #3 (rev 03) (prog-if 00 [UHCI])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin C routed to IRQ 10
        Region 4: I/O ports at 1860 [size=32]

0000:00:1d.7 USB Controller: Intel Corporation 82801DB/DBM (ICH4/ICH4-M) USB2
EHCI Controller (rev 03) (prog-if 20 [EHCI])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR+ FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin D routed to IRQ 11
        Region 0: Memory at e0100000 (32-bit, non-prefetchable) [size=1K]
        Capabilities: [50] Power Management version 2
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=375mA
PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-
        Capabilities: [58] #0a [2080]

0000:00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev 83)
(prog-if 00 [Normal decode])
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR+ FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR+
        Latency: 0
        Bus: primary=00, secondary=02, subordinate=02, sec-latency=64
        I/O behind bridge: 00003000-00003fff
        Memory behind bridge: e0200000-e02fffff
        Prefetchable memory behind bridge: 30000000-34ffffff
        BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-

0000:00:1f.0 ISA bridge: Intel Corporation 82801DBM (ICH4-M) LPC Interface
Bridge (rev 03)
        Control: I/O+ Mem+ BusMaster+ SpecCycle+ MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0

0000:00:1f.1 IDE interface: Intel Corporation 82801DBM (ICH4-M) IDE Controller
(rev 03) (prog-if 8a [Master SecP PriP])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin A routed to IRQ 10
        Region 0: I/O ports at <unassigned>
        Region 1: I/O ports at <unassigned>
        Region 2: I/O ports at <unassigned>
        Region 3: I/O ports at <unassigned>
        Region 4: I/O ports at 1810 [size=16]
        Region 5: Memory at 35000000 (32-bit, non-prefetchable) [size=1K]

0000:00:1f.3 SMBus: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) SMBus
Controller (rev 03)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Interrupt: pin B routed to IRQ 10
        Region 4: I/O ports at 1880 [size=32]

0000:00:1f.5 Multimedia audio controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) AC'97 Audio Controller (rev 03)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin B routed to IRQ 10
        Region 0: I/O ports at 1c00 [size=256]
        Region 1: I/O ports at 18c0 [size=64]
        Region 2: Memory at e0100c00 (32-bit, non-prefetchable) [size=512]
        Region 3: Memory at e0100800 (32-bit, non-prefetchable) [size=256]
        Capabilities: [50] Power Management version 2
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=375mA
PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

0000:00:1f.6 Modem: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) AC'97
Modem Controller (rev 03) (prog-if 00 [Generic])
        Subsystem: Acer Incorporated [ALI]: Unknown device 0038
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin B routed to IRQ 10
        Region 0: I/O ports at 2400 [size=256]
        Region 1: I/O ports at 2000 [size=128]
        Capabilities: [50] Power Management version 2
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=375mA
PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

0000:02:04.0 CardBus bridge: Texas Instruments PCI1520 PC card Cardbus
Controller (rev 01)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 168, Cache Line Size: 0x20 (128 bytes)
        Interrupt: pin A routed to IRQ 10
        Region 0: Memory at e0201000 (32-bit, non-prefetchable) [size=4K]
        Bus: primary=02, secondary=03, subordinate=06, sec-latency=176
        Memory window 0: 30000000-31fff000 (prefetchable)
        Memory window 1: 36000000-37fff000
        I/O window 0: 00003400-000034ff
        I/O window 1: 00003800-000038ff
        BridgeCtl: Parity- SERR- ISA- VGA- MAbort- >Reset+ 16bInt+ PostWrite+
        16-bit legacy interface ports at 0001

0000:02:04.1 CardBus bridge: Texas Instruments PCI1520 PC card Cardbus
Controller (rev 01)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 168, Cache Line Size: 0x20 (128 bytes)
        Interrupt: pin B routed to IRQ 10
        Region 0: Memory at e0202000 (32-bit, non-prefetchable) [size=4K]
        Bus: primary=02, secondary=07, subordinate=0a, sec-latency=176
        Memory window 0: 32000000-33fff000 (prefetchable)
        Memory window 1: 38000000-39fff000
        I/O window 0: 00003c00-00003cff
        I/O window 1: 00001400-000014ff
        BridgeCtl: Parity- SERR- ISA- VGA- MAbort- >Reset+ 16bInt+ PostWrite+
        16-bit legacy interface ports at 0001

0000:02:0a.0 Ethernet controller: Realtek Semiconductor Co., Ltd.
RTL-8139/8139C/8139C+ (rev 10)
        Subsystem: Acer Incorporated [ALI]: Unknown device 0039
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR+ FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 64 (8000ns min, 16000ns max)
        Interrupt: pin A routed to IRQ 11
        Region 0: I/O ports at 3000 [size=256]
        Region 1: Memory at e0200000 (32-bit, non-prefetchable) [size=256]
        Expansion ROM at 34000000 [disabled] [size=64K]
        Capabilities: [50] Power Management version 2
                Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=375mA
PME(D0-,D1+,D2+,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-
Comment 1 Cijoml Cijomlovic Cijomlov 2006-01-16 03:58:37 UTC
I tested also in this hardware P4 Mobile (512kbit) cache and problem didn't
occurs there. I also tried place my harddrive into Pentium M (2MB cache) and it
was working fine too.

Problem is probably in buffering!!
Comment 2 Andrew Morton 2006-01-16 16:47:53 UTC
It really helps if you can identify the "Most recent kernel where this bug did
not occur:".  Have you never had this card working on any earlier 2.6 kernel??
Comment 3 Cijoml Cijomlovic Cijomlov 2006-01-17 08:01:48 UTC
Hi,

It never worked with this processor (P4 Celeron) - every kernel has this problem.
Comment 4 Cijoml Cijomlovic Cijomlov 2006-01-25 07:39:35 UTC
Today I have bought FREECOM DVB-T USB STICK and I see the same problem, but no
messages goes to dmesg. This has to be a general problem probably in dvb-core
with buffering!
Comment 5 Cijoml Cijomlovic Cijomlov 2006-01-25 07:42:14 UTC
processor       : 0
vendor_id       : GenuineIntel
cpu family      : 15
model           : 2
model name      : Intel(R) Celeron(R) CPU 2.40GHz
stepping        : 9
cpu MHz         : 2399.029
cache size      : 128 KB
fdiv_bug        : no
hlt_bug         : no
f00f_bug        : no
coma_bug        : no
fpu             : yes
fpu_exception   : yes
cpuid level     : 2
wp              : yes
flags           : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov
pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe cid xtpr
bogomips        : 4798.83
Comment 6 Mauro Carvalho Chehab 2006-02-05 03:19:24 UTC
It seems to be related to bad performance at this processor or at the
motherboard. We've seem several problems with video devices related to bad PCI
implementations at motherboard chipset. What chipset is used at this notebook?
The tests you did with other processors were at the same machine?
Comment 7 Cijoml Cijomlovic Cijomlov 2006-02-05 03:24:42 UTC
0000:00:00.0 Host bridge: Intel Corporation 82852/82855 GM/GME/PM/GMV Processor
to I/O Controller (rev 02)
0000:00:00.1 System peripheral: Intel Corporation 82852/82855 GM/GME/PM/GMV
Processor to I/O Controller (rev 02)
0000:00:00.3 System peripheral: Intel Corporation 82852/82855 GM/GME/PM/GMV
Processor to I/O Controller (rev 02)
0000:00:02.0 VGA compatible controller: Intel Corporation 82852/855GM Integrated
Graphics Device (rev 02)
0000:00:02.1 Display controller: Intel Corporation 82852/855GM Integrated
Graphics Device (rev 02)
0000:00:1d.0 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #1 (rev 03)
0000:00:1d.1 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #2 (rev 03)
0000:00:1d.2 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #3 (rev 03)
0000:00:1d.7 USB Controller: Intel Corporation 82801DB/DBM (ICH4/ICH4-M) USB2
EHCI Controller (rev 03)
0000:00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev 83)
0000:00:1f.0 ISA bridge: Intel Corporation 82801DBM (ICH4-M) LPC Interface
Bridge (rev 03)
0000:00:1f.1 IDE interface: Intel Corporation 82801DBM (ICH4-M) IDE Controller
(rev 03)
0000:00:1f.3 SMBus: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) SMBus
Controller (rev 03)
0000:00:1f.5 Multimedia audio controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) AC'97 Audio Controller (rev 03)
0000:00:1f.6 Modem: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) AC'97
Modem Controller (rev 03)
0000:02:04.0 CardBus bridge: Texas Instruments PCI1520 PC card Cardbus
Controller (rev 01)
0000:02:04.1 CardBus bridge: Texas Instruments PCI1520 PC card Cardbus
Controller (rev 01)
0000:02:0a.0 Ethernet controller: Realtek Semiconductor Co., Ltd.
RTL-8139/8139C/8139C+ (rev 10)


Tests with Pentium 4 Mobile were done on the same hardware, pentium M uses
different chipset, so I have tested on other machine - same harddrive, only
kernel compiled for pentium M.
Comment 8 Cijoml Cijomlovic Cijomlov 2006-04-17 15:07:53 UTC
I finally find reason for it!!!!! GREAT!!!

Picture always get broken when I do:

notas:/home/cijoml# cat /proc/acpi/battery/BAT0/info
present:                 yes
design capacity:         4000 mAh
last full capacity:      4000 mAh
battery technology:      rechargeable
design voltage:          14800 mV
design capacity warning: 130 mAh
design capacity low:     120 mAh
capacity granularity 1:  10 mAh
capacity granularity 2:  3870 mAh
model number:            Bat 8Cell
serial number:           236
battery type:            Lion
OEM info:                Acer
notas:/home/cijoml# cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3960 mAh
present voltage:         14800 mV

So periodical broken picture was caused by klaptopdaemon checking battery
charge! When I killed this daemon, everythink is now great!
Comment 9 Cijoml Cijomlovic Cijomlov 2006-04-17 15:09:05 UTC
Changing category to ACPI to fix such behavior when reading from battery
Comment 10 Vladimir Lebedev 2006-05-19 07:19:09 UTC
Please try the latest kernel version. 2.6.16.16 is availabble now (for 
example).

There are some principal changes in the latest version.
Comment 11 Vladimir Lebedev 2006-05-19 07:19:43 UTC
Please try the latest kernel version. 2.6.16.16 is availabble now (for 
example).

There are some principal changes in the latest version.
Comment 12 Cijoml Cijomlovic Cijomlov 2006-05-19 07:50:23 UTC
Hi Vladimir,

just tested this with 2.6.16.16, but no change - picture went broken calling 

cat /proc/acpi/battery/BAT0/info
or
cat /proc/acpi/battery/BAT0/state

only change is, that information is displayed much faster.
Card tested are pluto2 (pcmcia) and WideView WT-220U PenType Receiver (USB2.0)

Comment 13 Vladimir Lebedev 2006-05-19 10:00:23 UTC

Hi,

Please, try the patch from the bug #5764 - here the simple mutex is used.

Vladimir,


Comment 14 Cijoml Cijomlovic Cijomlov 2006-05-19 23:45:10 UTC
Hi Vladimir,

same issue :(

I think root cause is, that this should be readed by background independent
thread and filled in some kind of buffer - than cat /proc/acpi/battery/... info
should be  readed from this buffer, not directly when user request it.

Michal
Comment 15 Vladimir Lebedev 2006-05-20 01:22:53 UTC

> When playing on P4 Celeron, picture often got bad and in dmesg I see...

What does it mean "When playing ...."? Is it game or movies or something else?
Can you clarify it?

I want to try to reproduce it.
Comment 16 Vladimir Lebedev 2006-05-20 01:33:04 UTC
What will be if you 'cat' other files from /proc/acpi directory instead of 
battery files?
Try thermal, fan files for example.
Comment 17 Cijoml Cijomlovic Cijomlov 2006-05-20 11:58:42 UTC
Hi Vladimir,

I am playing DVB-T stream - these devices are DVB-T receivers.

My laptop supports from other possibilities only 

cat /proc/acpi/thermal_zone/THRC/temperature
cat /proc/acpi/thermal_zone/THRS/temperature

And calling this is OK, video is without errors

throttling, fan etc are not supported.
Comment 18 Cijoml Cijomlovic Cijomlov 2006-05-20 12:07:04 UTC
Changed topic to better fit situation
Comment 19 Vladimir Lebedev 2006-05-20 13:48:55 UTC
> throttling, fan etc are not supported.

You can try to 'cat' all /proc/acpi files excluding batteries files only, 
or 'cat' other /proc files.
I am not sure that the 'cat' of batteries files is the main reason.
Please attach acpidump and full boot.msg and dmesg output before and after 
failures. Please attach /proc/interrupts file before and after failures too.

Vladimir.

Comment 20 Cijoml Cijomlovic Cijomlov 2006-05-20 13:58:31 UTC
Created attachment 8156 [details]
acpidump
Comment 21 Cijoml Cijomlovic Cijomlov 2006-05-20 14:00:21 UTC
Hi,

there is nothing written into dmesg after failure - for me it look like cat
batteries causes wait for something and in this case mpeg stream is not
delivered on time which causes stream errors.

notas:/home/cijoml# cat /proc/interrupts
           CPU0
  0:   39273751          XT-PIC  timer
  1:      14593          XT-PIC  i8042
  2:          0          XT-PIC  cascade
  3:         32          XT-PIC  pcmcia1.0
  5:          3          XT-PIC  parport0
  8:          4          XT-PIC  rtc
  9:         39          XT-PIC  acpi
 10:    9522830          XT-PIC  yenta, yenta, uhci_hcd:usb3, Intel
82801DB-ICH4, Intel 82801DB-ICH4 Modem
 11:  132911343          XT-PIC  uhci_hcd:usb1, uhci_hcd:usb2, ehci_hcd:usb4,
i915@pci:0000:00:02.0
 12:    1571730          XT-PIC  i8042
 14:      42692          XT-PIC  ide0
 15:         24          XT-PIC  ide1
NMI:          0
LOC:   39278332
ERR:          0
MIS:          0

Linux version 2.6.16.16 (root@notas) (gcc version 4.0.3 (Debian 4.0.3-1)) #2
PREEMPT Sat May 20 08:29:16 CEST 2006
BIOS-provided physical RAM map:
 BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
 BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
 BIOS-e820: 00000000000ce000 - 00000000000d0000 (reserved)
 BIOS-e820: 00000000000d8000 - 0000000000100000 (reserved)
 BIOS-e820: 0000000000100000 - 000000003eee0000 (usable)
 BIOS-e820: 000000003eee0000 - 000000003eeeb000 (ACPI data)
 BIOS-e820: 000000003eeeb000 - 000000003ef00000 (ACPI NVS)
 BIOS-e820: 000000003ef00000 - 0000000040000000 (reserved)
 BIOS-e820: 00000000fec10000 - 00000000fec20000 (reserved)
 BIOS-e820: 00000000ff800000 - 00000000ffc00000 (reserved)
 BIOS-e820: 00000000fffffc00 - 0000000100000000 (reserved)
110MB HIGHMEM available.
896MB LOWMEM available.
found SMP MP-table at 000f63f0
On node 0 totalpages: 257760
  DMA zone: 4096 pages, LIFO batch:0
  DMA32 zone: 0 pages, LIFO batch:0
  Normal zone: 225280 pages, LIFO batch:31
  HighMem zone: 28384 pages, LIFO batch:7
DMI present.
ACPI: RSDP (v000 PTLTD                                 ) @ 0x000f6420
ACPI: RSDT (v001 PTLTD  Montara  0x06040000  LTP 0x00000000) @ 0x3eee5e05
ACPI: FADT (v001 Acer   Yuhina   0x06040000 PTL  0x00000001) @ 0x3eeeaed2
ACPI: BOOT (v001 PTLTD  $SBFTBL$ 0x06040000  LTP 0x00000001) @ 0x3eeeafd8
ACPI: DSDT (v001   ANNI   Yuhina 0x06040000 MSFT 0x0100000e) @ 0x00000000
ACPI: PM-Timer IO Port: 0x1008
Intel MultiProcessor Specification v1.4
    Virtual Wire compatibility mode.
SMP mptable: bad signature [0x0]!
BIOS bug, MP table errors detected!...
... disabling SMP support. (tell your hw vendor)
Allocating PCI resources starting at 50000000 (gap: 40000000:bec10000)
Built 1 zonelists
Kernel command line: BOOT_IMAGE=Linux ro root=302 lapic
Local APIC disabled by BIOS -- reenabling.
Found and enabled local APIC!
mapped APIC to ffffd000 (fee00000)
Enabling fast FPU save and restore... done.
Enabling unmasked SIMD FPU exception support... done.
Initializing CPU#0
PID hash table entries: 4096 (order: 12, 65536 bytes)
Detected 2398.383 MHz processor.
Using pmtmr for high-res timesource
Console: colour dummy device 80x25
Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Memory: 1018552k/1031040k available (1782k kernel code, 11888k reserved, 678k
data, 160k init, 113536k highmem)
Checking if this processor honours the WP bit even in supervisor mode... Ok.
Calibrating delay using timer specific routine.. 4801.48 BogoMIPS (lpj=2400741)
Security Framework v1.0.0 initialized
SELinux:  Initializing.
SELinux:  Starting in permissive mode
Mount-cache hash table entries: 512
CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 00004400
00000000 00000000
CPU: After vendor identify, caps: bfebfbff 00000000 00000000 00000000 00004400
00000000 00000000
CPU: Trace cache: 12K uops, L1 D cache: 8K
CPU: L2 cache: 128K
CPU: After all inits, caps: bfebfbff 00000000 00000000 00000080 00004400
00000000 00000000
Intel machine check architecture supported.
Intel machine check reporting enabled on CPU#0.
CPU0: Intel P4/Xeon Extended MCE MSRs (12) available
CPU0: Thermal monitoring enabled
CPU: Intel(R) Celeron(R) CPU 2.40GHz stepping 09
Checking 'hlt' instruction... OK.
ACPI: setting ELCR to 0200 (from 0c88)
NET: Registered protocol family 16
ACPI: bus type pci registered
PCI: PCI BIOS revision 2.10 entry at 0xfd6b4, last bus=2
PCI: Using configuration type 1
ACPI: Subsystem revision 20060127
ACPI: Interpreter enabled
ACPI: Using PIC for interrupt routing
ACPI: PCI Root Bridge [PCI0] (0000:00)
PCI: Probing PCI hardware (bus 00)
Boot video device is 0000:00:02.0
PCI quirk: region 1000-107f claimed by ICH4 ACPI/GPIO/TCO
PCI quirk: region 1180-11bf claimed by ICH4 GPIO
PCI: Ignoring BAR0-3 of IDE controller 0000:00:1f.1
PCI: Transparent bridge - 0000:00:1e.0
PCI: Bus #03 (-#06) may be hidden behind transparent bridge #02 (-#02) (try
'pci=assign-busses')
PCI: Bus #07 (-#0a) may be hidden behind transparent bridge #02 (-#02) (try
'pci=assign-busses')
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCIB._PRT]
ACPI: PCI Interrupt Link [LNKA] (IRQs 10 11) *3
ACPI: PCI Interrupt Link [LNKB] (IRQs *10 11)
ACPI: PCI Interrupt Link [LNKC] (IRQs *10 11)
ACPI: PCI Interrupt Link [LNKD] (IRQs *11)
ACPI: PCI Interrupt Link [LNKE] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKF] (IRQs *10 11)
ACPI: PCI Interrupt Link [LNKG] (IRQs *10 11)
ACPI: PCI Interrupt Link [LNKH] (IRQs 10 11) *7
ACPI: Embedded Controller [EC] (gpe 28) interrupt mode.
Linux Plug and Play Support v0.97 (c) Adam Belay
pnp: PnP ACPI init
pnp: PnP ACPI: found 11 devices
PnPBIOS: Disabled by ACPI PNP
usbcore: registered new driver usbfs
usbcore: registered new driver hub
PCI: Using ACPI for IRQ routing
PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
TC classifier action (bugs to netdev@vger.kernel.org cc hadi@cyberus.ca)
pnp: 00:04: ioport range 0x600-0x60f has been reserved
PCI: Ignore bogus resource 6 [0:0] of 0000:00:02.0
PCI: Bus 3, cardbus bridge: 0000:02:04.0
  IO window: 00003400-000034ff
  IO window: 00003800-000038ff
  PREFETCH window: 50000000-51ffffff
  MEM window: 56000000-57ffffff
PCI: Bus 7, cardbus bridge: 0000:02:04.1
  IO window: 00003c00-00003cff
  IO window: 00001400-000014ff
  PREFETCH window: 52000000-53ffffff
  MEM window: 58000000-59ffffff
PCI: Bridge: 0000:00:1e.0
  IO window: 3000-3fff
  MEM window: e0200000-e02fffff
  PREFETCH window: 50000000-54ffffff
PCI: Setting latency timer of device 0000:00:1e.0 to 64
ACPI: PCI Interrupt Link [LNKF] enabled at IRQ 10
PCI: setting IRQ 10 as level-triggered
ACPI: PCI Interrupt 0000:02:04.0[A] -> Link [LNKF] -> GSI 10 (level, low) -> IRQ 10
ACPI: PCI Interrupt Link [LNKG] enabled at IRQ 10
ACPI: PCI Interrupt 0000:02:04.1[B] -> Link [LNKG] -> GSI 10 (level, low) -> IRQ 10
Simple Boot Flag at 0x3c set to 0x1
Machine check exception polling timer started.
IA-32 Microcode Update Driver: v1.14 <tigran@veritas.com>
audit: initializing netlink socket (disabled)
audit(1148119136.320:1): initialized
highmem bounce pool size: 64 pages
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
SELinux:  Registering netfilter hooks
Initializing Cryptographic API
io scheduler noop registered
io scheduler anticipatory registered (default)
io scheduler deadline registered
io scheduler cfq registered
ACPI: AC Adapter [AC] (on-line)
ACPI: Battery Slot [BAT0] (battery present)
ACPI: Power Button (FF) [PWRF]
ACPI: Lid Switch [LID]
ACPI: Sleep Button (CM) [SLP2]
ACPI: Video Device [VGA] (multi-head: yes  rom: no  post: no)
Using specific hotkey driver
ACPI: CPU0 (power states: C1[C1] C2[C2])
ACPI: Thermal Zone [THRS] (31 C)
ACPI: Thermal Zone [THRC] (24 C)
Linux agpgart interface v0.101 (c) Dave Jones
agpgart: Detected an Intel 855 Chipset.
agpgart: Detected 16252K stolen memory.
agpgart: AGP aperture is 128M @ 0xe8000000
[drm] Initialized drm 1.0.1 20051102
ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
PCI: setting IRQ 11 as level-triggered
ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[drm] Initialized i915 1.4.0 20060119 on minor 0
[drm] Initialized i915 1.4.0 20060119 on minor 1
intelfb: Framebuffer driver for Intel(R) 830M/845G/852GM/855GM/865G/915G/915GM
chipsets
intelfb: Version 0.9.2
ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
intelfb: 00:02.0: Intel(R) 852GME, aperture size 128MB, stolen memory 16252kB
intelfb: Non-CRT device is enabled ( LVDS port ).  Disabling mode switching.
intelfb: Initial video mode is 1024x768-32@60.
intelfb: Changing the video mode is not supported.
Console: switching to colour frame buffer device 128x48
PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
i8042.c: Detected active multiplexing controller, rev 1.1.
serio: i8042 AUX0 port at 0x60,0x64 irq 12
serio: i8042 AUX1 port at 0x60,0x64 irq 12
serio: i8042 AUX2 port at 0x60,0x64 irq 12
serio: i8042 AUX3 port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
Floppy drive(s): fd0 is 1.44M
FDC 0 is a National Semiconductor PC87306
RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
ICH4: IDE controller at PCI slot 0000:00:1f.1
PCI: Enabling device 0000:00:1f.1 (0005 -> 0007)
ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link [LNKC] -> GSI 10 (level, low) -> IRQ 10
ICH4: chipset revision 3
ICH4: not 100% native mode: will probe irqs later
    ide0: BM-DMA at 0x1810-0x1817, BIOS settings: hda:DMA, hdb:pio
    ide1: BM-DMA at 0x1818-0x181f, BIOS settings: hdc:DMA, hdd:pio
Probing IDE interface ide0...
hda: HTS726060M9AT00, ATA DISK drive
ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Probing IDE interface ide1...
hdc: SR244W1, ATAPI CD/DVD-ROM drive
ide1 at 0x170-0x177,0x376 on irq 15
hda: max request size: 512KiB
hda: 117210240 sectors (60011 MB) w/7877KiB Cache, CHS=16383/255/63, UDMA(100)
hda: cache flushes supported
 hda: hda1 hda2 hda3
ACPI: PCI Interrupt 0000:02:04.0[A] -> Link [LNKF] -> GSI 10 (level, low) -> IRQ 10
Yenta: CardBus bridge found at 0000:02:04.0 [1025:0039]
Yenta: Using CSCINT to route CSC interrupts to PCI
Yenta: Routing CardBus interrupts to PCI
Yenta TI: socket 0000:02:04.0, mfunc 0x00921b22, devctl 0x64
Yenta: ISA IRQ mask 0x00b8, PCI irq 10
Socket status: 30000006
pcmcia: parent PCI bridge I/O window: 0x3000 - 0x3fff
cs: IO port probe 0x3000-0x3fff: clean.
pcmcia: parent PCI bridge Memory window: 0xe0200000 - 0xe02fffff
pcmcia: parent PCI bridge Memory window: 0x50000000 - 0x54ffffff
ACPI: PCI Interrupt 0000:02:04.1[B] -> Link [LNKG] -> GSI 10 (level, low) -> IRQ 10
Yenta: CardBus bridge found at 0000:02:04.1 [1025:0039]
Yenta: Using CSCINT to route CSC interrupts to PCI
Yenta: Routing CardBus interrupts to PCI
Yenta TI: socket 0000:02:04.1, mfunc 0x00921b22, devctl 0x64
Yenta: ISA IRQ mask 0x00b8, PCI irq 10
Socket status: 30000010
pcmcia: parent PCI bridge I/O window: 0x3000 - 0x3fff
cs: IO port probe 0x3000-0x3fff: clean.
pcmcia: parent PCI bridge Memory window: 0xe0200000 - 0xe02fffff
pcmcia: parent PCI bridge Memory window: 0x50000000 - 0x54ffffff
usbmon: debugfs is not available
mice: PS/2 mouse device common for all mice
GACT probability on
NET: Registered protocol family 2
IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
TCP established hash table entries: 131072 (order: 7, 524288 bytes)
TCP bind hash table entries: 65536 (order: 6, 262144 bytes)
TCP: Hash tables configured (established 131072 bind 65536)
TCP reno registered
TCP bic registered
NET: Registered protocol family 1
NET: Registered protocol family 17
p4-clockmod: P4/Xeon(TM) CPU On-Demand Clock Modulation available
Using IPI Shortcut mode
ACPI wakeup devices:
 LID LANC MODM
ACPI: (supports S0 S3 S4 S5)
BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
EXT3-fs: mounted filesystem with ordered data mode.
VFS: Mounted root (ext3 filesystem) readonly.
kjournald starting.  Commit interval 5 seconds
Freeing unused kernel memory: 160k freed
input: AT Translated Set 2 keyboard as /class/input/input0
pccard: PCMCIA card inserted into slot 1
cs: memory probe 0xe0200000-0xe02fffff: excluding 0xe0200000-0xe020ffff
pcmcia: registering new device pcmcia1.0
Synaptics Touchpad, model: 1, fw: 5.8, id: 0x9248b1, caps: 0x904713/0x4000
input: SynPS/2 Synaptics TouchPad as /class/input/input1
Adding 698816k swap on /dev/hda3.  Priority:-1 extents:1 across:698816k
EXT3 FS on hda2, internal journal
Real Time Clock Driver v1.12ac
Generic RTC Driver v1.07
input: Acer hotkey driver as /class/input/input2
Acer Travelmate hotkey driver v0.5.33
hdc: ATAPI 24X CD-ROM drive, 128kB Cache, UDMA(33)
Uniform CD-ROM driver Revision: 3.20
NTFS driver 2.1.26 [Flags: R/W MODULE].
NTFS volume version 3.0.
NTFS-fs error (device hda1): load_system_files(): Volume is dirty.  Mounting
read-only.  Run chkdsk and mount in Windows.
pcmcia: Detected deprecated PCMCIA ioctl usage.
pcmcia: This interface will soon be removed from the kernel; please expect
breakage unless you upgrade to new tools.
pcmcia: see http://www.kernel.org/pub/linux/utils/kernel/pcmcia/pcmcia.html for
details.
hw_random: RNG not detected
8139too Fast Ethernet driver 0.9.27
ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
ACPI: PCI Interrupt 0000:02:0a.0[A] -> Link [LNKD] -> GSI 11 (level, low) -> IRQ 11
eth0: RealTek RTL8139 at 0xf8922000, 00:0a:e4:44:3c:b7, IRQ 11
eth0:  Identified 8139 chip type 'RTL-8101'
USB Universal Host Controller Interface driver v2.3
ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
PCI: Setting latency timer of device 0000:00:1d.0 to 64
uhci_hcd 0000:00:1d.0: UHCI Host Controller
uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
uhci_hcd 0000:00:1d.0: irq 11, io base 0x00001820
usb usb1: configuration #1 chosen from 1 choice
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link [LNKD] -> GSI 11 (level, low) -> IRQ 11
PCI: Setting latency timer of device 0000:00:1d.1 to 64
uhci_hcd 0000:00:1d.1: UHCI Host Controller
uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2
uhci_hcd 0000:00:1d.1: irq 11, io base 0x00001840
usb usb2: configuration #1 chosen from 1 choice
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 2 ports detected
ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link [LNKC] -> GSI 10 (level, low) -> IRQ 10
PCI: Setting latency timer of device 0000:00:1d.2 to 64
uhci_hcd 0000:00:1d.2: UHCI Host Controller
uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3
uhci_hcd 0000:00:1d.2: irq 10, io base 0x00001860
usb usb3: configuration #1 chosen from 1 choice
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 2 ports detected
usb 3-1: new full speed USB device using uhci_hcd and address 2
usb 3-1: configuration #1 chosen from 1 choice
usbcore: registered new driver hiddev
input: HID 0a12:1000 as /class/input/input3
input: USB HID v1.11 Keyboard [HID 0a12:1000] on usb-0000:00:1d.2-1
ACPI: PCI Interrupt Link [LNKH] enabled at IRQ 11
ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link [LNKH] -> GSI 11 (level, low) -> IRQ 11
PCI: Setting latency timer of device 0000:00:1d.7 to 64
ehci_hcd 0000:00:1d.7: EHCI Host Controller
ehci_hcd 0000:00:1d.7: debug port 1
PCI: cache line size of 128 is not supported by device 0000:00:1d.7
input: HID 0a12:1000 as /class/input/input4
input: USB HID v1.11 Mouse [HID 0a12:1000] on usb-0000:00:1d.2-1
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.6:USB HID core driver
ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 4
ehci_hcd 0000:00:1d.7: irq 11, io mem 0xe0100000
ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
usb usb4: configuration #1 chosen from 1 choice
usb 3-1: USB disconnect, address 2
hub 4-0:1.0: USB hub found
hub 4-0:1.0: 6 ports detected
usb 4-2: new high speed USB device using ehci_hcd and address 2
usb 4-2: configuration #1 chosen from 1 choice
usb 3-1: new full speed USB device using uhci_hcd and address 3
usb 3-1: configuration #1 chosen from 1 choice
input: HID 0a12:1000 as /class/input/input5
input: USB HID v1.11 Keyboard [HID 0a12:1000] on usb-0000:00:1d.2-1
input: HID 0a12:1000 as /class/input/input6
input: USB HID v1.11 Mouse [HID 0a12:1000] on usb-0000:00:1d.2-1
dvb-usb: found a 'WideView WT-220U PenType Receiver (Typhoon/Freecom)' in cold
state, will try to load a firmware
dvb-usb: downloading firmware from file 'dvb-usb-wt220u-02.fw'
usbcore: registered new driver dvb_usb_dtt200u
usb 4-2: USB disconnect, address 2
dvb-usb: generic DVB-USB module successfully deinitialized and disconnected.
hw_random: RNG not detected
usb 4-2: new high speed USB device using ehci_hcd and address 4
usb 4-2: configuration #1 chosen from 1 choice
dvb-usb: found a 'WideView WT-220U PenType Receiver (Typhoon/Freecom)' in warm
state.
dvb-usb: will use the device's hardware PID filter (table count: 15).
DVB: registering new adapter (WideView WT-220U PenType Receiver (Typhoon/Freecom)).
DVB: registering frontend 0 (WideView USB DVB-T)...
input: IR-receiver inside an USB DVB receiver as /class/input/input7
dvb-usb: schedule remote query interval to 300 msecs.
dvb-usb: WideView WT-220U PenType Receiver (Typhoon/Freecom) successfully
initialized and connected.
ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
PCI: Setting latency timer of device 0000:00:1f.5 to 64
intel8x0_measure_ac97_clock: measured 50329 usecs
intel8x0: clocking to 48000
dvb-usb: recv bulk message failed: -110
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
ACPI: PCI Interrupt 0000:00:1f.6[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
ACPI: PCI interrupt for device 0000:00:1f.6 disabled
ACPI: PCI Interrupt 0000:00:1f.6[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
PCI: Setting latency timer of device 0000:00:1f.6 to 64
parport: PnPBIOS parport detected.
parport0: PC-style at 0x378 (0x778), irq 5, dma 3
[PCSPP,TRISTATE,COMPAT,EPP,ECP,DMA]
cs: IO port probe 0x100-0x4ff: excluding 0x3c0-0x3df 0x4d0-0x4d7
cs: IO port probe 0x100-0x4ff: excluding 0x3c0-0x3df 0x4d0-0x4d7
cs: IO port probe 0x800-0x8ff: excluding 0x800-0x80f
cs: IO port probe 0x800-0x8ff: excluding 0x800-0x80f
cs: IO port probe 0xc00-0xcff: clean.
cs: IO port probe 0xc00-0xcff: clean.
cs: IO port probe 0xa00-0xaff: clean.
cs: IO port probe 0xa00-0xaff: clean.
ieee80211_crypt: registered algorithm 'NULL'
hostap_cs: 0.4.4-kernel (Jouni Malinen <jkmaline@cc.hut.fi>)
hostap_cs: setting Vcc=33 (constant)
hostap_cs: setting Vcc=50 (from config)
Checking CFTABLE_ENTRY 0x01 (default 0x01)
IO window settings: cfg->io.nwin=1 dflt.io.nwin=1
io->flags = 0x0046, io.base=0x0000, len=64
hostap_cs: Registered netdevice wifi0
wifi0: Interrupt, but dev not OK
hostap_cs: index 0x01: Vcc 5.0, irq 3, io 0x3100-0x313f
prism2_hw_init: initialized in 194 ms
wifi0: NIC: id=0x800c v1.0.0
wifi0: PRI: id=0x15 v1.1.1
wifi0: STA: id=0x1f v1.8.2
wifi0: registered netdevice wlan0
microcode: CPU0 updated from revision 0x17 to 0x2e, date = 08112004
Bluetooth: Core ver 2.8
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: L2CAP ver 2.8
Bluetooth: L2CAP socket layer initialized
Bluetooth: HIDP (Human Interface Emulation) ver 1.1
usb 3-1: usbfs: USBDEVFS_CONTROL failed cmd hid2hci rqt 64 rq 0 len 0 ret -71
Bluetooth: RFCOMM socket layer initialized
Bluetooth: RFCOMM TTY layer initialized
Bluetooth: RFCOMM ver 1.7
usb 3-1: USB disconnect, address 3
Bluetooth: BNEP (Ethernet Emulation) ver 1.2
Bluetooth: BNEP filters: protocol multicast
usb 3-1: new full speed USB device using uhci_hcd and address 4
usb 3-1: configuration #1 chosen from 1 choice
Bluetooth: HCI USB driver ver 2.9
usbcore: registered new driver hci_usb
input: Bluetooth HID Boot Protocol Device as /class/input/input8
input: Bluetooth HID Boot Protocol Device as /class/input/input9
CSLIP: code copyright 1989 Regents of the University of California
PPP generic driver version 2.4.2
PPP BSD Compression module registered
PPP Deflate Compression module registered
Comment 22 Vladimir Lebedev 2006-05-20 16:00:05 UTC
So, are you sure that failures are occured when you 'cat' batteries files only?

Also dmesg "overflow irq... " msg are present now or not?


Comment 23 Cijoml Cijomlovic Cijomlov 2006-05-21 00:30:41 UTC
yes, only when calling cat batteries

these irq errors are related to the pcmcia driver and now they are fixed - so no
problem now. In USB device, there were never such errors and behaviour is the
same for both devices.
Comment 24 Vladimir Lebedev 2006-05-25 08:49:03 UTC
Please try rc4-mm3, there are a lot of new important changes in ACPI.
Please upload whole dmesg output.

Also: did you do such experiment?

a) kill your klaptopdaemon 
b) run in a loop: sleep sometime; cat batteries files
c) run your program

What are the results in this case?

Vladimir.

Comment 25 Cijoml Cijomlovic Cijomlov 2006-05-26 05:33:48 UTC
Yes, I did that test as you can see above. I will try mm patch later today.
Comment 26 Vladimir Lebedev 2006-06-08 08:36:28 UTC
2.6.17-rc6-mm1 is available now,
Comment 27 Cijoml Cijomlovic Cijomlov 2006-06-09 02:10:06 UTC
notas:/usr/src/linux-2.6.17-rc6-mm1# make oldconfig
  HOSTCC  scripts/basic/fixdep
  HOSTCC  scripts/basic/docproc
make: *** No rule to make target
`/usr/src/linux-2.6.17-rc6-mm1/include/linux/dwarf2.h', needed by
`include/linux/dwarf2-defs.h'.  Stop.


I have compilation problems :(
Comment 28 Vladimir Lebedev 2006-06-09 08:53:16 UTC
Check your kernel source tree.
Check that `/usr/src/linux-2.6.17-rc6-mm1/include/linux/dwarf2.h' exists.
Maybe your linux-2.6.17-rc6-mm1 is corrupted (patching errors ...).
Comment 29 Vladimir Lebedev 2006-07-08 02:40:00 UTC
No response from bug submitter, please reopen if problem persists. 
Comment 30 Cijoml Cijomlovic Cijomlov 2006-07-08 09:05:30 UTC
I just tested 2.6.18-rc1 with same issue, but possitive change - errors are now
shorter, which means that timeout is much shorter.
Comment 31 Vladimir Lebedev 2006-07-09 01:20:26 UTC
Is it OK, or the problem still exists? If yes, please attach the latest log 
files.
Comment 32 Cijoml Cijomlovic Cijomlov 2006-07-09 12:27:48 UTC
Created attachment 8512 [details]
dmesg-2.6.18-rc1

dmesg-2.6.18-rc1
Comment 33 Vladimir Lebedev 2006-07-11 19:49:44 UTC
I guess there are conflicts/delays ... between the devices.
Please attach the output of "time cat /proc/acpi/battery/BAT0/*" command on 
latest version and on version (if it is possible) when the results is so bad.
Please do it with and without DVB playing.
We need to compare the results.
Also, do you have any problems without playing DVB?

Thanks
Vladimir .
Comment 34 Cijoml Cijomlovic Cijomlov 2006-07-11 23:44:52 UTC
2.6.18-rc1:

Without DVB decode, AC cable plugged:
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/*
alarm:                   unsupported
present:                 yes
design capacity:         4000 mAh
last full capacity:      4000 mAh
battery technology:      rechargeable
design voltage:          14800 mV
design capacity warning: 130 mAh
design capacity low:     120 mAh
capacity granularity 1:  10 mAh
capacity granularity 2:  3870 mAh
model number:            Bat 8Cell
serial number:           236
battery type:            Lion
OEM info:                Acer
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m1.443s
user    0m0.001s
sys     0m0.005s

Without DVB decode, AC cable unplugged:
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/*
alarm:                   unsupported
present:                 yes
design capacity:         4000 mAh
last full capacity:      4000 mAh
battery technology:      rechargeable
design voltage:          14800 mV
design capacity warning: 130 mAh
design capacity low:     120 mAh
capacity granularity 1:  10 mAh
capacity granularity 2:  3870 mAh
model number:            Bat 8Cell
serial number:           236
battery type:            Lion
OEM info:                Acer
present:                 yes
capacity state:          ok
charging state:          discharging
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m1.329s
user    0m0.001s
sys     0m0.004s

With DVB decode, AC cable plugged:
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/*
alarm:                   unsupported
present:                 yes
design capacity:         4000 mAh
last full capacity:      4000 mAh
battery technology:      rechargeable
design voltage:          14800 mV
design capacity warning: 130 mAh
design capacity low:     120 mAh
capacity granularity 1:  10 mAh
capacity granularity 2:  3870 mAh
model number:            Bat 8Cell
serial number:           236
battery type:            Lion
OEM info:                Acer
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m1.454s
user    0m0.000s
sys     0m0.011s

With DVB decode, AC cable unplugged:
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/*
alarm:                   unsupported
present:                 yes
design capacity:         4000 mAh
last full capacity:      4000 mAh
battery technology:      rechargeable
design voltage:          14800 mV
design capacity warning: 130 mAh
design capacity low:     120 mAh
capacity granularity 1:  10 mAh
capacity granularity 2:  3870 mAh
model number:            Bat 8Cell
serial number:           236
battery type:            Lion
OEM info:                Acer
present:                 yes
capacity state:          ok
charging state:          discharging
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m1.344s
user    0m0.001s
sys     0m0.006s

well there are other processes running - will attach pstree:
Comment 35 Cijoml Cijomlovic Cijomlov 2006-07-11 23:49:55 UTC
Created attachment 8537 [details]
/tmp/pstree -a

/tmp/pstree_-a
Comment 36 Vladimir Lebedev 2006-07-18 11:21:35 UTC
Created attachment 8580 [details]
C prog
Comment 37 Vladimir Lebedev 2006-07-18 11:23:05 UTC
Created attachment 8581 [details]
Makefile
Comment 38 Vladimir Lebedev 2006-07-18 11:29:27 UTC
Created attachment 8582 [details]
bat_time.c
Comment 39 Vladimir Lebedev 2006-07-18 11:46:01 UTC
I see the very strange times in previous attachments.
I attached Makefile and simple program bat_time.c
Please execute few times 'time bat_time BAT0'' w and w/o DVB and attach the 
output.
Thanks.
Comment 40 Cijoml Cijomlovic Cijomlov 2006-07-18 12:43:56 UTC
WITHOUT DVB DECODE

notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 22
alarm: read() passed, time = 17, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 702
info: open() passed, time = 36
info: read() passed, time = 213640, rr = 425
info: close() passed, time = 19
info: passed, time = 214016
state: open() passed, time = 27
state: read() passed, time = 1226070, rr = 191
state: close() passed, time = 21
state: passed, time = 1226164
test passed, time = 1441534

real    0m1.445s
user    0m0.001s
sys     0m0.007s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 26
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 854
info: open() passed, time = 38
info: read() passed, time = 212282, rr = 425
info: close() passed, time = 19
info: passed, time = 212787
state: open() passed, time = 28
state: read() passed, time = 1224843, rr = 191
state: close() passed, time = 19
state: passed, time = 1224938
test passed, time = 1439686

real    0m1.443s
user    0m0.000s
sys     0m0.007s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 18
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 31
alarm: passed, time = 867
info: open() passed, time = 59
info: read() passed, time = 212894, rr = 425
info: close() passed, time = 20
info: passed, time = 213345
state: open() passed, time = 28
state: read() passed, time = 1225021, rr = 191
state: close() passed, time = 22
state: passed, time = 1225119
test passed, time = 1440051

real    0m1.443s
user    0m0.000s
sys     0m0.007s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 17
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 809
info: open() passed, time = 27
info: read() passed, time = 213783, rr = 425
info: close() passed, time = 21
info: passed, time = 213878
state: open() passed, time = 30
state: read() passed, time = 1225359, rr = 191
state: close() passed, time = 20
state: passed, time = 1225458
test passed, time = 1440238

real    0m1.443s
user    0m0.000s
sys     0m0.006s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 19
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 919
info: open() passed, time = 36
info: read() passed, time = 212915, rr = 425
info: close() passed, time = 20
info: passed, time = 213488
state: open() passed, time = 28
state: read() passed, time = 1225077, rr = 191
state: close() passed, time = 20
state: passed, time = 1225173
test passed, time = 1440315

real    0m1.443s
user    0m0.001s
sys     0m0.005s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 22
alarm: read() passed, time = 17, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 824
info: open() passed, time = 34
info: read() passed, time = 212700, rr = 425
info: close() passed, time = 20
info: passed, time = 213134
state: open() passed, time = 28
state: read() passed, time = 1223019, rr = 191
state: close() passed, time = 19
state: passed, time = 1223110
test passed, time = 1437878

real    0m1.440s
user    0m0.000s
sys     0m0.006s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 16
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 22
alarm: passed, time = 551
info: open() passed, time = 25
info: read() passed, time = 213985, rr = 425
info: close() passed, time = 20
info: passed, time = 214078
state: open() passed, time = 29
state: read() passed, time = 1225095, rr = 191
state: close() passed, time = 21
state: passed, time = 1225189
test passed, time = 1439900

real    0m1.442s
user    0m0.000s
sys     0m0.005s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 19
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 30
alarm: passed, time = 845
info: open() passed, time = 32
info: read() passed, time = 213136, rr = 425
info: close() passed, time = 20
info: passed, time = 213590
state: open() passed, time = 28
state: read() passed, time = 1225242, rr = 191
state: close() passed, time = 21
state: passed, time = 1225335
test passed, time = 1440532

real    0m1.443s
user    0m0.000s
sys     0m0.009s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 17
alarm: read() passed, time = 17, rr = 37
alarm: close() passed, time = 29
alarm: passed, time = 843
info: open() passed, time = 38
info: read() passed, time = 212960, rr = 425
info: close() passed, time = 66
info: passed, time = 213827
state: open() passed, time = 37
state: read() passed, time = 1223931, rr = 191
state: close() passed, time = 21
state: passed, time = 1224362
test passed, time = 1440439

real    0m1.443s
user    0m0.001s
sys     0m0.005s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 20
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 30
alarm: passed, time = 840
info: open() passed, time = 34
info: read() passed, time = 213504, rr = 425
info: close() passed, time = 19
info: passed, time = 213604
state: open() passed, time = 26
state: read() passed, time = 1223685, rr = 191
state: close() passed, time = 22
state: passed, time = 1223780
test passed, time = 1438692

real    0m1.442s
user    0m0.001s
sys     0m0.005s
Comment 41 Cijoml Cijomlovic Cijomlov 2006-07-18 12:49:11 UTC
WITH DVB DECODE:

notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 24
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 29
alarm: passed, time = 1598
info: open() passed, time = 36
info: read() passed, time = 213630, rr = 425
info: close() passed, time = 23
info: passed, time = 214049
state: open() passed, time = 67
state: read() passed, time = 1239908, rr = 191
state: close() passed, time = 20
state: passed, time = 1240048
test passed, time = 1456753

real    0m1.460s
user    0m0.000s
sys     0m0.010s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 89
alarm: read() passed, time = 85, rr = 37
alarm: close() passed, time = 29
alarm: passed, time = 1223
info: open() passed, time = 91
info: read() passed, time = 212913, rr = 425
info: close() passed, time = 21
info: passed, time = 213499
state: open() passed, time = 50
state: read() passed, time = 1237574, rr = 191
state: close() passed, time = 21
state: passed, time = 1237699
test passed, time = 1453392

real    0m1.458s
user    0m0.002s
sys     0m0.009s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 94
alarm: read() passed, time = 17, rr = 37
alarm: close() passed, time = 22
alarm: passed, time = 465
info: open() passed, time = 24
info: read() passed, time = 215140, rr = 425
info: close() passed, time = 23
info: passed, time = 215237
state: open() passed, time = 30
state: read() passed, time = 1233067, rr = 191
state: close() passed, time = 21
state: passed, time = 1233206
test passed, time = 1449002

real    0m1.453s
user    0m0.000s
sys     0m0.007s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 23
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 31
alarm: passed, time = 1936
info: open() passed, time = 35
info: read() passed, time = 214050, rr = 425
info: close() passed, time = 22
info: passed, time = 214158
state: open() passed, time = 29
state: read() passed, time = 1236152, rr = 191
state: close() passed, time = 23
state: passed, time = 1236291
test passed, time = 1453036

real    0m1.457s
user    0m0.000s
sys     0m0.008s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 21
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 22
alarm: passed, time = 634
info: open() passed, time = 29
info: read() passed, time = 212904, rr = 425
info: close() passed, time = 21
info: passed, time = 213006
state: open() passed, time = 32
state: read() passed, time = 1237153, rr = 191
state: close() passed, time = 77
state: passed, time = 1237312
test passed, time = 1451087

real    0m1.455s
user    0m0.000s
sys     0m0.006s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 20
alarm: read() passed, time = 16, rr = 37
alarm: close() passed, time = 23
alarm: passed, time = 726
info: open() passed, time = 26
info: read() passed, time = 213515, rr = 425
info: close() passed, time = 22
info: passed, time = 213633
state: open() passed, time = 32
state: read() passed, time = 1234555, rr = 191
state: close() passed, time = 21
state: passed, time = 1234661
test passed, time = 1449141

real    0m1.453s
user    0m0.002s
sys     0m0.005s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 26
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 31
alarm: passed, time = 1183
info: open() passed, time = 39
info: read() passed, time = 214253, rr = 425
info: close() passed, time = 23
info: passed, time = 214792
state: open() passed, time = 32
state: read() passed, time = 1225399, rr = 191
state: close() passed, time = 38
state: passed, time = 1225521
test passed, time = 1442503

real    0m1.449s
user    0m0.000s
sys     0m0.009s
Comment 42 Vladimir Lebedev 2006-07-18 15:38:39 UTC
>real    0m1.445s
>user    0m0.001s
>sys     0m0.007s

Do you use the latest kernels, I mean 2.6.18-rc* ?

The computer is not loaded, but we lost about 1.4 seconds.
On other computers I see that  'real ~= user + sys'.
Use the 'top' command, maybe you will  find other computer loadings.
I am going  to prepare the kernel's patch to get more information.
But, first of all I am waiting the info about the results of 'top' command. ...
(who eats the time !!!)
Also, please try to work with kernel boot option 'ec_intr=0' on 2.6.18-rc*.
please execute 'bat_times' prog and so on.

Thanks.


Comment 43 Cijoml Cijomlovic Cijomlov 2006-07-18 22:50:50 UTC
Hi Vladimir,

yes, I still use 2.6.18-rc1 kernel. Computer is not loaded at all. It still has 
low load:

cijoml@notas:~$ w
 07:53:40 up 6 min,  1 user,  load average: 0,19, 0,50, 0,29
USER     TTY      FROM              LOGIN@   IDLE   JCPU   PCPU WHAT
cijoml   :0       -                07:50   ?xdm?  26.70s  0.05s /bin/sh
/usr/bin/x-session-manager
Comment 44 Cijoml Cijomlovic Cijomlov 2006-07-18 22:58:23 UTC
Hi Vladimir,

with tuning option ec_intr=0 I getts:

notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 19
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 32
alarm: passed, time = 895
info: open() passed, time = 40
info: read() passed, time = 215041, rr = 425
info: close() passed, time = 20
info: passed, time = 215511
state: open() passed, time = 30
state: read() passed, time = 1225250, rr = 191
state: close() passed, time = 22
state: passed, time = 1225354
test passed, time = 1442562

real    0m1.474s
user    0m0.001s
sys     0m0.011s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 26
alarm: read() passed, time = 20, rr = 37
alarm: close() passed, time = 31
alarm: passed, time = 927
info: open() passed, time = 36
info: read() passed, time = 214185, rr = 425
info: close() passed, time = 20
info: passed, time = 214304
state: open() passed, time = 33
state: read() passed, time = 1226051, rr = 191
state: close() passed, time = 21
state: passed, time = 1226157
test passed, time = 1442177

real    0m1.445s
user    0m0.001s
sys     0m0.011s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 22
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 28
alarm: passed, time = 832
info: open() passed, time = 40
info: read() passed, time = 214132, rr = 425
info: close() passed, time = 20
info: passed, time = 214609
state: open() passed, time = 32
state: read() passed, time = 1225138, rr = 191
state: close() passed, time = 22
state: passed, time = 1225244
test passed, time = 1441505

real    0m1.444s
user    0m0.001s
sys     0m0.011s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 20
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 31
alarm: passed, time = 968
info: open() passed, time = 37
info: read() passed, time = 214061, rr = 425
info: close() passed, time = 21
info: passed, time = 214509
state: open() passed, time = 31
state: read() passed, time = 1227634, rr = 191
state: close() passed, time = 20
state: passed, time = 1227736
test passed, time = 1444017

real    0m1.446s
user    0m0.001s
sys     0m0.010s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 20
alarm: read() passed, time = 18, rr = 37
alarm: close() passed, time = 33
alarm: passed, time = 978
info: open() passed, time = 28
info: read() passed, time = 213052, rr = 425
info: close() passed, time = 19
info: passed, time = 213149
state: open() passed, time = 30
state: read() passed, time = 1225011, rr = 191
state: close() passed, time = 20
state: passed, time = 1225111
test passed, time = 1439345

real    0m1.442s
user    0m0.001s
sys     0m0.009s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 20
alarm: read() passed, time = 19, rr = 37
alarm: close() passed, time = 29
alarm: passed, time = 910
info: open() passed, time = 38
info: read() passed, time = 214110, rr = 425
info: close() passed, time = 20
info: passed, time = 214582
state: open() passed, time = 30
state: read() passed, time = 1226111, rr = 191
state: close() passed, time = 21
state: passed, time = 1226210
test passed, time = 1442486

real    0m1.445s
user    0m0.002s
sys     0m0.010s
Comment 45 Vladimir Lebedev 2006-07-19 02:08:56 UTC
Created attachment 8584 [details]
debug patch

Hi,

First question: Do you use the vendor's DSDT or your DSDT is patched?

Second: please apply the bat_spy patch, perform few times 'cat
/proc/acpi/battery/BAT0/*'  and attach the dmesg output.
We must find where we lose time.

Thanks.
Vladimir.
Comment 46 Cijoml Cijomlovic Cijomlov 2006-07-19 23:04:08 UTC
command:
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 19
alarm: read() passed, time = 17, rr = 37
alarm: close() passed, time = 27
alarm: passed, time = 767
info: open() passed, time = 26
info: read() passed, time = 213081, rr = 425
info: close() passed, time = 20
info: passed, time = 213176
state: open() passed, time = 30
state: read() passed, time = 1226941, rr = 191
state: close() passed, time = 20
state: passed, time = 1227038
test passed, time = 1441082

real    0m1.457s
user    0m0.000s
sys     0m0.010s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 22
alarm: read() passed, time = 16, rr = 37
alarm: close() passed, time = 22
alarm: passed, time = 317
info: open() passed, time = 23
info: read() passed, time = 214005, rr = 425
info: close() passed, time = 25
info: passed, time = 214405
state: open() passed, time = 35
state: read() passed, time = 1224488, rr = 191
state: close() passed, time = 19
state: passed, time = 1224595
test passed, time = 1439784

real    0m1.442s
user    0m0.000s
sys     0m0.009s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 18
alarm: read() passed, time = 22, rr = 37
alarm: close() passed, time = 33
alarm: passed, time = 888
info: open() passed, time = 32
info: read() passed, time = 214086, rr = 425
info: close() passed, time = 22
info: passed, time = 214530
state: open() passed, time = 28
state: read() passed, time = 1226066, rr = 191
state: close() passed, time = 21
state: passed, time = 1226162
test passed, time = 1442304

real    0m1.445s
user    0m0.001s
sys     0m0.010s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 17
alarm: read() passed, time = 21, rr = 37
alarm: close() passed, time = 30
alarm: passed, time = 871
info: open() passed, time = 28
info: read() passed, time = 214947, rr = 425
info: close() passed, time = 19
info: passed, time = 215039
state: open() passed, time = 28
state: read() passed, time = 1225139, rr = 191
state: close() passed, time = 19
state: passed, time = 1225232
test passed, time = 1441242

real    0m1.444s
user    0m0.000s
sys     0m0.011s
notas:/home/cijoml/filmy/Bloodrayne# time ./bat_time BAT0
alarm: open() passed, time = 19
alarm: read() passed, time = 16, rr = 37
alarm: close() passed, time = 20
alarm: passed, time = 308
info: open() passed, time = 22
info: read() passed, time = 213109, rr = 425
info: close() passed, time = 19
info: passed, time = 213197
state: open() passed, time = 26
state: read() passed, time = 1225124, rr = 191
state: close() passed, time = 18
state: passed, time = 1225217
test passed, time = 1438812

real    0m1.441s
user    0m0.000s
sys     0m0.009s



dmesg output:
ACPI BAT: _BIF extract 2: 213042
ACPI BAT: _BIF extract 4: 2
ACPI BAT: _BIF extract 6: 2
ACPI BAT: _BST extract 2: 1226907
ACPI BAT: _BST extract 4: 1
ACPI BAT: _BST extract 6: 1
ACPI BAT: _BIF extract 2: 213293
ACPI BAT: _BIF extract 4: 3
ACPI BAT: _BIF extract 6: 2
ACPI BAT: _BST extract 2: 1224452
ACPI BAT: _BST extract 4: 2
ACPI BAT: _BST extract 6: 2
ACPI BAT: _BIF extract 2: 214043
ACPI BAT: _BIF extract 4: 2
ACPI BAT: _BIF extract 6: 2
ACPI BAT: _BST extract 2: 1226031
ACPI BAT: _BST extract 4: 2
ACPI BAT: _BST extract 6: 1
ACPI BAT: _BIF extract 2: 214907
ACPI BAT: _BIF extract 4: 2
ACPI BAT: _BIF extract 6: 2
ACPI BAT: _BST extract 2: 1225103
ACPI BAT: _BST extract 4: 2
ACPI BAT: _BST extract 6: 1
ACPI BAT: _BIF extract 2: 213071
ACPI BAT: _BIF extract 4: 2
ACPI BAT: _BIF extract 6: 2
ACPI BAT: _BST extract 2: 1225090
ACPI BAT: _BST extract 4: 2
ACPI BAT: _BST extract 6: 2
Comment 47 Cijoml Cijomlovic Cijomlov 2006-07-19 23:04:59 UTC
I am using vendor's DSDT - latest available BIOS
Comment 48 Vladimir Lebedev 2006-07-24 09:00:04 UTC
Created attachment 8607 [details]
catacpi

Hi
Please execute the 'catacpi' script and attach the output.
Vladimir.
Comment 49 Cijoml Cijomlovic Cijomlov 2006-07-24 09:51:01 UTC
notas:/home/cijoml# ./catacpi
/proc/acpi/wakeup =====================
Device  Sleep state     Status
 LID       3            * enabled
LANC       5            disabled
MODM       4            disabled

real    0m0.003s
user    0m0.001s
sys     0m0.002s
/proc/acpi/alarm =====================
2006-07-00 20:25:36

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRC/polling_frequency =====================
<polling disabled>

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRC/cooling_mode =====================
cooling mode:   active

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRC/trip_points =====================
critical (S5):           80 C

real    0m0.002s
user    0m0.001s
sys     0m0.001s
/proc/acpi/thermal_zone/THRC/temperature =====================
temperature:             54 C

real    0m0.020s
user    0m0.000s
sys     0m0.003s
/proc/acpi/thermal_zone/THRC/state =====================
state:                   ok

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRS/polling_frequency =====================
<polling disabled>

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRS/cooling_mode =====================
cooling mode:   active

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/thermal_zone/THRS/trip_points =====================
critical (S5):           85 C

real    0m0.002s
user    0m0.001s
sys     0m0.001s
/proc/acpi/thermal_zone/THRS/temperature =====================
temperature:             54 C

real    0m0.022s
user    0m0.001s
sys     0m0.003s
/proc/acpi/thermal_zone/THRS/state =====================
state:                   ok

real    0m0.002s
user    0m0.000s
sys     0m0.003s
/proc/acpi/processor/CPU0/power =====================
active state:            C2
max_cstate:              C8
bus master activity:     00000000
states:
    C1:                  type[C1] promotion[C2] demotion[--] latency[000]
usage[00000010] duration[00000000000000000000]
   *C2:                  type[C2] promotion[--] demotion[C1] latency[001]
usage[08691081] duration[00000000026420256352]

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/processor/CPU0/limit =====================
<not supported>

real    0m0.018s
user    0m0.000s
sys     0m0.001s
/proc/acpi/processor/CPU0/throttling =====================
<not supported>

real    0m0.002s
user    0m0.000s
sys     0m0.002s
/proc/acpi/processor/CPU0/info =====================
processor id:            0
acpi id:                 0
bus mastering control:   yes
power management:        yes
throttling control:      no
limit interface:         no

real    0m0.002s
user    0m0.000s
sys     0m0.001s
/proc/acpi/video/VGA/TVO/EDID =====================
&#729;&#729;&#729;&#729;&#729;&#729;A&#272;&#355;       &#273;
Comment 50 Vladimir Lebedev 2006-07-25 09:53:35 UTC
Created attachment 8611 [details]
bat_spy.diff

Please remove the previous patch and apply the new bat_spy.diff patch.
Perform the 'time cat /proc/acpi/batteru/BAT0/state' few times and attach the
output and dmesg. This patch is a debug patch.

Thanks.
Comment 51 Cijoml Cijomlovic Cijomlov 2006-07-25 11:51:47 UTC
notas:/home/cijoml# time cat /proc/acpi/batteru/BAT0/state
cat: /proc/acpi/batteru/BAT0/state: nen
Comment 52 Cijoml Cijomlovic Cijomlov 2006-07-25 13:04:36 UTC
Sorry I misstyped previous post and got No such file or directory. Sending fixed
output.

notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      4000 mAh
present voltage:         14800 mV

real    0m1.120s
user    0m0.001s
sys     0m0.005s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      4000 mAh
present voltage:         14800 mV

real    0m1.118s
user    0m0.000s
sys     0m0.006s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      4000 mAh
present voltage:         14800 mV

real    0m1.121s
user    0m0.001s
sys     0m0.007s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      4000 mAh
present voltage:         14800 mV

real    0m1.119s
user    0m0.001s
sys     0m0.005s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      4000 mAh
present voltage:         14800 mV

real    0m1.119s
user    0m0.002s
sys     0m0.004s


ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 4: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 3: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 3: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1113834: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 6: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 4: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 2: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 3: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1113901: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 68: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 38: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 41: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1115048: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 5: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 4: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 2: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 2: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1115636: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 202: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 3: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 3: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1114284: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 5: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 5: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 2: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 3: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1114792: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 204: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 178: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 175: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1116009: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 5: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 4: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 3: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 2: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1117426: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 231: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 203: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 3: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1115229: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 6: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 4: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 3: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 2: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1116130: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: ACPI_ALLOCATE_ZEROED 1
ACPI SPY NSXFEVAL: 71: ACPI_ALLOCATE_ZEROED 2
ACPI SPY NSXFEVAL: 0: acpi_ns_map_handle_to_node 1
ACPI SPY NSXFEVAL: 38: acpi_ns_map_handle_to_node 2
ACPI SPY NSXFEVAL: 0: external_params && external_params->count 1
ACPI SPY NSXFEVAL: 38: external_params && external_params->count 2
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSXFEVAL: 1115101: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY NSXFEVAL: 0: return_buffer 1
ACPI SPY NSXFEVAL: 6: return_buffer 2
ACPI SPY NSXFEVAL: 0: info->return_object 1
ACPI SPY NSXFEVAL: 5: info->return_object 2
ACPI SPY NSXFEVAL: 0: info->parameters 1
ACPI SPY NSXFEVAL: 2: info->parameters 2
ACPI SPY NSXFEVAL: 0: ACPI_FREE(info) 1
ACPI SPY NSXFEVAL: 2: ACPI_FREE(info) 2
ACPI SPY BATTERY: 1115737: _BST extract 2
Comment 53 Vladimir Lebedev 2006-07-25 13:16:18 UTC
Thanks for the rapid response.
The following patch will be ready tomorrow :-).

Comment 54 Vladimir Lebedev 2006-07-26 14:41:35 UTC
Created attachment 8622 [details]
bat_spy.diff

Hi,
Please remove the previous patch and apply the new bat_spy.diff patch.
Perform the 'time cat /proc/acpi/battery/BAT0/state' twice and attach the
output and dmesg. Step by step we come nearer to a problem. Are you ready to
continue?
Regards.
Comment 55 Cijoml Cijomlovic Cijomlov 2006-07-26 22:53:36 UTC
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3920 mAh
present voltage:         14800 mV

real    0m1.230s
user    0m0.000s
sys     0m0.008s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3920 mAh
present voltage:         14800 mV

real    0m1.230s
user    0m0.000s
sys     0m0.006s


ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSEVAL: 0: acpi_ns_get_node 1
ACPI SPY NSEVAL: 88: acpi_ns_get_node 2
ACPI SPY NSEVAL: 0: if acpi_ns_get_type 1
ACPI SPY NSEVAL: 41: if acpi_ns_get_type 2
ACPI SPY NSEVAL: 0: acpi_ns_get_attached_object 1
ACPI SPY NSEVAL: 41: acpi_ns_get_attached_object 2
ACPI SPY NSEVAL: 0: ACPI_DUMP_PATHNAME 1
ACPI SPY NSEVAL: 39: ACPI_DUMP_PATHNAME 2
ACPI SPY NSEVAL: 0: acpi_ex_enter_interpreter 1
ACPI SPY NSEVAL: 40: acpi_ex_enter_interpreter 2
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ds_begin_method_execution 1
ACPI SPY PSXFACE: 4: acpi_ds_begin_method_execution 2
ACPI SPY PSXFACE: 0: acpi_ps_update_parameter_list 1
ACPI SPY PSXFACE: 3: acpi_ps_update_parameter_list 2
ACPI SPY PSXFACE: 0: acpi_ps_start_trace 1
ACPI SPY PSXFACE: 2: acpi_ps_start_trace 2
ACPI SPY PSXFACE: 0: acpi_ps_execute_pass 1
ACPI SPY2 PSXFACE: 0: acpi_ps_create_scope_op 1
ACPI SPY2 PSXFACE: 8: acpi_ps_create_scope_op 2
ACPI SPY2 PSXFACE: 0: acpi_ds_create_walk_state 1
ACPI SPY2 PSXFACE: 6: acpi_ds_create_walk_state 2
ACPI SPY2 PSXFACE: 0: acpi_ds_init_aml_walk 1
ACPI SPY2 PSXFACE: 4: acpi_ds_init_aml_walk 2
ACPI SPY2 PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY2 PSXFACE: 50: acpi_ps_parse_aml 2
ACPI SPY2 PSXFACE: 0: acpi_ps_delete_parse_tree 1
ACPI SPY2 PSXFACE: 3: acpi_ps_delete_parse_tree 2
ACPI SPY PSXFACE: 86: acpi_ps_execute_pass 2
ACPI SPY PSXFACE: 0: acpi_ps_execute_pass x 1
ACPI SPY2 PSXFACE: 0: acpi_ps_create_scope_op 1
ACPI SPY2 PSXFACE: 3: acpi_ps_create_scope_op 2
ACPI SPY2 PSXFACE: 0: acpi_ds_create_walk_state 1
ACPI SPY2 PSXFACE: 3: acpi_ds_create_walk_state 2
ACPI SPY2 PSXFACE: 0: acpi_ds_init_aml_walk 1
ACPI SPY2 PSXFACE: 3: acpi_ds_init_aml_walk 2
ACPI SPY2 PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY2 PSXFACE: 1225967: acpi_ps_parse_aml 2
ACPI SPY2 PSXFACE: 0: acpi_ps_delete_parse_tree 1
ACPI SPY2 PSXFACE: 4: acpi_ps_delete_parse_tree 2
ACPI SPY PSXFACE: 1226002: acpi_ps_execute_pass x 2
ACPI SPY PSXFACE: 0: acpi_ps_stop_trace 1
ACPI SPY PSXFACE: 2: acpi_ps_stop_trace 2
ACPI SPY PSXFACE: 0: acpi_ps_update_parameter_list x 1
ACPI SPY PSXFACE: 3: acpi_ps_update_parameter_list x 2
ACPI SPY PSXFACE: 0: if (info->return_object) 1
ACPI SPY PSXFACE: 3: if (info->return_object) 2
ACPI SPY NSEVAL: 1226130: acpi_ps_execute_method 2
ACPI SPY NSEVAL: 0: acpi_ex_exit_interpreter 1
ACPI SPY NSEVAL: 0: acpi_ex_exit_interpreter 1
ACPI SPY NSEVAL: 0: status == AE_CTRL_RETURN_VALUE 1
ACPI SPY NSEVAL: 2: status == AE_CTRL_RETURN_VALUE 2
ACPI SPY NSXFEVAL: 1226641: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY BATTERY: 1226743: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: else (pathname) && (acpi_ns_valid_root_prefix(pathname[0])) 1
ACPI SPY NSEVAL: 0: acpi_ns_get_node 1
ACPI SPY NSEVAL: 50: acpi_ns_get_node 2
ACPI SPY NSEVAL: 0: if acpi_ns_get_type 1
ACPI SPY NSEVAL: 63: if acpi_ns_get_type 2
ACPI SPY NSEVAL: 0: acpi_ns_get_attached_object 1
ACPI SPY NSEVAL: 3: acpi_ns_get_attached_object 2
ACPI SPY NSEVAL: 0: ACPI_DUMP_PATHNAME 1
ACPI SPY NSEVAL: 3: ACPI_DUMP_PATHNAME 2
ACPI SPY NSEVAL: 0: acpi_ex_enter_interpreter 1
ACPI SPY NSEVAL: 3: acpi_ex_enter_interpreter 2
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ds_begin_method_execution 1
ACPI SPY PSXFACE: 4: acpi_ds_begin_method_execution 2
ACPI SPY PSXFACE: 0: acpi_ps_update_parameter_list 1
ACPI SPY PSXFACE: 3: acpi_ps_update_parameter_list 2
ACPI SPY PSXFACE: 0: acpi_ps_start_trace 1
ACPI SPY PSXFACE: 2: acpi_ps_start_trace 2
ACPI SPY PSXFACE: 0: acpi_ps_execute_pass 1
ACPI SPY2 PSXFACE: 0: acpi_ps_create_scope_op 1
ACPI SPY2 PSXFACE: 4: acpi_ps_create_scope_op 2
ACPI SPY2 PSXFACE: 0: acpi_ds_create_walk_state 1
ACPI SPY2 PSXFACE: 6: acpi_ds_create_walk_state 2
ACPI SPY2 PSXFACE: 0: acpi_ds_init_aml_walk 1
ACPI SPY2 PSXFACE: 4: acpi_ds_init_aml_walk 2
ACPI SPY2 PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY2 PSXFACE: 49: acpi_ps_parse_aml 2
ACPI SPY2 PSXFACE: 0: acpi_ps_delete_parse_tree 1
ACPI SPY2 PSXFACE: 2: acpi_ps_delete_parse_tree 2
ACPI SPY PSXFACE: 80: acpi_ps_execute_pass 2
ACPI SPY PSXFACE: 0: acpi_ps_execute_pass x 1
ACPI SPY2 PSXFACE: 0: acpi_ps_create_scope_op 1
ACPI SPY2 PSXFACE: 3: acpi_ps_create_scope_op 2
ACPI SPY2 PSXFACE: 0: acpi_ds_create_walk_state 1
ACPI SPY2 PSXFACE: 4: acpi_ds_create_walk_state 2
ACPI SPY2 PSXFACE: 0: acpi_ds_init_aml_walk 1
ACPI SPY2 PSXFACE: 4: acpi_ds_init_aml_walk 2
ACPI SPY2 PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY2 PSXFACE: 1226194: acpi_ps_parse_aml 2
ACPI SPY2 PSXFACE: 0: acpi_ps_delete_parse_tree 1
ACPI SPY2 PSXFACE: 4: acpi_ps_delete_parse_tree 2
ACPI SPY PSXFACE: 1226233: acpi_ps_execute_pass x 2
ACPI SPY PSXFACE: 0: acpi_ps_stop_trace 1
ACPI SPY PSXFACE: 3: acpi_ps_stop_trace 2
ACPI SPY PSXFACE: 0: acpi_ps_update_parameter_list x 1
ACPI SPY PSXFACE: 3: acpi_ps_update_parameter_list x 2
ACPI SPY PSXFACE: 0: if (info->return_object) 1
ACPI SPY PSXFACE: 2: if (info->return_object) 2
ACPI SPY NSEVAL: 1226353: acpi_ps_execute_method 2
ACPI SPY NSEVAL: 0: acpi_ex_exit_interpreter 1
ACPI SPY NSEVAL: 0: acpi_ex_exit_interpreter 1
ACPI SPY NSEVAL: 0: status == AE_CTRL_RETURN_VALUE 1
ACPI SPY NSEVAL: 3: status == AE_CTRL_RETURN_VALUE 2
ACPI SPY NSXFEVAL: 1226596: else (pathname) &&
(acpi_ns_valid_root_prefix(pathname[0])) 2
ACPI SPY BATTERY: 1226704: _BST extract 2


I don't care about the steps :) I just want to have this fixed ;) Send more
patches, but name newer differently then older ones :)))
Comment 56 Vladimir Lebedev 2006-07-29 08:41:26 UTC
Created attachment 8637 [details]
bat_spy1.diff


Please perform the same actions (remove/apply, etc), the patch name is
bat_spy1.diff.
Comment 57 Cijoml Cijomlovic Cijomlov 2006-07-30 23:29:47 UTC
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3920 mAh
present voltage:         14800 mV

real    0m1.259s
user    0m0.000s
sys     0m0.011s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3920 mAh
present voltage:         14800 mV

real    0m1.231s
user    0m0.001s
sys     0m0.009s


ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: acpi_ns_evaluate 1
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 194: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 186: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 222: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 188: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 168: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 174: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 195: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 169: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 173: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 225: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 177: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 191: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 175: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 167: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 173: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 166: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 171: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 229: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 3699: acpi_ps_parse_aml 2
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 187: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 204: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 187: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 211: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 216: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 318: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 182: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200868: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 245: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 172: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 164: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 188: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 226: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 219: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 177: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 190: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 171: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 168: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 166: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 185: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 176: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 226: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 181: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 195: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 486: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 185: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 177: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200571: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 241: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 172: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 168: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 223: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 186: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 214: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 214: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 182: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 193: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 172: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 169: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 175: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 168: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 225: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 182: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 178: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 495: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 200: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 173: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 169: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 99270: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 215: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 211: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 183: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 165: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 169: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 201248: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 273: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 173: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 167: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 188: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 176: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 167: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 218: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 182: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 171: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 190: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 167: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 168: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 189: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 186: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 99815: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 237: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 217: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 187: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 169: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 215: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 201178: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 243: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 170: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 161: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 224: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 72: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 46: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 91: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 47: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 46: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 43: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 43: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 41: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 42: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 40: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 226543: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 11: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 21
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 22
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 18: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 27: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 29
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 1246718: acpi_ps_parse_aml 2
ACPI SPY NSEVAL: 1250792: acpi_ps_execute_method 2
ACPI SPY NSXFEVAL: 1250985: acpi_ns_evaluate 2
ACPI SPY BATTERY: 1251272: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: acpi_ns_evaluate 1
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 92: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 51: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 42: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 41: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 41: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 39: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 6: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 472: acpi_ps_parse_aml 2
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 20: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 21: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 202295: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 36: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 39: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 21: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200810: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 11: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 46: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 19: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 147: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 99710: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 30: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 5: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200922: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 34: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 99672: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 29: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200160: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 9: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 222277: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 13: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 21
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 22
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 18: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 25: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 29
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 1226782: acpi_ps_parse_aml 2
ACPI SPY NSEVAL: 1227322: acpi_ps_execute_method 2
ACPI SPY NSXFEVAL: 1227377: acpi_ns_evaluate 2
ACPI SPY BATTERY: 1227481: _BST extract 2
Comment 58 Vladimir Lebedev 2006-07-31 12:37:25 UTC
Hi,

Please remove the battery (physically) and perform the same actions, please 
attach the output and dmesg. I am interested in time of 'cat' command 
execution. I am afraid that it is a hardware problem.

Vladimir.

Comment 59 Cijoml Cijomlovic Cijomlov 2006-07-31 13:49:52 UTC
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 no

real    0m0.004s
user    0m0.001s
sys     0m0.002s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 no

real    0m0.003s
user    0m0.001s
sys     0m0.001s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 no

real    0m0.002s
user    0m0.001s
sys     0m0.001s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 no

real    0m0.004s
user    0m0.000s
sys     0m0.003s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 no

real    0m0.003s
user    0m0.002s
sys     0m0.001s


no dmesg inputs about battery
Comment 60 Vladimir Lebedev 2006-07-31 16:28:02 UTC
Created attachment 8665 [details]
bat_spy2.diff


Please remove the patch bat_spy1.diff, apply the attached bat_spy2.diff,
remove the battery and perform our usual procedure :).

Thanks.
Vladimir.
Comment 61 Cijoml Cijomlovic Cijomlov 2006-07-31 22:34:22 UTC
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      unknown
present voltage:         unknown

real    0m0.408s
user    0m0.000s
sys     0m0.006s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      unknown
present voltage:         unknown

real    0m0.408s
user    0m0.000s
sys     0m0.005s


ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: acpi_ns_evaluate 1
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 234: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 178: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 237: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 199: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 174: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 182: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 179: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 177: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 197: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 9: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 1983: acpi_ps_parse_aml 2
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 25: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200969: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 38: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 5: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 21: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200802: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 9: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 24: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 25: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 58: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 21
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 22
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 16: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 29
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 402359: acpi_ps_parse_aml 2
ACPI SPY NSEVAL: 404558: acpi_ps_execute_method 2
ACPI SPY NSXFEVAL: 404771: acpi_ns_evaluate 2
ACPI SPY BATTERY: 405045: _BST extract 2
ACPI SPY BATTERY: 0: _BST extract 1
ACPI SPY NSXFEVAL: 0: acpi_ns_evaluate 1
ACPI SPY NSEVAL: 0: acpi_ps_execute_method 1
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 190: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 69: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 103: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 56: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 44: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 43: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 43: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 43: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 46: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 37: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 6: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 1005: acpi_ps_parse_aml 2
ACPI SPY PSXFACE: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 0: acpi_ps_parse_aml 1
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 2
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 21: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 201455: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 37: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 39: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 5: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 21: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 200809: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 10: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 45: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 23
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 24
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 25
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 26
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 5: acpi_ps_parse_aml 5
ACPI SPY PSPARSE _BST: 25: acpi_ps_parse_aml 6
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 7
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 8
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 59: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 23: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 21
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 22
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 3
ACPI SPY PSPARSE _BST: 16: acpi_ps_parse_aml 4
ACPI SPY PSPARSE _BST: 4: acpi_ps_parse_aml 10
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 11
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 12
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 13
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 14
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 15
ACPI SPY PSPARSE _BST: 24: acpi_ps_parse_aml 16
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 17
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 18
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 19
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 20
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 29
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 34
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 35
ACPI SPY PSPARSE _BST: 3: acpi_ps_parse_aml 36
ACPI SPY PSPARSE _BST: 2: acpi_ps_parse_aml 37
ACPI SPY PSXFACE: 402844: acpi_ps_parse_aml 2
ACPI SPY NSEVAL: 404051: acpi_ps_execute_method 2
ACPI SPY NSXFEVAL: 404243: acpi_ns_evaluate 2
ACPI SPY BATTERY: 404506: _BST extract 2
Comment 62 Vladimir Lebedev 2006-09-25 22:37:49 UTC
Created attachment 9099 [details]
Optimized ec.
Comment 63 Vladimir Lebedev 2006-09-25 22:55:05 UTC
I suggest continuing the problem investigation on stable version 2.6.18.

Please:
a) Check the problem on original 2.6.18 (DVB behavior and times 
of 'cat /proc/acpi/battery/*');
b) Apply the patch 'ec' and perform the same actions as in a).

Vladimir.
Comment 64 Cijoml Cijomlovic Cijomlov 2006-09-27 11:31:44 UTC
Hi Vladimir, glad to see you back! :D

notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charging
present rate:            unknown
remaining capacity:      3080 mAh
present voltage:         14800 mV

real    0m1.118s
user    0m0.001s
sys     0m0.006s
notas:/home/cijoml# uname -a
Linux notas 2.6.18 #1 PREEMPT Wed Sep 20 09:28:52 CEST 2006 i686 GNU/Linux
Comment 65 Vladimir Lebedev 2006-09-27 13:16:38 UTC
I am glad to see you too.
But, as I understand the problem persist on 2.6.18 and on the 'ec' patched 
2.6.18; Is it?
Comment 66 Cijoml Cijomlovic Cijomlov 2006-09-27 13:26:45 UTC
yes it is right. But we are 0.1 sec faster ;) only 1,1 sec left ;)
Comment 67 Vladimir Lebedev 2006-09-27 20:30:27 UTC
OK, I need some time for coding 'time measurement patch' for the embedded 
controler.
Comment 68 Cijoml Cijomlovic Cijomlov 2006-10-19 03:32:16 UTC
Hi Vladimir, any news or patches to test?
Comment 69 Vladimir Lebedev 2006-10-20 01:05:29 UTC
I will send my questions/suggestions in a couple of days.
Comment 70 Vladimir Lebedev 2006-10-21 23:25:56 UTC
I tried to recompile your dsdt which I had extracted from acpidump (I am going 
to add some debug messages and send it to you), but iasl completes with errors.
I run 'iasl -tc DSDT.dsl' command. I am not sure that the problems is here, 
but it will be nice to have clear dsdt.
Could you try to find other dsdt which can be compiled without errors?

Comment 71 Cijoml Cijomlovic Cijomlov 2006-10-21 23:59:01 UTC
I am not sure what you mean. You want me to send you dsdt from my second laptop
with the same problem?
Comment 72 Vladimir Lebedev 2006-10-22 00:32:57 UTC
> I am not sure what you mean. You want me to send you dsdt from my second 
laptop with the same problem?

I mean dsdt (or acpidump) from another bios version, so yes, if your second 
laptop has another bios version.
Your current dsdt is wrong, at least 'iasl' compiler shows few errors.

Comment 73 Vladimir Lebedev 2006-10-27 03:39:38 UTC
Created attachment 9366 [details]
dsdt_patch_1


Debug info added
Comment 74 Vladimir Lebedev 2006-10-27 03:55:13 UTC
dsdt_patch_1 contains additional debug info for _BST method.

Please:

apply the patch to your dsdt, recompile it (iasl -tc  ...)

in .config:
turn on <Show timing informaition on printks> in <Kernel hacking>
turn on <Debug Statements> in ACPI submenu

recompile the kernel with new DSDT
and as usually perform twice 'cat /proc/acpi/battery/BAT0/state' and post 
dmesg.
Comment 75 Cijoml Cijomlovic Cijomlov 2006-10-27 09:06:43 UTC
Created attachment 9367 [details]
dsdt.dsl with errors
Comment 76 Cijoml Cijomlovic Cijomlov 2006-10-27 09:07:33 UTC
I have some errors when compiling can you please send patch fixing them?
Including errors:


notas:/usr/src/dsdt-acer# iasl -tc dsdt.dsl

Intel ACPI Component Architecture
ASL Optimizing Compiler version 20060912 [Oct  2 2006]
Copyright (C) 2000 - 2006 Intel Corporation
Supports ACPI Specification Revision 3.0a

dsdt.dsl   977:                         If (UPBI ())
Error    4059 - Called method returns no value ^

dsdt.dsl   982:                         If (UPBS ())
Error    4059 - Called method returns no value ^

dsdt.dsl  2830:                     Field (ERAM, AnyAcc, Lock, Preserve)
Error    4074 -                               ^ Host Operation Region requires
ByteAcc access

dsdt.dsl  2928:                             Store (Arg2, DAT3)
Error    4005 -    Method argument is not initialized ^  (Arg2)

dsdt.dsl  2928:                             Store (Arg2, DAT3)
Remark   5065 -   Not a parameter, used as local only ^  (Arg2)

ASL Input:  dsdt.dsl - 5355 lines, 197380 bytes, 2348 keywords
Compilation complete. 4 Errors, 0 Warnings, 1 Remarks, 507 Optimizations
Comment 77 Vladimir Lebedev 2006-10-27 09:27:20 UTC
These errors are from the original dsdt, ignore them.
Iasl should produce dsdt.hex in our case too, please try it.

Comment 78 Cijoml Cijomlovic Cijomlov 2006-10-27 09:51:57 UTC
If I did everything OK, this should be an output:

[  263.252000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  263.452000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  263.452000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  263.452000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  263.653000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  264.355000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  264.355000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  273.211000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  273.414000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  273.414000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  273.414000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  273.615000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  274.317000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  274.317000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  275.139000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  275.342000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  275.342000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  275.342000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  275.543000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  276.245000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  276.245000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
Comment 79 Vladimir Lebedev 2006-10-27 09:59:45 UTC
OK, I need some time for the investigation ...
Comment 80 Vladimir Lebedev 2006-10-27 10:08:54 UTC
Please send kernel boot options.
Comment 81 Cijoml Cijomlovic Cijomlov 2006-10-27 10:12:27 UTC
in lilo.conf I have:

        append="lapic ec_intr=0 pci=assign-busses"

Dmesg says:
[   12.236716] Kernel command line: BOOT_IMAGE=Linux ro root=302 lapic ec_intr=0
pci=assign-busses
Comment 82 Vladimir Lebedev 2006-10-28 00:49:31 UTC
Please describe your hardware model name and current bios version.
Comment 83 Cijoml Cijomlovic Cijomlov 2006-10-28 00:59:48 UTC
Hi Vladimir, it is everythink described in first post at the begining of this bug.

My BIOS revision is 1.15 and can be found here:

https://www.synapsenow.com/synapse/data/7117/documents/tm240250115.exe
Comment 84 Cijoml Cijomlovic Cijomlov 2006-10-28 02:55:32 UTC
Created attachment 9369 [details]
dmesg for 2.6.18.1 when udev was installed instead of hotplug

dmesg for 2.6.18.1 when udev was installed instead of hotplug however this
change took no effect.
Comment 85 Vladimir Lebedev 2006-10-28 03:43:22 UTC
It seems to me I know the reason of problem. I will describe it on Monday.
But:
> My BIOS revision is 1.15 and can be found here:
Is it latest bios? Can you try an another bios? If it is possible please send 
the acpidump or dsdt.
Comment 86 Cijoml Cijomlovic Cijomlov 2006-10-28 04:01:43 UTC
Yes, it is latest version. There is not another bios for this laptop.
Comment 87 Cijoml Cijomlovic Cijomlov 2006-10-28 04:04:12 UTC
Created attachment 9372 [details]
acpidump-acer-TM240-BIOS-1.15
Comment 88 Vladimir Lebedev 2006-10-29 06:09:07 UTC
Created attachment 9374 [details]
dsdt_patch_2

dsdt_patch_2.txt: sleep time is commented.
Try patch2, apply it to the original dsdt.
Comment 89 Cijoml Cijomlovic Cijomlov 2006-10-31 01:09:55 UTC
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m0.433s
user    0m0.000s
sys     0m0.010s
notas:/home/cijoml# time cat /proc/acpi/battery/BAT0/state
present:                 yes
capacity state:          ok
charging state:          charged
present rate:            unknown
remaining capacity:      3880 mAh
present voltage:         14800 mV

real    0m0.433s
user    0m0.000s
sys     0m0.012s



[  111.092000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  111.094000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  111.094000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  111.094000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  111.096000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  111.500000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  111.501000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  114.238000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  114.241000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  114.241000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  114.242000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  114.243000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  114.648000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  114.648000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  115.890000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  115.893000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  115.893000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  115.893000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  115.895000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  116.299000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  116.299000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  127.959000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  127.962000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  127.963000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  127.963000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  127.964000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  128.369000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  128.369000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  131.298000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  131.301000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  131.301000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  131.301000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  131.303000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  131.707000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  131.707000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
[  141.071000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 0:"
[  141.073000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 1:"
[  141.077000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 3:"
[  141.078000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 4:"
[  141.080000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 7:"
[  142.205000] [ACPI Debug]  String: [0x11] "BAT0_BST_TRACE 9:"
[  142.205000] [ACPI Debug]  String: [0x10] "BAT0_BST_RETURN:"
Comment 90 Vladimir Lebedev 2006-10-31 07:20:35 UTC
What about DVB, is the picture broken?

Comment 91 Cijoml Cijomlovic Cijomlov 2006-10-31 09:03:50 UTC
yes. But much much smaller. 

Now we miss only 9Mbit/2 instead of 35MBit/s of stream.

But I still think this is not fix for this case, because this check should be
multithreaded to be fixed. No we partially fixed my BIOS, but fix in my opinion
is not to let kernel wat for battery status...
Comment 92 Vladimir Lebedev 2006-12-18 22:28:23 UTC
Please try the latest 2.6.20-rc1-mm1 version w/o DSDT patches.
Comment 93 Cijoml Cijomlovic Cijomlov 2007-01-16 02:30:28 UTC
Hi Vladimir - I will test 2.6.20 after release - I work on my thesis now and
this week I am very busy.
Comment 94 Adrian Bunk 2007-02-28 16:57:45 UTC
Cijoml, have you found time for trying recent (and -mm) kernels?
Comment 95 Vladimir Lebedev 2007-02-28 20:44:27 UTC
I think the problem is that the system collects a lot of GPE queries during 
the sleep time of _BST request (EC semaphore is locking), and try to performs 
them after EC semaphore unlocking.
Comment 96 Cijoml Cijomlovic Cijomlov 2007-02-28 23:23:32 UTC
Hi Vladimir,

I have clear 2.6.20 install. Is it enough? If not, I will compile somethink more
like mm kernels.
Comment 97 Vladimir Lebedev 2007-03-01 08:50:22 UTC
There are a lot of ACPI changes in 2.6.21 release, so please test the latest 
git*. I am going to send some test patch based on 2.6.21.
Comment 98 Vladimir Lebedev 2007-05-17 13:38:47 UTC
Please test with 2.6.21, 2.6.22-rc*, or something later.
If this issue still exists, please re-open this bug report.

Comment 99 Cijoml Cijomlovic Cijomlov 2007-05-23 10:31:03 UTC
Cool just tested with 2.6.21.1 and latest DVB SVN trunk and YES! IT WORKS NOW!
Thank you for your help :)

Note You need to log in before you can comment on or make changes to this bug.