Bug 103571 - ASUS UX305FA: Battery uevents are not reported by kernel
Summary: ASUS UX305FA: Battery uevents are not reported by kernel
Status: CLOSED WILL_NOT_FIX
Alias: None
Product: ACPI
Classification: Unclassified
Component: Power-Battery (show other bugs)
Hardware: Intel Linux
: P1 high
Assignee: Aaron Lu
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2015-08-26 23:53 UTC by doa379
Modified: 2015-11-24 00:53 UTC (History)
4 users (show)

See Also:
Kernel Version: 4.3.0
Subsystem:
Regression: No
Bisected commit-id:


Attachments
acpidump (725.12 KB, application/octet-stream)
2015-08-27 13:31 UTC, doa379
Details
interrupts (7.35 KB, application/octet-stream)
2015-08-27 13:33 UTC, doa379
Details
dmesg (51.39 KB, application/octet-stream)
2015-08-27 13:37 UTC, doa379
Details
dmidecode (10.17 KB, application/octet-stream)
2015-08-27 14:00 UTC, doa379
Details
dmesg -- extended EC debug info (64.84 KB, text/plain)
2015-08-31 11:04 UTC, doa379
Details
Linux 4.3-rc7 EC-Debug dmesg output (55.48 KB, text/plain)
2015-10-28 20:53 UTC, doa379
Details
Linux 4.3-rc7 EC-Debug dmesg output (62.07 KB, application/octet-stream)
2015-10-29 18:09 UTC, doa379
Details
Linux 4.3-rc7 EC-Debug dmesg with opcode trace output (77.32 KB, application/octet-stream)
2015-10-30 19:49 UTC, doa379
Details
Add debug print in acpi_battery_notify (453 bytes, patch)
2015-11-03 05:36 UTC, Aaron Lu
Details | Diff
Linux 4.3 syslog w/ event traces (339.25 KB, text/plain)
2015-11-04 19:56 UTC, doa379
Details

Description doa379 2015-08-26 23:53:50 UTC
This is a pm issue. Battery does not update percentages update with respect to kernel uevents. acpi -b or upower report battery levels properly.

You have to manually get a uevent through:

$ echo change | sudo tee /sys/class/power_supply/*/uevent

or

$ sudo udevadm trigger --subsystem-match=power_supply


Seems dodgy ACPI BIOS related.

Please request which data you need. Ready to test solutions.
Comment 1 doa379 2015-08-27 13:31:57 UTC
Created attachment 185971 [details]
acpidump

Added acpidump output.
Comment 2 doa379 2015-08-27 13:33:54 UTC
Created attachment 185981 [details]
interrupts

Added interrupts output.
Comment 3 doa379 2015-08-27 13:37:13 UTC
Created attachment 185991 [details]
dmesg

Added dmesg output.
Comment 4 doa379 2015-08-27 14:00:27 UTC
Created attachment 186001 [details]
dmidecode

Added dmidecode output.
Comment 5 doa379 2015-08-31 11:04:59 UTC
Created attachment 186301 [details]
dmesg -- extended EC debug info
Comment 6 Aaron Lu 2015-09-08 07:54:41 UTC
(In reply to doa379 from comment #0)
> This is a pm issue. Battery does not update percentages update with respect
> to kernel uevents. acpi -b or upower report battery levels properly.
> 
> You have to manually get a uevent through:
> 
> $ echo change | sudo tee /sys/class/power_supply/*/uevent
> 
> or
> 
> $ sudo udevadm trigger --subsystem-match=power_supply
> 
> 
> Seems dodgy ACPI BIOS related.
> 
> Please request which data you need. Ready to test solutions.

From the dsdt, the EC's _QA1, _QA3 and _QAC have code to notify the battery.

Lv,
How to turn on debug for EC so that we can see if any of the above query method happened?
Comment 7 doa379 2015-09-08 09:13:26 UTC
You can enable debug option in:

linux-4.2/drivers/acpi/ec.c

Line number 33.
Comment 8 Lv Zheng 2015-09-08 14:25:29 UTC
You can use dynamic debugging feature to enable specific debugging log lines.
But for EC, it's better to enable DEBUG macro in ec.c so we can have full debugging details to learn its state machine.

Uncomment the following line in drivers/acpi/ec.c and rebuild the kernel:
/* #define DEBUG */

Thanks
-Lv
Comment 9 Aaron Lu 2015-09-15 02:52:15 UTC
Hi,

Can you please follow comment #8 and make sure debug output is enabled, then use the computer and see if any of the above _Q method happens? If not, then probably this is a firmware issue?
Comment 10 Aaron Lu 2015-10-13 02:53:32 UTC
ping
Comment 11 doa379 2015-10-27 13:14:01 UTC
If we follow Comment #5. Debug output was enabled for EC and dmesg was posted.

What are we looking for?
Comment 12 Aaron Lu 2015-10-28 01:19:39 UTC
Lv,

Is the log in comment #5 enough? Thanks.
Comment 13 Lv Zheng 2015-10-28 02:45:09 UTC
Hi, Aaron

I can see event ID 0xB2 and 0xAC returned by the firmware.
And there is only 1 _QAC evaluation can be seen in the log.

The whole process of _QAC doesn't take too long time.
[    9.084387] ACPI : EC: ===== IRQ (2) =====
[    9.084399] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    9.084400] ACPI : EC: ##### Command(QR_EC) submitted/blocked #####
[    9.084413] ACPI : EC: ##### Event started #####
[    9.084415] ACPI : EC: 2: Increase command
[    9.084416] ACPI : EC: ***** Command(QR_EC) started *****
[    9.084417] ACPI : EC: ===== TASK (2) =====
[    9.084426] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    9.084427] ACPI : EC: EC_SC(W) = 0x84
[    9.085723] ACPI : EC: ===== IRQ (2) =====
[    9.085732] ACPI : EC: EC_SC(R) = 0x09 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=1
[    9.085736] ACPI : EC: EC_DATA(R) = 0xac
[    9.085737] ACPI : EC: ##### Command(QR_EC) unblocked #####
[    9.085738] ACPI : EC: ##### Command(QR_EC) completed by hardware #####
[    9.085749] ACPI : EC: ***** Command(QR_EC) stopped *****
[    9.085750] ACPI : EC: 1: Decrease command
[    9.085752] ACPI : EC: ##### Query(0xac) scheduled #####
[    9.085757] ACPI : EC: ##### Event stopped #####
[    9.085761] ACPI : EC: ##### Query(0xac) started #####
[    9.086719] ACPI : EC: ##### Query(0xac) stopped #####
So you may need to enable battery debugging features to trace problems after that.

Thanks and best regards
-Lv
Comment 14 Lv Zheng 2015-10-28 03:18:47 UTC
Hi,

There might be possibilities that, OSPM is not notified even when the _QAC() is actually executed:
            Name (ECFL, Ones)

            Method (ECAV, 0, NotSerialized)
            {
                If (LGreaterEqual (SLPT, 0x04))
                {
                    Return (Zero)
                }

                If (LEqual (NVLK, One))
                {
                    Return (Zero)
                }

                If (LEqual (ECFL, Ones))
                {
                    If (LGreaterEqual (_REV, 0x02))
                    {
                        Return (One)
                    }
                    Else
                    {
                        Return (Zero)
                    }

                    Return (Zero)
                }

                Return (ECFL)
            }

        Method (ISMI, 1, Serialized)
        {
            Store (Arg0, SMCM)
        }

        Method (ECAC, 0, NotSerialized)
        {
            Store (GNBF, BIPA)
            Store (0x30, MFUN)
            Store (One, SFUN)
            Store (0x10, LEN)
            Store (Zero, EROR)
            Store (One, STAS)
            ISMI (0xA1)
        }

        Method (ST8E, 2, Serialized)
        {
            If (ECAV ())
            {
                Acquire (MU4T, 0xFFFF)
                Store (0xFF, CMD)
                Store (0x8E, EDA1)
                Store (Arg0, EDA2)
                Store (Arg1, EDA3)
                ECAC ()
                Release (MU4T)
                Return (EDA1)
            }

            Return (Ones)
        }

        Method (_QAC, 0, NotSerialized)  // _Qxx: EC Query
        {
            Store (ST8E (0x28, Zero), Local0)
            If (LEqual (And (Local0, One), One))
            {
                Notify (BAT0, 0x80)
            }
        }
It might be a BIOS issue as the code path containing the Notify opcode should be determined by the result of executing ECAV()/ECAC(). Only when ECAV() returns true and EDA1 returns One after executing ECAC(), the battery driver can get notified. Except ECFL, all other variables (see below list) accessed in these functions are system memory/IO opregion fields:
 SPLT/NVLK in ECAV()
 SMCM in ISMI()
 GNBF/BIPA/MFUN/SFUN/LEN/EROR/STAS in ECAC()
 CMD/EDA1/EDA2/EDA3 in ST8E()
So maybe we could confirm one more time that Linux battery device driver is actually notified during evaluating _QAC() before starting to debug battery driver. We can confirm this by using AML tracer.

Could you enable EC debugging and boot the kernel with the following command line:
 acpi.trace_state=method acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC
The AML tracer feature is available in 4.3 kernels. So you need to download latest upstream kernel before performing this test. I'm sorry for the inconvenience.

Thanks and best regards
-Lv
Comment 15 doa379 2015-10-28 20:53:04 UTC
Created attachment 191441 [details]
Linux 4.3-rc7 EC-Debug dmesg output
Comment 16 doa379 2015-10-28 20:54:53 UTC
I compiled the Linux 4.3-rc7 kernel together with EC Debug output and then loaded the kernel with these parameters:

acpi.trace_state=method acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC

The dmesg output is attached.
Comment 17 Lv Zheng 2015-10-29 04:45:07 UTC
I don't know why this is the first line of your kernel boot log:
[    0.000000] ACPI: SSDT 0x00000000BD734838 000315 (v01 SataRe SataTabl 00001000 INTL 20120913)

You should upload the dmesg containing information that we can learn if the kernel boot parameters are applied correctly.

From the log, I couldn't see the parameters successfully applied.
Could you enlarge the log buffer, boot the kernel and upload the log again?

Maybe you should check:
1. if CONFIG_ACPI_DEBUG is enabled by typing "make menuconfig" before building the kernel;
2. Documetation/acpi/method-tracing.txt for correct parameters to be used to trace _SB.PCI0.LPCB.EC0._QAC
3. add the following parameter to enlarge the kernel log buffer "log_buf_len=xxx"

Thanks and best regards
-Lv
Comment 18 doa379 2015-10-29 18:04:05 UTC
Comment on attachment 191441 [details]
Linux 4.3-rc7 EC-Debug dmesg output

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.3.0-rc7 (jh@ux305) (gcc version 4.9.2 (Debian 4.9.2-10) ) #2 SMP PREEMPT Thu Oct 29 17:46:39 GMT 2015
[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-4.3.0-rc7 root=/dev/mapper/Debian-root ro kernel.nmi_watchdog=0 nmi_watchdog=0 intel_iommu=igfx_off rootfstype=ext4 acpi.trace_state=method acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC log_buf_len=16M init=/lib/sysvinit/init
[    0.000000] x86/fpu: xstate_offset[2]: 0240, xstate_sizes[2]: 0100
[    0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 0x340 bytes, using 'standard' format.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000059000-0x000000000009dfff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009e000-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000b9582fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000b9583000-0x00000000b98e7fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000b98e8000-0x00000000bce16fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bce17000-0x00000000bce75fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000bce76000-0x00000000bd2d4fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bd2d5000-0x00000000be413fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000be414000-0x00000000beee3fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000beee4000-0x00000000beffdfff] type 20
[    0.000000] BIOS-e820: [mem 0x00000000beffe000-0x00000000beffefff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bf800000-0x00000000dfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed00000-0x00000000fed03fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000021effffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] efi: EFI v2.40 by American Megatrends
[    0.000000] efi:  ESRT=0xbee2cd98  ACPI=0xbd71b000  ACPI 2.0=0xbd71b000  SMBIOS=0xbee2c918 
[    0.000000] esrt: Reserving ESRT space from 0x00000000bee2cd98 to 0x00000000bee2cdd0.
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: ASUSTeK COMPUTER INC. UX305FA/UX305FA, BIOS UX305FA.211 07/02/2015
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x21f000 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-DFFFF uncachable
[    0.000000]   E0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0000000000 mask 7F80000000 write-back
[    0.000000]   1 base 0080000000 mask 7FE0000000 write-back
[    0.000000]   2 base 00A0000000 mask 7FF0000000 write-back
[    0.000000]   3 base 00B0000000 mask 7FF8000000 write-back
[    0.000000]   4 base 00B8000000 mask 7FFC000000 write-back
[    0.000000]   5 base 00BC000000 mask 7FFE000000 write-back
[    0.000000]   6 base 00BE000000 mask 7FFF000000 write-back
[    0.000000]   7 base 0100000000 mask 7F00000000 write-back
[    0.000000]   8 base 0200000000 mask 7FE0000000 write-back
[    0.000000]   9 base 021F000000 mask 7FFF000000 uncachable
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] e820: update [mem 0xbf000000-0xffffffff] usable ==> reserved
[    0.000000] e820: last_pfn = 0xbefff max_arch_pfn = 0x400000000
[    0.000000] Base memory trampoline at [ffff880000098000] 98000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x01dd1000, 0x01dd1fff] PGTABLE
[    0.000000] BRK [0x01dd2000, 0x01dd2fff] PGTABLE
[    0.000000] BRK [0x01dd3000, 0x01dd3fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x21ee00000-0x21effffff]
[    0.000000]  [mem 0x21ee00000-0x21effffff] page 2M
[    0.000000] BRK [0x01dd4000, 0x01dd4fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x200000000-0x21edfffff]
[    0.000000]  [mem 0x200000000-0x21edfffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x1e0000000-0x1ffffffff]
[    0.000000]  [mem 0x1e0000000-0x1ffffffff] page 1G
[    0.000000] init_memory_mapping: [mem 0x00100000-0xb9582fff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x3fffffff] page 2M
[    0.000000]  [mem 0x40000000-0x7fffffff] page 1G
[    0.000000]  [mem 0x80000000-0xb93fffff] page 2M
[    0.000000]  [mem 0xb9400000-0xb9582fff] page 4k
[    0.000000] init_memory_mapping: [mem 0xb98e8000-0xbce16fff]
[    0.000000]  [mem 0xb98e8000-0xb99fffff] page 4k
[    0.000000]  [mem 0xb9a00000-0xbcdfffff] page 2M
[    0.000000]  [mem 0xbce00000-0xbce16fff] page 4k
[    0.000000] BRK [0x01dd5000, 0x01dd5fff] PGTABLE
[    0.000000] BRK [0x01dd6000, 0x01dd6fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0xbce76000-0xbd2d4fff]
[    0.000000]  [mem 0xbce76000-0xbcffffff] page 4k
[    0.000000]  [mem 0xbd000000-0xbd1fffff] page 2M
[    0.000000]  [mem 0xbd200000-0xbd2d4fff] page 4k
[    0.000000] init_memory_mapping: [mem 0xbeffe000-0xbeffefff]
[    0.000000]  [mem 0xbeffe000-0xbeffefff] page 4k
[    0.000000] init_memory_mapping: [mem 0x100000000-0x1dfffffff]
[    0.000000]  [mem 0x100000000-0x1dfffffff] page 1G
[    0.000000] log_buf_len: 16777216 bytes
[    0.000000] early log buf free: 59068(90%)
[    0.000000] RAMDISK: [mem 0x375a2000-0x37ac8fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000BD71B000 000024 (v02 _ASUS_)
[    0.000000] ACPI: XSDT 0x00000000BD71B0B0 0000DC (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FACP 0x00000000BD734478 00010C (v05 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: DSDT 0x00000000BD71B218 01925E (v02 _ASUS_ Notebook 01072009 INTL 20120913)
[    0.000000] ACPI: FACS 0x00000000BE411F80 000040
[    0.000000] ACPI: APIC 0x00000000BD734588 000084 (v03 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FPDT 0x00000000BD734610 000044 (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FIDT 0x00000000BD734658 00009C (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: ECDT 0x00000000BD7346F8 0000C1 (v01 _ASUS_ Notebook 01072009 AMI. 00000005)
[    0.000000] ACPI: MCFG 0x00000000BD7347C0 00003C (v01 _ASUS_ Notebook 01072009 MSFT 00000097)
[    0.000000] ACPI: HPET 0x00000000BD734800 000038 (v01 _ASUS_ Notebook 01072009 AMI. 00000005)
[    0.000000] ACPI: SSDT 0x00000000BD734838 000315 (v01 SataRe SataTabl 00001000 INTL 20120913)
[    0.000000] ACPI: UEFI 0x00000000BD734B50 000042 (v01                 00000000      00000000)
[    0.000000] ACPI: SSDT 0x00000000BD734B98 000C7D (v02 Ther_R Ther_Rvp 00001000 INTL 20120913)
[    0.000000] ACPI: ASF! 0x00000000BD735818 0000A0 (v32 INTEL   HCG     00000001 TFSM 000F4240)
[    0.000000] ACPI: SSDT 0x00000000BD7358B8 000494 (v02 PmRef  Cpu0Ist  00003000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD735D50 000B74 (v02 CpuRef CpuSsdt  00003000 INTL 20120913)
[    0.000000] ACPI: TPM2 0x00000000BD7368C8 000034 (v03        Tpm2Tabl 00000001 AMI  00000000)
[    0.000000] ACPI: SSDT 0x00000000BD736900 000041 (v01 Ssdt   PttSsdt  00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD736948 0037ED (v02 DptfTa DptfTabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73A138 000394 (v02 CppcTa CppcTabl 00001000 INTL 20120913)
[    0.000000] ACPI: PCCT 0x00000000BD73A4D0 00006E (v05 PcctTa PcctTabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73A540 000AC4 (v02 Cpc_Ta Cpc_Tabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73B008 00667D (v02 SaSsdt SaSsdt   00003000 INTL 20120913)
[    0.000000] ACPI: DMAR 0x00000000BD741688 0000A8 (v01 INTEL  BDW      00000001 INTL 00000001)
[    0.000000] ACPI: BGRT 0x00000000BD741730 000038 (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: MSDM 0x00000000BCE74E18 000055 (v03 _ASUS_ Notebook 00000000 ASUS 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000021effffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x21dfd6000-0x21dffbfff]
[    0.000000]  [ffffea0000000000-ffffea00087fffff] PMD -> [ffff880215e00000-ffff88021d5fffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000021effffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000000057fff]
[    0.000000]   node   0: [mem 0x0000000000059000-0x000000000009dfff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x00000000b9582fff]
[    0.000000]   node   0: [mem 0x00000000b98e8000-0x00000000bce16fff]
[    0.000000]   node   0: [mem 0x00000000bce76000-0x00000000bd2d4fff]
[    0.000000]   node   0: [mem 0x00000000beffe000-0x00000000beffefff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000021effffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000021effffff]
[    0.000000] On node 0 totalpages: 1949358
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 22 pages reserved
[    0.000000]   DMA zone: 3996 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 12029 pages used for memmap
[    0.000000]   DMA32 zone: 769810 pages, LIFO batch:31
[    0.000000]   Normal zone: 18368 pages used for memmap
[    0.000000]   Normal zone: 1175552 pages, LIFO batch:31
[    0.000000] Reserving Intel graphics stolen memory at 0xc0000000-0xdfffffff
[    0.000000] ACPI: PM-Timer IO Port: 0x1808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] res res lint[0x63])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] res dfl lint[0x96])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high level lint[0x8b])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0xd5])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-39
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a701 base: 0xfed00000
[    0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00058000-0x00058fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009e000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xb9583000-0xb98e7fff]
[    0.000000] PM: Registered nosave memory: [mem 0xbce17000-0xbce75fff]
[    0.000000] PM: Registered nosave memory: [mem 0xbd2d5000-0xbe413fff]
[    0.000000] PM: Registered nosave memory: [mem 0xbe414000-0xbeee3fff]
[    0.000000] PM: Registered nosave memory: [mem 0xbeee4000-0xbeffdfff]
[    0.000000] PM: Registered nosave memory: [mem 0xbefff000-0xbf7fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xbf800000-0xdfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xe0000000-0xf7ffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xf8000000-0xfbffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfc000000-0xfebfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfecfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed00000-0xfed03fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed04000-0xfed1bfff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed1c000-0xfed1ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed20000-0xfedfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee00000-0xfee00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee01000-0xfeffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xff000000-0xffffffff]
[    0.000000] e820: [mem 0xe0000000-0xf7ffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] PERCPU: Embedded 31 pages/cpu @ffff88021dc00000 s87448 r8192 d31336 u524288
[    0.000000] pcpu-alloc: s87448 r8192 d31336 u524288 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 1918875
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-4.3.0-rc7 root=/dev/mapper/Debian-root ro kernel.nmi_watchdog=0 nmi_watchdog=0 intel_iommu=igfx_off rootfstype=ext4 acpi.trace_state=method acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC log_buf_len=16M init=/lib/sysvinit/init
[    0.000000] DMAR: Disable GFX device mapping
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 7522384K/7797432K available (4591K kernel code, 934K rwdata, 1680K rodata, 1564K init, 1376K bss, 275048K reserved, 0K cma-reserved)
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 64.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4
[    0.000000] NR_IRQS:524544 nr_irqs:728 16
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] spurious 8259A interrupt: IRQ7.
[    0.000000] tsc: Detected 1396.734 MHz processor
[    0.000028] Calibrating delay loop (skipped), value calculated using timer frequency.. 2793.46 BogoMIPS (lpj=1396734)
[    0.000036] pid_max: default: 32768 minimum: 301
[    0.000043] ACPI: Core revision 20150818
[    0.040914] ACPI: 10 ACPI AML tables successfully acquired and loaded
[    0.041729] Security Framework initialized
[    0.042223] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.043979] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.044750] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.044763] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.044975] Initializing cgroup subsys io
[    0.044983] Initializing cgroup subsys memory
[    0.044993] Initializing cgroup subsys devices
[    0.044997] Initializing cgroup subsys freezer
[    0.045002] Initializing cgroup subsys net_cls
[    0.045006] Initializing cgroup subsys perf_event
[    0.045011] Initializing cgroup subsys net_prio
[    0.045038] CPU: Physical Processor ID: 0
[    0.045041] CPU: Processor Core ID: 0
[    0.045047] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.045051] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.046339] process: using mwait in idle threads
[    0.046345] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
[    0.046348] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
[    0.046684] Freeing SMP alternatives memory: 20K (ffffffff81a72000 - ffffffff81a77000)
[    0.049802] x2apic: IRQ remapping doesn't support X2APIC mode
[    0.050351] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[    0.060358] TSC deadline timer enabled
[    0.060360] smpboot: CPU0: Intel(R) Core(TM) M-5Y71 CPU @ 1.20GHz (family: 0x6, model: 0x3d, stepping: 0x4)
[    0.060379] Performance Events: PEBS fmt2+, 16-deep LBR, Broadwell events, full-width counters, Intel PMU driver.
[    0.060404] ... version:                3
[    0.060407] ... bit width:              48
[    0.060410] ... generic registers:      4
[    0.060413] ... value mask:             0000ffffffffffff
[    0.060416] ... max period:             0000ffffffffffff
[    0.060419] ... fixed-purpose events:   3
[    0.060422] ... event mask:             000000070000000f
[    0.068456] x86: Booting SMP configuration:
[    0.068459] .... node  #0, CPUs:      #1 #2 #3
[    0.117795] x86: Booted up 1 node, 4 CPUs
[    0.117804] smpboot: Total of 4 processors activated (11173.87 BogoMIPS)
[    0.122285] devtmpfs: initialized
[    0.124560] PM: Registering ACPI NVS region [mem 0xbd2d5000-0xbe413fff] (18083840 bytes)
[    0.124981] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.125052] pinctrl core: initialized pinctrl subsystem
[    0.125196] NET: Registered protocol family 16
[    0.130152] cpuidle: using governor ladder
[    0.135170] cpuidle: using governor menu
[    0.135291] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.135300] ACPI: bus type PCI registered
[    0.135306] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.135433] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf8000000-0xfbffffff] (base 0xf8000000)
[    0.135445] PCI: MMCONFIG at [mem 0xf8000000-0xfbffffff] reserved in E820
[    0.135461] PCI: Using configuration type 1 for base access
[    0.142714] ACPI: Added _OSI(Module Device)
[    0.142721] ACPI: Added _OSI(Processor Device)
[    0.142727] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.142733] ACPI: Added _OSI(Processor Aggregator Device)
[    0.146160] ACPI : EC: EC description table is found, configuring boot EC
[    0.146169] ACPI : EC: +++++ Starting EC +++++
[    0.146178] ACPI : EC: 1: Increase driver
[    0.146179] ACPI : EC: +++++ EC started +++++
[    0.149321] ACPI: Executed 8 blocks of module-level executable AML code
[    0.158985] ACPI: Dynamic OEM Table Load:
[    0.158994] ACPI: SSDT 0xFFFF880214445C00 0003D3 (v02 PmRef  Cpu0Cst  00003001 INTL 20120913)
[    0.160119] ACPI: Dynamic OEM Table Load:
[    0.160127] ACPI: SSDT 0xFFFF880214BC8000 0005AA (v02 PmRef  ApIst    00003000 INTL 20120913)
[    0.161332] ACPI: Dynamic OEM Table Load:
[    0.161339] ACPI: SSDT 0xFFFF880214430800 000119 (v02 PmRef  ApCst    00003000 INTL 20120913)
[    0.163489] ACPI: Interpreter enabled
[    0.163499] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150818/hwxface-580)
[    0.163509] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150818/hwxface-580)
[    0.163527] ACPI: (supports S0 S3 S4 S5)
[    0.163530] ACPI: Using IOAPIC for interrupt routing
[    0.163564] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.166243] ACPI: Power Resource [PG00] (on)
[    0.166768] ACPI: Power Resource [PG01] (on)
[    0.167290] ACPI: Power Resource [PG02] (on)
[    0.179900] ACPI: Power Resource [FN00] (off)
[    0.179989] ACPI: Power Resource [FN01] (off)
[    0.180075] ACPI: Power Resource [FN02] (off)
[    0.180160] ACPI: Power Resource [FN03] (off)
[    0.180245] ACPI: Power Resource [FN04] (off)
[    0.181176] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3e])
[    0.181184] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.182088] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
[    0.182093] acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration
[    0.182622] PCI host bridge to bus 0000:00
[    0.182627] pci_bus 0000:00: root bus resource [bus 00-3e]
[    0.182631] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.182635] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.182639] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.182644] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000c3fff window]
[    0.182649] pci_bus 0000:00: root bus resource [mem 0x000c4000-0x000c7fff window]
[    0.182654] pci_bus 0000:00: root bus resource [mem 0x000c8000-0x000cbfff window]
[    0.182659] pci_bus 0000:00: root bus resource [mem 0x000cc000-0x000cffff window]
[    0.182664] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff window]
[    0.182669] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff window]
[    0.182674] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff window]
[    0.182679] pci_bus 0000:00: root bus resource [mem 0x000dc000-0x000dffff window]
[    0.182684] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xfeafffff window]
[    0.182695] pci 0000:00:00.0: [8086:1604] type 00 class 0x060000
[    0.182817] pci 0000:00:02.0: [8086:161e] type 00 class 0x030000
[    0.182831] pci 0000:00:02.0: reg 0x10: [mem 0xf6000000-0xf6ffffff 64bit]
[    0.182838] pci 0000:00:02.0: reg 0x18: [mem 0xe0000000-0xefffffff 64bit pref]
[    0.182842] pci 0000:00:02.0: reg 0x20: [io  0xf000-0xf03f]
[    0.182969] pci 0000:00:03.0: [8086:160c] type 00 class 0x040300
[    0.182982] pci 0000:00:03.0: reg 0x10: [mem 0xf711c000-0xf711ffff 64bit]
[    0.183102] pci 0000:00:04.0: [8086:1603] type 00 class 0x118000
[    0.183117] pci 0000:00:04.0: reg 0x10: [mem 0xf7110000-0xf7117fff 64bit]
[    0.183253] pci 0000:00:14.0: [8086:9cb1] type 00 class 0x0c0330
[    0.183280] pci 0000:00:14.0: reg 0x10: [mem 0xf7100000-0xf710ffff 64bit]
[    0.183327] pci 0000:00:14.0: PME# supported from D3hot D3cold
[    0.183410] pci 0000:00:14.0: System wakeup disabled by ACPI
[    0.183450] pci 0000:00:16.0: [8086:9cba] type 00 class 0x078000
[    0.183473] pci 0000:00:16.0: reg 0x10: [mem 0xf7123000-0xf712301f 64bit]
[    0.183522] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.183652] pci 0000:00:1b.0: [8086:9ca0] type 00 class 0x040300
[    0.183675] pci 0000:00:1b.0: reg 0x10: [mem 0xf7118000-0xf711bfff 64bit]
[    0.183722] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.183818] pci 0000:00:1b.0: System wakeup disabled by ACPI
[    0.183865] pci 0000:00:1c.0: [8086:9c90] type 01 class 0x060400
[    0.183930] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.183948] pci 0000:00:1c.0: Enabling MPC IRBNCE
[    0.183952] pci 0000:00:1c.0: Intel PCH root port ACS workaround enabled
[    0.184072] pci 0000:00:1c.0: System wakeup disabled by ACPI
[    0.184118] pci 0000:00:1c.3: [8086:9c96] type 01 class 0x060400
[    0.184187] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
[    0.184205] pci 0000:00:1c.3: Enabling MPC IRBNCE
[    0.184210] pci 0000:00:1c.3: Intel PCH root port ACS workaround enabled
[    0.184332] pci 0000:00:1c.3: System wakeup disabled by ACPI
[    0.184378] pci 0000:00:1f.0: [8086:9cc7] type 00 class 0x060100
[    0.184580] pci 0000:00:1f.2: [8086:9c83] type 00 class 0x010601
[    0.184598] pci 0000:00:1f.2: reg 0x10: [io  0xf0b0-0xf0b7]
[    0.184605] pci 0000:00:1f.2: reg 0x14: [io  0xf0a0-0xf0a3]
[    0.184612] pci 0000:00:1f.2: reg 0x18: [io  0xf090-0xf097]
[    0.184618] pci 0000:00:1f.2: reg 0x1c: [io  0xf080-0xf083]
[    0.184624] pci 0000:00:1f.2: reg 0x20: [io  0xf060-0xf07f]
[    0.184631] pci 0000:00:1f.2: reg 0x24: [mem 0xf7121000-0xf71217ff]
[    0.184656] pci 0000:00:1f.2: PME# supported from D3hot
[    0.184754] pci 0000:00:1f.3: [8086:9ca2] type 00 class 0x0c0500
[    0.184768] pci 0000:00:1f.3: reg 0x10: [mem 0xf7120000-0xf71200ff 64bit]
[    0.184786] pci 0000:00:1f.3: reg 0x20: [io  0xf040-0xf05f]
[    0.184969] acpiphp: Slot [1] registered
[    0.184983] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.185377] pci 0000:02:00.0: [8086:095a] type 00 class 0x028000
[    0.185587] pci 0000:02:00.0: reg 0x10: [mem 0xf7000000-0xf7001fff 64bit]
[    0.186030] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    0.188483] pci 0000:00:1c.3: PCI bridge to [bus 02]
[    0.188498] pci 0000:00:1c.3:   bridge window [mem 0xf7000000-0xf70fffff]
[    0.190405] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
[    0.190484] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190561] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190637] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190712] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190788] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190863] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.190939] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.191567] ACPI: Enabled 4 GPEs in block 00 to 7F
[    0.191643] ACPI : EC: GPE = 0xa, I/O: command/status = 0x66, data = 0x62
[    0.191741] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.191744] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.191750] vgaarb: loaded
[    0.191753] vgaarb: bridge control possible 0000:00:02.0
[    0.191869] PCI: Using ACPI for IRQ routing
[    0.193137] PCI: pci_cache_line_size set to 64 bytes
[    0.193460] e820: reserve RAM buffer [mem 0x00058000-0x0005ffff]
[    0.193462] e820: reserve RAM buffer [mem 0x0009e000-0x0009ffff]
[    0.193463] e820: reserve RAM buffer [mem 0xb9583000-0xbbffffff]
[    0.193464] e820: reserve RAM buffer [mem 0xbce17000-0xbfffffff]
[    0.193466] e820: reserve RAM buffer [mem 0xbd2d5000-0xbfffffff]
[    0.193467] e820: reserve RAM buffer [mem 0xbefff000-0xbfffffff]
[    0.193468] e820: reserve RAM buffer [mem 0x21f000000-0x21fffffff]
[    0.193601] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.193608] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.195647] clocksource: Switched to clocksource hpet
[    0.195780] pnp: PnP ACPI init
[    0.195977] system 00:00: [io  0x0240-0x0259] has been reserved
[    0.195983] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.196069] pnp 00:01: Plug and Play ACPI device, IDs ETD0105 SYN0a00 SYN0002 PNP0f03 PNP0f13 PNP0f12 (active)
[    0.196110] pnp 00:02: Plug and Play ACPI device, IDs ATK3001 PNP030b (active)
[    0.196209] system 00:03: [io  0x0680-0x069f] has been reserved
[    0.196213] system 00:03: [io  0xffff] has been reserved
[    0.196217] system 00:03: [io  0xffff] has been reserved
[    0.196221] system 00:03: [io  0xffff] has been reserved
[    0.196226] system 00:03: [io  0x1800-0x18fe] could not be reserved
[    0.196230] system 00:03: [io  0x164e-0x164f] has been reserved
[    0.196235] system 00:03: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.196287] pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.196328] system 00:05: [io  0x1854-0x1857] has been reserved
[    0.196333] system 00:05: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
[    0.196564] system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.196569] system 00:06: [mem 0xfed10000-0xfed17fff] has been reserved
[    0.196574] system 00:06: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.196578] system 00:06: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.196582] system 00:06: [mem 0xf8000000-0xfbffffff] has been reserved
[    0.196586] system 00:06: [mem 0xfed20000-0xfed3ffff] has been reserved
[    0.196591] system 00:06: [mem 0xfed90000-0xfed93fff] has been reserved
[    0.196595] system 00:06: [mem 0xfed45000-0xfed8ffff] could not be reserved
[    0.196599] system 00:06: [mem 0xff000000-0xffffffff] has been reserved
[    0.196604] system 00:06: [mem 0xfee00000-0xfeefffff] could not be reserved
[    0.196608] system 00:06: [mem 0xf7fe0000-0xf7feffff] has been reserved
[    0.196613] system 00:06: [mem 0xf7ff0000-0xf7ffffff] has been reserved
[    0.196618] system 00:06: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.197229] system 00:07: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.197913] pnp: PnP ACPI: found 8 devices
[    0.203946] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.203987] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.204001] pci 0000:00:1c.3: PCI bridge to [bus 02]
[    0.204007] pci 0000:00:1c.3:   bridge window [mem 0xf7000000-0xf70fffff]
[    0.204020] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.204022] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.204023] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.204024] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000c3fff window]
[    0.204026] pci_bus 0000:00: resource 8 [mem 0x000c4000-0x000c7fff window]
[    0.204027] pci_bus 0000:00: resource 9 [mem 0x000c8000-0x000cbfff window]
[    0.204028] pci_bus 0000:00: resource 10 [mem 0x000cc000-0x000cffff window]
[    0.204030] pci_bus 0000:00: resource 11 [mem 0x000d0000-0x000d3fff window]
[    0.204031] pci_bus 0000:00: resource 12 [mem 0x000d4000-0x000d7fff window]
[    0.204032] pci_bus 0000:00: resource 13 [mem 0x000d8000-0x000dbfff window]
[    0.204034] pci_bus 0000:00: resource 14 [mem 0x000dc000-0x000dffff window]
[    0.204035] pci_bus 0000:00: resource 15 [mem 0xe0000000-0xfeafffff window]
[    0.204037] pci_bus 0000:02: resource 1 [mem 0xf7000000-0xf70fffff]
[    0.204085] NET: Registered protocol family 2
[    0.204256] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    0.204389] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.204506] TCP: Hash tables configured (established 65536 bind 65536)
[    0.204532] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    0.204559] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    0.204618] NET: Registered protocol family 1
[    0.204632] pci 0000:00:02.0: Video device with shadowed ROM
[    0.204830] PCI: CLS 64 bytes, default 64
[    0.204870] Unpacking initramfs...
[    0.298706] Freeing initrd memory: 5276K (ffff8800375a2000 - ffff880037ac9000)
[    0.298739] DMAR: Host address width 39
[    0.298743] DMAR: DRHD base: 0x000000fed90000 flags: 0x0
[    0.298759] DMAR: dmar0: reg_base_addr fed90000 ver 1:0 cap 1c0000c40660462 ecap 7e1ff0505e
[    0.298765] DMAR: DRHD base: 0x000000fed91000 flags: 0x1
[    0.298775] DMAR: dmar1: reg_base_addr fed91000 ver 1:0 cap d2008c20660462 ecap f010da
[    0.298780] DMAR: RMRR base: 0x000000bee3d000 end: 0x000000bee4bfff
[    0.298784] DMAR: RMRR base: 0x000000bf800000 end: 0x000000dfffffff
[    0.298795] DMAR: No ATSR found
[    0.298904] DMAR: dmar1: Using Queued invalidation
[    0.298919] DMAR: Setting RMRR:
[    0.298939] DMAR: Setting identity map for device 0000:00:14.0 [0xbee3d000 - 0xbee4bfff]
[    0.298962] DMAR: Prepare 0-16MiB unity mapping for LPC
[    0.298974] DMAR: Setting identity map for device 0000:00:1f.0 [0x0 - 0xffffff]
[    0.299016] DMAR: Intel(R) Virtualization Technology for Directed I/O
[    0.299063] iommu: Adding device 0000:00:00.0 to group 0
[    0.299077] iommu: Adding device 0000:00:03.0 to group 1
[    0.299089] iommu: Adding device 0000:00:04.0 to group 2
[    0.299100] iommu: Adding device 0000:00:14.0 to group 3
[    0.299113] iommu: Adding device 0000:00:16.0 to group 4
[    0.299123] iommu: Adding device 0000:00:1b.0 to group 5
[    0.299135] iommu: Adding device 0000:00:1c.0 to group 6
[    0.299147] iommu: Adding device 0000:00:1c.3 to group 7
[    0.299163] iommu: Adding device 0000:00:1f.0 to group 8
[    0.299173] iommu: Adding device 0000:00:1f.2 to group 8
[    0.299183] iommu: Adding device 0000:00:1f.3 to group 8
[    0.299194] iommu: Adding device 0000:02:00.0 to group 9
[    0.301039] RAPL PMU detected, API unit is 2^-32 Joules, 4 fixed counters 655360 ms ovfl timer
[    0.301046] hw unit of domain pp0-core 2^-14 Joules
[    0.301049] hw unit of domain package 2^-14 Joules
[    0.301052] hw unit of domain dram 2^-14 Joules
[    0.301055] hw unit of domain pp1-gpu 2^-14 Joules
[    0.301145] microcode: CPU0 sig=0x306d4, pf=0x80, revision=0x1f
[    0.301157] microcode: CPU1 sig=0x306d4, pf=0x80, revision=0x1f
[    0.301166] microcode: CPU2 sig=0x306d4, pf=0x80, revision=0x1f
[    0.301176] microcode: CPU3 sig=0x306d4, pf=0x80, revision=0x1f
[    0.301233] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    0.301289] simple-framebuffer simple-framebuffer.0: framebuffer at 0xe0000000, 0x7e9000 bytes, mapped to 0xffffc90001000000
[    0.301296] simple-framebuffer simple-framebuffer.0: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    0.306188] Console: switching to colour frame buffer device 240x67
[    0.310998] simple-framebuffer simple-framebuffer.0: fb0: simplefb registered!
[    0.311229] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.311287] audit: initializing netlink subsys (disabled)
[    0.311324] audit: type=2000 audit(1446141151.302:1): initialized
[    0.311691] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.311741] zbud: loaded
[    0.311915] VFS: Disk quotas dquot_6.6.0
[    0.311951] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.312388] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.312445] io scheduler noop registered (default)
[    0.313139] pcieport 0000:00:1c.0: Signaling PME through PCIe PME interrupt
[    0.313183] pcie_pme 0000:00:1c.0:pcie01: service driver pcie_pme loaded
[    0.313208] pcieport 0000:00:1c.3: Signaling PME through PCIe PME interrupt
[    0.313240] pci 0000:02:00.0: Signaling PME through PCIe PME interrupt
[    0.313279] pcie_pme 0000:00:1c.3:pcie01: service driver pcie_pme loaded
[    0.313287] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.313320] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.313373] intel_idle: MWAIT substates: 0x11142120
[    0.313374] intel_idle: v0.4 model 0x3D
[    0.313375] intel_idle: lapic_timer_reliable_states 0xffffffff
[    0.313774] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.314561] i8042: PNP: PS/2 Controller [PNP030b:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[    0.318176] i8042: Detected active multiplexing controller, rev 1.1
[    0.319637] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.319685] serio: i8042 AUX0 port at 0x60,0x64 irq 12
[    0.319729] serio: i8042 AUX1 port at 0x60,0x64 irq 12
[    0.319770] serio: i8042 AUX2 port at 0x60,0x64 irq 12
[    0.319811] serio: i8042 AUX3 port at 0x60,0x64 irq 12
[    0.319964] mousedev: PS/2 mouse device common for all mice
[    0.320033] rtc_cmos 00:04: RTC can wake from S4
[    0.320193] rtc_cmos 00:04: rtc core: registered rtc_cmos as rtc0
[    0.320242] rtc_cmos 00:04: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[    0.320281] Intel P-state driver initializing.
[    0.321511] NET: Registered protocol family 10
[    0.322082] mip6: Mobile IPv6
[    0.322132] NET: Registered protocol family 17
[    0.322192] mpls_gso: MPLS GSO support
[    0.322667] registered taskstats version 1
[    0.322758] zswap: loaded using pool lzo/zbud
[    0.324084] rtc_cmos 00:04: setting system clock to 2015-10-29 17:52:32 UTC (1446141152)
[    0.325172] Freeing unused kernel memory: 1564K (ffffffff818eb000 - ffffffff81a72000)
[    0.325254] Write protecting the kernel read-only data: 8192k
[    0.326352] Freeing unused kernel memory: 1540K (ffff88000167f000 - ffff880001800000)
[    0.326686] Freeing unused kernel memory: 368K (ffff8800019a4000 - ffff880001a00000)
[    0.359237] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.365581] systemd-udevd[75]: starting version 215
[    0.366868] random: systemd-udevd urandom read with 5 bits of entropy available
[    0.383436] thermal LNXTHERM:00: registered as thermal_zone0
[    0.384790] ACPI: Thermal Zone [THRM] (50 C)
[    0.386428] SCSI subsystem initialized
[    0.386726] thermal LNXTHERM:01: registered as thermal_zone1
[    0.386727] ACPI: Thermal Zone [TZ00] (28 C)
[    0.387000] thermal LNXTHERM:02: registered as thermal_zone2
[    0.387001] ACPI: Thermal Zone [TZ01] (30 C)
[    0.397927] libata version 3.00 loaded.
[    0.398241] AVX2 version of gcm_enc/dec engaged.
[    0.399431] AES CTR mode by8 optimization enabled
[    0.402127] ahci 0000:00:1f.2: version 3.0
[    0.412642] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x1 impl SATA mode
[    0.414247] ahci 0000:00:1f.2: flags: 64bit ncq pm led clo only pio slum part deso sadm sds apst 
[    0.416070] scsi host0: ahci
[    0.418395] scsi host1: ahci
[    0.420653] ata1: SATA max UDMA/133 abar m2048@0xf7121000 port 0xf7121100 irq 43
[    0.422870] ata2: DUMMY
[    0.729657] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    0.732647] ata1.00: ATA-10: Micron_M600_MTFDDAV256MBF, MA01, max UDMA/133
[    0.734587] ata1.00: 500118192 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    0.739245] ata1.00: configured for UDMA/133
[    0.741088] scsi 0:0:0:0: Direct-Access     ATA      Micron_M600_MTFD MA01 PQ: 0 ANSI: 5
[    0.744066] ata1.00: Enabling discard_zeroes_data
[    0.745804] sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[    0.747026] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    0.747992] sd 0:0:0:0: [sda] Write Protect is off
[    0.748931] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    0.748941] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.750082] ata1.00: Enabling discard_zeroes_data
[    0.752756]  sda: sda1 sda2 sda3
[    0.754407] ata1.00: Enabling discard_zeroes_data
[    0.755324] sd 0:0:0:0: [sda] Attached SCSI disk
[    0.797449] device-mapper: uevent: version 1.0.3
[    0.798496] device-mapper: ioctl: 4.33.0-ioctl (2015-8-18) initialised: dm-devel@redhat.com
[    1.300543] tsc: Refined TSC clocksource calibration: 1396.766 MHz
[    1.300549] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1422315cd49, max_idle_ns: 440795270477 ns
[    2.300517] clocksource: Switched to clocksource tsc
[    5.877338] ACPI : EC: ===== IRQ (0) =====
[    5.877349] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    5.877351] ACPI : EC: ##### Command(QR_EC) submitted/blocked #####
[    5.877359] ACPI : EC: ##### Event started #####
[    5.877361] ACPI : EC: 2: Increase command
[    5.877362] ACPI : EC: ***** Command(QR_EC) started *****
[    5.877363] ACPI : EC: ===== TASK (0) =====
[    5.877371] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    5.877373] ACPI : EC: EC_SC(W) = 0x84
[    5.877435] ACPI : EC: ===== IRQ (0) =====
[    5.877443] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    5.877455] ACPI : EC: ===== IRQ (0) =====
[    5.877463] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    5.877703] ACPI : EC: ===== TASK (0) =====
[    5.877711] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    5.878094] ACPI : EC: ===== IRQ (0) =====
[    5.878101] ACPI : EC: EC_SC(R) = 0x29 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=1
[    5.878105] ACPI : EC: EC_DATA(R) = 0xb2
[    5.878106] ACPI : EC: ##### Command(QR_EC) unblocked #####
[    5.878107] ACPI : EC: ##### Command(QR_EC) completed by hardware #####
[    5.878108] ACPI : EC: ##### Command(QR_EC) submitted/blocked #####
[    5.878116] ACPI : EC: ***** Command(QR_EC) stopped *****
[    5.878117] ACPI : EC: 1: Decrease command
[    5.878123] ACPI : EC: ===== IRQ (0) =====
[    5.878131] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    5.878135] ACPI : EC: 2: Increase command
[    5.878136] ACPI : EC: ***** Command(QR_EC) started *****
[    5.878138] ACPI : EC: ===== TASK (0) =====
[    5.878145] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    5.878147] ACPI : EC: EC_SC(W) = 0x84
[    5.878226] ACPI : EC: ===== IRQ (0) =====
[    5.878234] ACPI : EC: EC_SC(R) = 0x09 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=1
[    5.878238] ACPI : EC: EC_DATA(R) = 0xb2
[    5.878239] ACPI : EC: ##### Command(QR_EC) unblocked #####
[    5.878240] ACPI : EC: ##### Command(QR_EC) completed by hardware #####
[    5.878246] ACPI : EC: ***** Command(QR_EC) stopped *****
[    5.878247] ACPI : EC: 1: Decrease command
[    5.878248] ACPI : EC: ##### Event stopped #####
[    5.878250] ACPI : EC: ##### Event started #####
[    5.878251] ACPI : EC: ##### Event stopped #####
[    6.638620] PM: Starting manual resume from disk
[    6.683786] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
[    6.831372] random: nonblocking pool is initialized
[    6.875897] systemd-udevd[698]: starting version 215
[    6.925045] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input5
[    6.927127] ACPI: Lid Switch [LID]
[    6.929860] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input6
[    6.931294] ACPI: Sleep Button [SLPB]
[    6.933469] ACPI: bus type USB registered
[    6.935697] usbcore: registered new interface driver usbfs
[    6.935759] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input7
[    6.935762] ACPI: Power Button [PWRF]
[    6.940984] usbcore: registered new interface driver hub
[    6.942778] usbcore: registered new device driver usb
[    6.955265] hidraw: raw HID events driver (C) Jiri Kosina
[    6.957613] ACPI: AC Adapter [AC0] (off-line)
[    6.963255] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    6.965423] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1
[    6.967613] xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x00009810
[    6.969723] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported
[    6.971086] ACPI : EC: 2: Increase command
[    6.971088] ACPI : EC: ***** Command(BE_EC) started *****
[    6.971089] ACPI : EC: ===== TASK (3) =====
[    6.971096] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.971097] ACPI : EC: EC_SC(W) = 0x82
[    6.971173] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    6.971202] ACPI : EC: ===== IRQ (0) =====
[    6.971210] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    6.971213] ACPI : EC: EC_DATA(R) = 0x90
[    6.971225] ACPI : EC: ===== IRQ (0) =====
[    6.971233] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.972173] ACPI : EC: ===== IRQ (0) =====
[    6.972181] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.972191] ACPI : EC: ===== IRQ (0) =====
[    6.972199] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.973067] ACPI : EC: ***** Command(BE_EC) stopped *****
[    6.973068] ACPI : EC: 1: Decrease command
[    6.973068] ACPI : EC: 2: Increase command
[    6.973069] ACPI : EC: ***** Command(RD_EC) started *****
[    6.973069] ACPI : EC: ===== TASK (3) =====
[    6.973077] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.973078] ACPI : EC: EC_SC(W) = 0x80
[    6.973363] ACPI : EC: ===== IRQ (0) =====
[    6.973372] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.973374] ACPI : EC: EC_DATA(W) = 0xc4
[    6.973386] ACPI : EC: ===== IRQ (0) =====
[    6.973394] ACPI : EC: EC_SC(R) = 0x01 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=1
[    6.973395] [drm] Initialized drm 1.1.0 20060810
[    6.973784] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.973786] usb usb1: Product: xHCI Host Controller
[    6.973787] usb usb1: Manufacturer: Linux 4.3.0-rc7 xhci-hcd
[    6.973789] usb usb1: SerialNumber: 0000:00:14.0
[    6.974602] hub 1-0:1.0: USB hub found
[    6.974615] hub 1-0:1.0: 11 ports detected
[    6.981036] ACPI : EC: EC_DATA(R) = 0x45
[    6.981048] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.981051] ACPI : EC: 1: Decrease command
[    6.981053] ACPI : EC: ===== IRQ (2) =====
[    6.981063] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.981065] ACPI : EC: 2: Increase command
[    6.981066] ACPI : EC: ***** Command(RD_EC) started *****
[    6.981067] ACPI : EC: ===== TASK (3) =====
[    6.981074] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.981076] ACPI : EC: EC_SC(W) = 0x80
[    6.981177] ACPI : EC: ===== IRQ (3) =====
[    6.981185] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.981186] ACPI : EC: EC_DATA(W) = 0xc5
[    6.981200] ACPI : EC: ===== IRQ (3) =====
[    6.981208] ACPI : EC: EC_SC(R) = 0x01 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=1
[    6.981211] ACPI : EC: EC_DATA(R) = 0x00
[    6.981216] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.981218] ACPI : EC: 1: Decrease command
[    6.981222] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.981226] ACPI : EC: ===== IRQ (0) =====
[    6.981234] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.986501] sdhci: Secure Digital Host Controller Interface driver
[    6.987390] ACPI: Battery Slot [BAT0] (battery present)
[    6.990286] sdhci: Copyright(c) Pierre Ossman
[    6.994310] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    6.996203] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    6.998283] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    7.000331] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.003088] wmi: Mapper loaded
[    7.005086] usb usb2: Product: xHCI Host Controller
[    7.006493] usb usb2: Manufacturer: Linux 4.3.0-rc7 xhci-hcd
[    7.007551] usb usb2: SerialNumber: 0000:00:14.0
[    7.008712] hub 2-0:1.0: USB hub found
[    7.008717] EFI Variables Facility v0.08 2004-May-17
[    7.011737] hub 2-0:1.0: 4 ports detected
[    7.013722] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    7.016243] ACPI Warning: SystemIO range 0x000000000000F040-0x000000000000F05F conflicts with OpRegion 0x000000000000F040-0x000000000000F04F (\SMB0) (20150818/utaddress-254)
[    7.018289] ACPI Warning: SystemIO range 0x000000000000F040-0x000000000000F05F conflicts with OpRegion 0x000000000000F040-0x000000000000F04F (\_SB_.PCI0.SBUS.SMBI) (20150818/utaddress-254)
[    7.020567] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    7.025076] input: PC Speaker as /devices/platform/pcspkr/input/input9
[    7.029800] i915: unknown parameter 'lvds_downclock' ignored
[    7.031869] Error: Driver 'pcspkr' is already registered, aborting...
[    7.033718] Intel(R) Wireless WiFi driver for Linux
[    7.035805] [drm] Memory usable by graphics device = 4096M
[    7.035808] checking generic (e0000000 7e9000) vs hw (e0000000 10000000)
[    7.035810] fb: switching to inteldrmfb from simple
[    7.039986] Copyright(c) 2003- 2015 Intel Corporation
[    7.040013] Console: switching to colour dummy device 80x25
[    7.040183] [drm] Replacing VGA console driver
[    7.041944] snd_hda_codec_conexant hdaudioC0D0: CX20751/2: BIOS auto-probing.
[    7.042257] snd_hda_codec_conexant hdaudioC0D0: autoconfig for CX20751/2: line_outs=1 (0x17/0x0/0x0/0x0/0x0) type:speaker
[    7.042267] snd_hda_codec_conexant hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    7.042275] snd_hda_codec_conexant hdaudioC0D0:    hp_outs=1 (0x16/0x0/0x0/0x0/0x0)
[    7.042282] snd_hda_codec_conexant hdaudioC0D0:    mono: mono_out=0x0
[    7.042287] snd_hda_codec_conexant hdaudioC0D0:    inputs:
[    7.042292] snd_hda_codec_conexant hdaudioC0D0:      Internal Mic=0x1a
[    7.042299] snd_hda_codec_conexant hdaudioC0D0:      Mic=0x19
[    7.043269] snd_hda_codec_conexant hdaudioC0D0: Enable sync_write for stable communication
[    7.043333] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-7265D-17.ucode failed with error -2
[    7.043351] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-7265D-16.ucode failed with error -2
[    7.043365] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-7265D-15.ucode failed with error -2
[    7.043379] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-7265D-14.ucode failed with error -2
[    7.043393] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-7265D-13.ucode failed with error -2
[    7.047697] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    7.047706] [drm] Driver supports precise vblank timestamp query.
[    7.047851] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
[    7.048549] iwlwifi 0000:02:00.0: loaded firmware version 25.16.12.0 op_mode iwlmvm
[    7.053658] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10
[    7.053800] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[    7.053843] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12
[    7.061650] iwlwifi 0000:02:00.0: Detected Intel(R) Dual Band Wireless AC 7265, REV=0x210
[    7.061990] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[    7.062441] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[    7.063010] [drm:gen8_irq_handler [i915]] *ERROR* The master control interrupt lied (SDE)!
[    7.064407] fbcon: inteldrmfb (fb0) is primary device
[    7.069044] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    7.072723] asus_wmi: ASUS WMI generic driver loaded
[    7.074069] asus_wmi: Initialization: 0x1
[    7.074512] asus_wmi: BIOS WMI version: 7.9
[    7.074665] asus_wmi: SFUN value: 0xa2077
[    7.074958] intel_rapl: Found RAPL domain package
[    7.074960] intel_rapl: Found RAPL domain core
[    7.074961] intel_rapl: Found RAPL domain uncore
[    7.074963] intel_rapl: Found RAPL domain dram
[    7.075549] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input15
[    7.077155] input: Asus WMI hotkeys as /devices/platform/asus-nb-wmi/input/input16
[    7.080962] ACPI : EC: 2: Increase command
[    7.080963] ACPI : EC: ***** Command(BE_EC) started *****
[    7.080965] ACPI : EC: ===== TASK (0) =====
[    7.080972] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    7.080973] ACPI : EC: EC_SC(W) = 0x82
[    7.081077] ACPI : EC: ===== IRQ (0) =====
[    7.081085] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    7.081088] ACPI : EC: EC_DATA(R) = 0x90
[    7.081174] ACPI : EC: ===== IRQ (0) =====
[    7.081182] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.081302] ACPI : EC: ***** Command(BE_EC) stopped *****
[    7.081303] ACPI : EC: 1: Decrease command
[    7.081304] ACPI : EC: 2: Increase command
[    7.081304] ACPI : EC: ***** Command(RD_EC) started *****
[    7.081305] ACPI : EC: ===== TASK (0) =====
[    7.081312] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.081313] ACPI : EC: EC_SC(W) = 0x80
[    7.081352] ACPI : EC: ===== IRQ (1) =====
[    7.081361] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.081361] ACPI : EC: EC_DATA(W) = 0x93
[    7.081450] ACPI : EC: ===== IRQ (0) =====
[    7.081457] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    7.081460] ACPI : EC: EC_DATA(R) = 0x00
[    7.081485] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.081485] ACPI : EC: 1: Decrease command
[    7.081486] ACPI : EC: 2: Increase command
[    7.081487] ACPI : EC: ***** Command(RD_EC) started *****
[    7.081487] ACPI : EC: ===== TASK (0) =====
[    7.081495] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.081495] ACPI : EC: EC_SC(W) = 0x80
[    7.081534] ACPI : EC: ===== IRQ (0) =====
[    7.081541] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.081542] ACPI : EC: EC_DATA(W) = 0x94
[    7.081630] ACPI : EC: ===== IRQ (0) =====
[    7.081637] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    7.081640] ACPI : EC: EC_DATA(R) = 0x00
[    7.081899] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.081900] ACPI : EC: 1: Decrease command
[    7.081905] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.081905] ACPI : EC: 2: Increase command
[    7.081906] ACPI : EC: ***** Command(BD_EC) started *****
[    7.081907] ACPI : EC: ===== TASK (0) =====
[    7.081913] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.081914] ACPI : EC: EC_SC(W) = 0x83
[    7.081951] ACPI : EC: ===== IRQ (0) =====
[    7.081959] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.082057] ACPI : EC: ===== IRQ (0) =====
[    7.082064] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.082206] ACPI : EC: ***** Command(BD_EC) stopped *****
[    7.082207] ACPI : EC: 1: Decrease command
[    7.083074] asus_wmi: Number of fans: 1
[    7.087682] ACPI : EC: 2: Increase command
[    7.087683] ACPI : EC: ***** Command(BE_EC) started *****
[    7.087684] ACPI : EC: ===== TASK (2) =====
[    7.087692] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.087693] ACPI : EC: EC_SC(W) = 0x82
[    7.087892] ACPI : EC: ===== IRQ (2) =====
[    7.087900] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    7.087902] ACPI : EC: EC_DATA(R) = 0x90
[    7.087909] ACPI : EC: ***** Command(BE_EC) stopped *****
[    7.087910] ACPI : EC: 1: Decrease command
[    7.087910] ACPI : EC: 2: Increase command
[    7.087911] ACPI : EC: ***** Command(RD_EC) started *****
[    7.087911] ACPI : EC: ===== TASK (2) =====
[    7.087997] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.087998] ACPI : EC: EC_SC(W) = 0x80
[    7.088001] ACPI : EC: ===== IRQ (3) =====
[    7.088007] ACPI : EC: EC_SC(R) = 0x1a SCI_EVT=0 BURST=1 CMD=1 IBF=1 OBF=0
[    7.088036] ACPI : EC: ===== IRQ (2) =====
[    7.088045] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.088045] ACPI : EC: EC_DATA(W) = 0x93
[    7.088135] ACPI : EC: ===== IRQ (2) =====
[    7.088141] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    7.088144] ACPI : EC: EC_DATA(R) = 0x00
[    7.088149] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.088150] ACPI : EC: 1: Decrease command
[    7.088150] ACPI : EC: 2: Increase command
[    7.088151] ACPI : EC: ***** Command(RD_EC) started *****
[    7.088152] ACPI : EC: ===== TASK (2) =====
[    7.088159] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.088160] ACPI : EC: EC_SC(W) = 0x80
[    7.088197] ACPI : EC: ===== IRQ (2) =====
[    7.088204] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.088205] ACPI : EC: EC_DATA(W) = 0x94
[    7.088293] ACPI : EC: ===== IRQ (2) =====
[    7.088300] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    7.088303] ACPI : EC: EC_DATA(R) = 0x00
[    7.088307] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.088308] ACPI : EC: 1: Decrease command
[    7.088315] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.088315] ACPI : EC: 2: Increase command
[    7.088316] ACPI : EC: ***** Command(BD_EC) started *****
[    7.088316] ACPI : EC: ===== TASK (2) =====
[    7.088322] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    7.088322] ACPI : EC: EC_SC(W) = 0x83
[    7.088362] ACPI : EC: ===== IRQ (2) =====
[    7.088369] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.088373] ACPI : EC: ***** Command(BD_EC) stopped *****
[    7.088374] ACPI : EC: 1: Decrease command
[    7.088458] ACPI : EC: ===== IRQ (1) =====
[    7.088465] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.117132] hpet1: lost 1 rtc interrupts
[    7.119963] ACPI : EC: 2: Increase command
[    7.119964] ACPI : EC: ***** Command(BE_EC) started *****
[    7.119964] ACPI : EC: ===== TASK (2) =====
[    7.119972] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.119973] ACPI : EC: EC_SC(W) = 0x82
[    7.120177] ACPI : EC: ===== IRQ (2) =====
[    7.120184] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    7.120187] ACPI : EC: EC_DATA(R) = 0x90
[    7.120193] ACPI : EC: ***** Command(BE_EC) stopped *****
[    7.120194] ACPI : EC: 1: Decrease command
[    7.120194] ACPI : EC: 2: Increase command
[    7.120195] ACPI : EC: ***** Command(RD_EC) started *****
[    7.120196] ACPI : EC: ===== TASK (2) =====
[    7.120278] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.120278] ACPI : EC: EC_SC(W) = 0x80
[    7.120497] ACPI : EC: ===== TASK (2) =====
[    7.120505] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    7.120506] ACPI : EC: EC_DATA(W) = 0x93
[    7.121704] ACPI : EC: ===== TASK (2) =====
[    7.121713] ACPI : EC: EC_SC(R) = 0x01 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=1
[    7.121716] ACPI : EC: EC_DATA(R) = 0x00
[    7.121717] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.121718] ACPI : EC: 1: Decrease command
[    7.121718] ACPI : EC: 2: Increase command
[    7.121719] ACPI : EC: ***** Command(RD_EC) started *****
[    7.121720] ACPI : EC: ===== TASK (2) =====
[    7.121727] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    7.121728] ACPI : EC: EC_SC(W) = 0x80
[    7.122496] ACPI : EC: ===== TASK (2) =====
[    7.122505] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.122505] ACPI : EC: EC_DATA(W) = 0x94
[    7.122625] ACPI : EC: ===== IRQ (0) =====
[    7.122632] ACPI : EC: EC_SC(R) = 0x01 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=1
[    7.122634] ACPI : EC: EC_DATA(R) = 0x00
[    7.122637] ACPI : EC: ***** Command(RD_EC) stopped *****
[    7.122637] ACPI : EC: 1: Decrease command
[    7.122640] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    7.220109] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[    7.363619] cfg80211: World regulatory domain updated:
[    7.363621] cfg80211:  DFS Master region: unset
[    7.363621] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[    7.363623] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    7.363624] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    7.363625] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[    7.363626] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    7.363628] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[    7.363629] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[    7.363630] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[    7.363631] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[    7.369469] usb 1-4: new full-speed USB device number 2 using xhci_hcd
[    7.384497] ahci 0000:00:1f.2: port does not support device sleep
[    7.420563] psmouse serio4: elantech: assuming hardware version 4 (with firmware version 0x381f00)
[    7.435500] psmouse serio4: elantech: Synaptics capabilities query result 0x10, 0x14, 0x0e.
[    7.449112] psmouse serio4: elantech: Elan sample query result 00, 23, 64
[    7.518111] input: ETPS/2 Elantech Touchpad as /devices/platform/i8042/serio4/input/input17
[    7.535306] usb 1-4: New USB device found, idVendor=8087, idProduct=0a2a
[    7.535308] usb 1-4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    7.688485] usb 1-5: new high-speed USB device number 3 using xhci_hcd
[    7.880020] usb 1-5: New USB device found, idVendor=064e, idProduct=9700
[    7.880022] usb 1-5: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[    7.880024] usb 1-5: Product: USB2.0 UVC HD Webcam
[    7.880025] usb 1-5: Manufacturer: SuYin
[    7.880027] usb 1-5: SerialNumber: HF1019-T838-SN03-Rev0101
[    7.902350] ACPI : EC: ===== IRQ (3) =====
[    7.902362] ACPI : EC: EC_SC(R) = 0x20 SCI_EVT=1 BURST=0 CMD=0 IBF=0 OBF=0
[    7.902363] ACPI : EC: ##### Command(QR_EC) submitted/blocked #####
[    7.902380] ACPI : EC: ##### Event started #####
[    7.902382] ACPI : EC: 2: Increase command
[    7.902383] ACPI : EC: ***** Command(QR_EC) started *****
[    7.902384] ACPI : EC: ===== TASK (3) =====
[    7.902392] ACPI : EC: EC_SC(R) = 0x20 SCI_EVT=1 BURST=0 CMD=0 IBF=0 OBF=0
[    7.902393] ACPI : EC: EC_SC(W) = 0x84
[    7.903399] ACPI : EC: ===== TASK (3) =====
[    7.903408] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    7.903782] ACPI : EC: ===== IRQ (2) =====
[    7.903793] ACPI : EC: EC_SC(R) = 0x09 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=1
[    7.903796] ACPI : EC: EC_DATA(R) = 0xac
[    7.903797] ACPI : EC: ##### Command(QR_EC) unblocked #####
[    7.903798] ACPI : EC: ##### Command(QR_EC) completed by hardware #####
[    7.903858] ACPI : EC: ***** Command(QR_EC) stopped *****
[    7.903859] ACPI : EC: 1: Decrease command
[    7.903861] ACPI : EC: ##### Query(0xac) scheduled #####
[    7.903863] ACPI : EC: ##### Event stopped #####
[    7.903864] ACPI : EC: ##### Query(0xac) started #####
[    7.903882]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.903915]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.903926]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.904022]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.904090]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.904219]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002e16e:\ISMI] execution.
[    7.904967]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002e16e:\ISMI] execution.
[    7.904976]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.904998]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.905041]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.905045] ACPI : EC: ##### Query(0xac) stopped #####
Comment 19 doa379 2015-10-29 18:09:03 UTC
Created attachment 191491 [details]
Linux 4.3-rc7 EC-Debug dmesg output

Thanks for the update. I have attached a more specific dmesg output together with CONFIG_ACPI_DEBUG=y option set in the .config spec. file prior to compilation.

Ready to carry out all further proposed testing.
Comment 20 Lv Zheng 2015-10-30 06:47:53 UTC
Only when ECAV() returns true and EDA1 returns 1 after executing ECAC(), the battery driver can get notified.

From the above log, I can confirm that ECAV() has returned true.
Now you need to use the following command line:
 acpi.trace_state=opcode acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC
to boot the kernel again and upload the dmesg output.

Thanks and best regards
-Lv
Comment 21 Lv Zheng 2015-10-30 06:59:51 UTC
These log entries tell us how many control methods are excuted during _QAC's evaluation:
[    7.903864] ACPI : EC: ##### Query(0xac) started #####
[    7.903882]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.903915]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.903926]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.904022]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.904090]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.904219]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002e16e:\ISMI] execution.
[    7.904967]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002e16e:\ISMI] execution.
[    7.904976]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.904998]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.905041]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.905045] ACPI : EC: ##### Query(0xac) stopped #####
It executes ST8E(), inside of which, ECAV() is executed, and since we can see ECAC() is executed, ECAV() should have returned true. We can also see ISMI() is executed inside of ECAC().

Using acpi.trace_state=opcode, we can see trace log entries about not only control methods, but also opcodes, so the log will be more detailed and thus its quantity will be much larger than this. The opcode tracing is always used after tracing method executions so that we could have chance to limit the output quantity using filtered call paths.

Thanks and best regards
-Lv
Comment 22 doa379 2015-10-30 19:49:46 UTC
Created attachment 191651 [details]
Linux 4.3-rc7 EC-Debug dmesg with opcode trace output

Herein attached dmesg output update with opcode trace.
Comment 23 doa379 2015-10-31 09:58:11 UTC
[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-4.3.0-rc7 root=/dev/mapper/Debian-root ro kernel.nmi_watchdog=0 nmi_watchdog=0 intel_iommu=igfx_off rootfstype=ext4 acpi.trace_state=opcode acpi.trace_method_name=_SB.PCI0.LPCB.EC0._QAC log_buf_len=16M init=/lib/sysvinit/init
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000BD71B000 000024 (v02 _ASUS_)
[    0.000000] ACPI: XSDT 0x00000000BD71B0B0 0000DC (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FACP 0x00000000BD734478 00010C (v05 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: DSDT 0x00000000BD71B218 01925E (v02 _ASUS_ Notebook 01072009 INTL 20120913)
[    0.000000] ACPI: FACS 0x00000000BE411F80 000040
[    0.000000] ACPI: APIC 0x00000000BD734588 000084 (v03 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FPDT 0x00000000BD734610 000044 (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: FIDT 0x00000000BD734658 00009C (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: ECDT 0x00000000BD7346F8 0000C1 (v01 _ASUS_ Notebook 01072009 AMI. 00000005)
[    0.000000] ACPI: MCFG 0x00000000BD7347C0 00003C (v01 _ASUS_ Notebook 01072009 MSFT 00000097)
[    0.000000] ACPI: HPET 0x00000000BD734800 000038 (v01 _ASUS_ Notebook 01072009 AMI. 00000005)
[    0.000000] ACPI: SSDT 0x00000000BD734838 000315 (v01 SataRe SataTabl 00001000 INTL 20120913)
[    0.000000] ACPI: UEFI 0x00000000BD734B50 000042 (v01                 00000000      00000000)
[    0.000000] ACPI: SSDT 0x00000000BD734B98 000C7D (v02 Ther_R Ther_Rvp 00001000 INTL 20120913)
[    0.000000] ACPI: ASF! 0x00000000BD735818 0000A0 (v32 INTEL   HCG     00000001 TFSM 000F4240)
[    0.000000] ACPI: SSDT 0x00000000BD7358B8 000494 (v02 PmRef  Cpu0Ist  00003000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD735D50 000B74 (v02 CpuRef CpuSsdt  00003000 INTL 20120913)
[    0.000000] ACPI: TPM2 0x00000000BD7368C8 000034 (v03        Tpm2Tabl 00000001 AMI  00000000)
[    0.000000] ACPI: SSDT 0x00000000BD736900 000041 (v01 Ssdt   PttSsdt  00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD736948 0037ED (v02 DptfTa DptfTabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73A138 000394 (v02 CppcTa CppcTabl 00001000 INTL 20120913)
[    0.000000] ACPI: PCCT 0x00000000BD73A4D0 00006E (v05 PcctTa PcctTabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73A540 000AC4 (v02 Cpc_Ta Cpc_Tabl 00001000 INTL 20120913)
[    0.000000] ACPI: SSDT 0x00000000BD73B008 00667D (v02 SaSsdt SaSsdt   00003000 INTL 20120913)
[    0.000000] ACPI: DMAR 0x00000000BD741688 0000A8 (v01 INTEL  BDW      00000001 INTL 00000001)
[    0.000000] ACPI: BGRT 0x00000000BD741730 000038 (v01 _ASUS_ Notebook 01072009 AMI  00010013)
[    0.000000] ACPI: MSDM 0x00000000BCE74E18 000055 (v03 _ASUS_ Notebook 00000000 ASUS 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: PM-Timer IO Port: 0x1808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] res res lint[0x63])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] res dfl lint[0x96])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high level lint[0x8b])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0xd5])
[    0.000000] ACPI: NMI not connected to LINT 1!
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a701 base: 0xfed00000
[    0.122523] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.122533] ACPI: bus type PCI registered
[    0.128886] ACPI: Added _OSI(Module Device)
[    0.128894] ACPI: Added _OSI(Processor Device)
[    0.128900] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.128905] ACPI: Added _OSI(Processor Aggregator Device)
[    0.132848] ACPI : EC: EC description table is found, configuring boot EC
[    0.132855] ACPI : EC: +++++ Starting EC +++++
[    0.132864] ACPI : EC: 1: Increase driver
[    0.132865] ACPI : EC: +++++ EC started +++++
[    0.135762] ACPI: Executed 8 blocks of module-level executable AML code
[    0.144744] ACPI: Dynamic OEM Table Load:
[    0.144752] ACPI: SSDT 0xFFFF880214453800 0003D3 (v02 PmRef  Cpu0Cst  00003001 INTL 20120913)
[    0.145747] ACPI: Dynamic OEM Table Load:
[    0.145754] ACPI: SSDT 0xFFFF880214408000 0005AA (v02 PmRef  ApIst    00003000 INTL 20120913)
[    0.146821] ACPI: Dynamic OEM Table Load:
[    0.146827] ACPI: SSDT 0xFFFF88021443D600 000119 (v02 PmRef  ApCst    00003000 INTL 20120913)
[    0.148739] ACPI: Interpreter enabled
[    0.148749] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150818/hwxface-580)
[    0.148758] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150818/hwxface-580)
[    0.148773] ACPI: (supports S0 S3 S4 S5)
[    0.148776] ACPI: Using IOAPIC for interrupt routing
[    0.151180] ACPI: Power Resource [PG00] (on)
[    0.151654] ACPI: Power Resource [PG01] (on)
[    0.152119] ACPI: Power Resource [PG02] (on)
[    0.163424] ACPI: Power Resource [FN00] (off)
[    0.163503] ACPI: Power Resource [FN01] (off)
[    0.163584] ACPI: Power Resource [FN02] (off)
[    0.163659] ACPI: Power Resource [FN03] (off)
[    0.163735] ACPI: Power Resource [FN04] (off)
[    0.164559] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3e])
[    0.172597] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
[    0.172669] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.172736] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.172804] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.172870] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.172936] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.173003] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.173070] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 10 12) *0, disabled.
[    0.173631] ACPI: Enabled 4 GPEs in block 00 to 7F
[    0.173700] ACPI : EC: GPE = 0xa, I/O: command/status = 0x66, data = 0x62
[    0.342545] ACPI: Thermal Zone [THRM] (50 C)
[    0.344213] ACPI: Thermal Zone [TZ00] (28 C)
[    0.344527] ACPI: Thermal Zone [TZ01] (30 C)
[    6.516914] ACPI: Lid Switch [LID]
[    6.521394] ACPI: Sleep Button [SLPB]
[    6.523339] ACPI: AC Adapter [AC0] (on-line)
[    6.525863] ACPI: bus type USB registered
[    6.525883] ACPI: Power Button [PWRF]
[    6.557750] ACPI : EC: 2: Increase command
[    6.557753] ACPI : EC: ***** Command(BE_EC) started *****
[    6.557755] ACPI : EC: ===== TASK (0) =====
[    6.557765] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.557766] ACPI : EC: EC_SC(W) = 0x82
[    6.557872] ACPI : EC: ===== IRQ (0) =====
[    6.557880] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    6.557884] ACPI : EC: EC_DATA(R) = 0x90
[    6.557891] ACPI : EC: ***** Command(BE_EC) stopped *****
[    6.557892] ACPI : EC: 1: Decrease command
[    6.557894] ACPI : EC: 2: Increase command
[    6.557895] ACPI : EC: ***** Command(RD_EC) started *****
[    6.557896] ACPI : EC: ===== TASK (0) =====
[    6.557905] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.557907] ACPI : EC: EC_SC(W) = 0x80
[    6.557912] ACPI : EC: ===== IRQ (3) =====
[    6.557922] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.557923] ACPI : EC: EC_DATA(W) = 0xc4
[    6.557945] ACPI : EC: ===== IRQ (3) =====
[    6.557953] ACPI : EC: EC_SC(R) = 0x12 SCI_EVT=0 BURST=1 CMD=0 IBF=1 OBF=0
[    6.557966] ACPI : EC: ===== IRQ (0) =====
[    6.557974] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.557979] ACPI : EC: EC_DATA(R) = 0x46
[    6.557983] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.557985] ACPI : EC: 1: Decrease command
[    6.557991] ACPI : EC: ===== IRQ (0) =====
[    6.557999] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.558002] ACPI : EC: 2: Increase command
[    6.558004] ACPI : EC: ***** Command(RD_EC) started *****
[    6.558005] ACPI : EC: ===== TASK (0) =====
[    6.558013] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.558015] ACPI : EC: EC_SC(W) = 0x80
[    6.558055] ACPI : EC: ===== IRQ (0) =====
[    6.558063] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.558064] ACPI : EC: EC_DATA(W) = 0xc5
[    6.558078] ACPI : EC: ===== IRQ (0) =====
[    6.558086] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.558090] ACPI : EC: EC_DATA(R) = 0x00
[    6.558095] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.558096] ACPI : EC: 1: Decrease command
[    6.558103] ACPI : EC: ===== IRQ (0) =====
[    6.558110] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.558122] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.558123] ACPI : EC: 2: Increase command
[    6.558124] ACPI : EC: ***** Command(BD_EC) started *****
[    6.558126] ACPI : EC: ===== TASK (0) =====
[    6.558134] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.558135] ACPI : EC: EC_SC(W) = 0x83
[    6.558175] ACPI : EC: ===== IRQ (3) =====
[    6.558184] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.558191] ACPI : EC: ***** Command(BD_EC) stopped *****
[    6.558193] ACPI : EC: 1: Decrease command
[    6.558198] ACPI : EC: ===== IRQ (0) =====
[    6.558207] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.563969] ACPI: Battery Slot [BAT0] (battery present)
[    6.592650] ACPI Warning: SystemIO range 0x000000000000F040-0x000000000000F05F conflicts with OpRegion 0x000000000000F040-0x000000000000F04F (\SMB0) (20150818/utaddress-254)
[    6.592655] ACPI Warning: SystemIO range 0x000000000000F040-0x000000000000F05F conflicts with OpRegion 0x000000000000F040-0x000000000000F04F (\_SB_.PCI0.SBUS.SMBI) (20150818/utaddress-254)
[    6.592656] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    6.698828] ACPI : EC: 2: Increase command
[    6.698831] ACPI : EC: ***** Command(BE_EC) started *****
[    6.698833] ACPI : EC: ===== TASK (2) =====
[    6.698841] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.698842] ACPI : EC: EC_SC(W) = 0x82
[    6.699698] ACPI : EC: ===== TASK (0) =====
[    6.699708] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.700551] ACPI : EC: ===== TASK (0) =====
[    6.700673] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.700967] ACPI : EC: ===== IRQ (0) =====
[    6.700981] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    6.700984] ACPI : EC: EC_DATA(R) = 0x90
[    6.701475] ACPI : EC: ***** Command(BE_EC) stopped *****
[    6.701478] ACPI : EC: 1: Decrease command
[    6.701479] ACPI : EC: 2: Increase command
[    6.701481] ACPI : EC: ***** Command(RD_EC) started *****
[    6.701482] ACPI : EC: ===== TASK (0) =====
[    6.701489] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.701491] ACPI : EC: EC_SC(W) = 0x80
[    6.701527] ACPI : EC: ===== IRQ (0) =====
[    6.701537] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.701539] ACPI : EC: EC_DATA(W) = 0x93
[    6.701554] ACPI : EC: ===== IRQ (0) =====
[    6.701562] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.701565] ACPI : EC: EC_DATA(R) = 0x00
[    6.701579] ACPI : EC: ===== IRQ (0) =====
[    6.701589] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.701855] ACPI : EC: ===== IRQ (2) =====
[    6.701864] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.701878] ACPI : EC: ===== IRQ (2) =====
[    6.701886] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.702126] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.702128] ACPI : EC: 1: Decrease command
[    6.702130] ACPI : EC: 2: Increase command
[    6.702131] ACPI : EC: ***** Command(RD_EC) started *****
[    6.702133] ACPI : EC: ===== TASK (0) =====
[    6.702143] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.702145] ACPI : EC: EC_SC(W) = 0x80
[    6.702584] ACPI : EC: ===== TASK (0) =====
[    6.702594] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.703529] ACPI : EC: ===== TASK (2) =====
[    6.703537] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.704531] ACPI : EC: ===== TASK (2) =====
[    6.704539] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.705344] ACPI : EC: ===== IRQ (2) =====
[    6.705356] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.705358] ACPI : EC: EC_DATA(W) = 0x94
[    6.705388] ACPI : EC: ===== IRQ (2) =====
[    6.705396] ACPI : EC: EC_SC(R) = 0x01 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=1
[    6.705400] ACPI : EC: EC_DATA(R) = 0x00
[    6.705412] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.705413] ACPI : EC: 1: Decrease command
[    6.705418] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.705419] ACPI : EC: ===== IRQ (0) =====
[    6.705427] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.740010] ACPI : EC: 2: Increase command
[    6.740011] ACPI : EC: ***** Command(BE_EC) started *****
[    6.740012] ACPI : EC: ===== TASK (2) =====
[    6.740023] ACPI : EC: EC_SC(R) = 0x00 SCI_EVT=0 BURST=0 CMD=0 IBF=0 OBF=0
[    6.740024] ACPI : EC: EC_SC(W) = 0x82
[    6.740569] ACPI : EC: ===== TASK (2) =====
[    6.740577] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.742239] ACPI : EC: ===== IRQ (0) =====
[    6.742247] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    6.742250] ACPI : EC: EC_DATA(R) = 0x90
[    6.742321] ACPI : EC: ***** Command(BE_EC) stopped *****
[    6.742322] ACPI : EC: 1: Decrease command
[    6.742323] ACPI : EC: 2: Increase command
[    6.742324] ACPI : EC: ***** Command(RD_EC) started *****
[    6.742325] ACPI : EC: ===== TASK (2) =====
[    6.742334] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.742334] ACPI : EC: EC_SC(W) = 0x80
[    6.742372] ACPI : EC: ===== IRQ (0) =====
[    6.742380] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.742380] ACPI : EC: EC_DATA(W) = 0x93
[    6.742396] ACPI : EC: ===== IRQ (0) =====
[    6.742403] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.742406] ACPI : EC: EC_DATA(R) = 0x00
[    6.742409] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.742410] ACPI : EC: 1: Decrease command
[    6.742410] ACPI : EC: 2: Increase command
[    6.742411] ACPI : EC: ***** Command(RD_EC) started *****
[    6.742411] ACPI : EC: ===== TASK (2) =====
[    6.742497] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.742498] ACPI : EC: EC_SC(W) = 0x80
[    6.742523] ACPI : EC: ===== TASK (2) =====
[    6.742528] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.742529] ACPI : EC: EC_DATA(W) = 0x94
[    6.742537] ACPI : EC: ===== IRQ (3) =====
[    6.742547] ACPI : EC: EC_SC(R) = 0x12 SCI_EVT=0 BURST=1 CMD=0 IBF=1 OBF=0
[    6.742560] ACPI : EC: ===== IRQ (2) =====
[    6.742568] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.742571] ACPI : EC: EC_DATA(R) = 0x00
[    6.742576] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.742577] ACPI : EC: 1: Decrease command
[    6.742580] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.742581] ACPI : EC: 2: Increase command
[    6.742581] ACPI : EC: ***** Command(BD_EC) started *****
[    6.742582] ACPI : EC: ===== TASK (2) =====
[    6.742591] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.742592] ACPI : EC: EC_SC(W) = 0x83
[    6.742594] ACPI : EC: ===== IRQ (3) =====
[    6.742601] ACPI : EC: EC_SC(R) = 0x1a SCI_EVT=0 BURST=1 CMD=1 IBF=1 OBF=0
[    6.742630] ACPI : EC: ===== IRQ (2) =====
[    6.742637] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.742642] ACPI : EC: ***** Command(BD_EC) stopped *****
[    6.742643] ACPI : EC: 1: Decrease command
[    6.742651] ACPI : EC: ===== IRQ (0) =====
[    6.742658] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.748378] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    6.781034] ACPI : EC: 2: Increase command
[    6.781035] ACPI : EC: ***** Command(BE_EC) started *****
[    6.781036] ACPI : EC: ===== TASK (2) =====
[    6.781043] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.781044] ACPI : EC: EC_SC(W) = 0x82
[    6.781514] ACPI : EC: ===== TASK (1) =====
[    6.781522] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.782522] ACPI : EC: ===== TASK (2) =====
[    6.782529] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.783516] ACPI : EC: ===== TASK (0) =====
[    6.783525] ACPI : EC: EC_SC(R) = 0x0a SCI_EVT=0 BURST=0 CMD=1 IBF=1 OBF=0
[    6.783632] ACPI : EC: ===== IRQ (2) =====
[    6.783641] ACPI : EC: EC_SC(R) = 0x19 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=1
[    6.783644] ACPI : EC: EC_DATA(R) = 0x90
[    6.783651] ACPI : EC: ***** Command(BE_EC) stopped *****
[    6.783652] ACPI : EC: 1: Decrease command
[    6.783652] ACPI : EC: 2: Increase command
[    6.783653] ACPI : EC: ***** Command(RD_EC) started *****
[    6.783654] ACPI : EC: ===== TASK (2) =====
[    6.783735] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.783736] ACPI : EC: EC_SC(W) = 0x80
[    6.783771] ACPI : EC: ===== IRQ (0) =====
[    6.783778] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.783778] ACPI : EC: EC_DATA(W) = 0x93
[    6.783868] ACPI : EC: ===== IRQ (2) =====
[    6.783875] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.783878] ACPI : EC: EC_DATA(R) = 0x00
[    6.783901] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.783902] ACPI : EC: 1: Decrease command
[    6.783903] ACPI : EC: 2: Increase command
[    6.783903] ACPI : EC: ***** Command(RD_EC) started *****
[    6.783904] ACPI : EC: ===== TASK (2) =====
[    6.783910] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.783910] ACPI : EC: EC_SC(W) = 0x80
[    6.783950] ACPI : EC: ===== IRQ (2) =====
[    6.783957] ACPI : EC: EC_SC(R) = 0x18 SCI_EVT=0 BURST=1 CMD=1 IBF=0 OBF=0
[    6.783957] ACPI : EC: EC_DATA(W) = 0x94
[    6.784046] ACPI : EC: ===== IRQ (2) =====
[    6.784053] ACPI : EC: EC_SC(R) = 0x11 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=1
[    6.784056] ACPI : EC: EC_DATA(R) = 0x00
[    6.784068] ACPI : EC: ***** Command(RD_EC) stopped *****
[    6.784069] ACPI : EC: 1: Decrease command
[    6.784071] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.784072] ACPI : EC: 2: Increase command
[    6.784072] ACPI : EC: ***** Command(BD_EC) started *****
[    6.784073] ACPI : EC: ===== TASK (2) =====
[    6.784078] ACPI : EC: EC_SC(R) = 0x10 SCI_EVT=0 BURST=1 CMD=0 IBF=0 OBF=0
[    6.784079] ACPI : EC: EC_SC(W) = 0x83
[    6.784117] ACPI : EC: ===== IRQ (2) =====
[    6.784123] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    6.784128] ACPI : EC: ***** Command(BD_EC) stopped *****
[    6.784128] ACPI : EC: 1: Decrease command
[    6.784213] ACPI : EC: ===== IRQ (0) =====
[    6.784220] ACPI : EC: EC_SC(R) = 0x08 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=0
[    7.346268] ACPI : EC: ===== IRQ (2) =====
[    7.346280] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    7.346281] ACPI : EC: ##### Command(QR_EC) submitted/blocked #####
[    7.346304] ACPI : EC: ##### Event started #####
[    7.346306] ACPI : EC: 2: Increase command
[    7.346307] ACPI : EC: ***** Command(QR_EC) started *****
[    7.346308] ACPI : EC: ===== TASK (2) =====
[    7.346316] ACPI : EC: EC_SC(R) = 0x28 SCI_EVT=1 BURST=0 CMD=1 IBF=0 OBF=0
[    7.346317] ACPI : EC: EC_SC(W) = 0x84
[    7.346429] ACPI : EC: ===== TASK (2) =====
[    7.346436] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    7.347483] ACPI : EC: ===== TASK (2) =====
[    7.347494] ACPI : EC: EC_SC(R) = 0x2a SCI_EVT=1 BURST=0 CMD=1 IBF=1 OBF=0
[    7.347706] ACPI : EC: ===== IRQ (2) =====
[    7.347717] ACPI : EC: EC_SC(R) = 0x09 SCI_EVT=0 BURST=0 CMD=1 IBF=0 OBF=1
[    7.347721] ACPI : EC: EC_DATA(R) = 0xac
[    7.347722] ACPI : EC: ##### Command(QR_EC) unblocked #####
[    7.347723] ACPI : EC: ##### Command(QR_EC) completed by hardware #####
[    7.347742] ACPI : EC: ***** Command(QR_EC) stopped *****
[    7.347743] ACPI : EC: 1: Decrease command
[    7.347745] ACPI : EC: ##### Query(0xac) scheduled #####
[    7.347747] ACPI : EC: ##### Event stopped #####
[    7.347748] ACPI : EC: ##### Query(0xac) started #####
[    7.347767]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.347780]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c2f:Store] execution.
[    7.347785]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c30:-NamePath-] execution.
[    7.347795]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c34:ByteConst] execution.
[    7.347798]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c34:ByteConst] execution.
[    7.347801]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c36:Zero] execution.
[    7.347803]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c36:Zero] execution.
[    7.347810]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c30:-MethodCall-] execution.
[    7.347815]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.347822]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033774:If] execution.
[    7.347826]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033776:-NamePath-] execution.
[    7.347829]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033776:-MethodCall-] execution.
[    7.347833]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.347837]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da3a:If] execution.
[    7.347841]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da3c:LNot] execution.
[    7.347844]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da3d:LLess] execution.
[    7.347847]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da3e:-NamePath-] execution.
[    7.347887]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da3e:-NamePath-] execution.
[    7.347890]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da42:ByteConst] execution.
[    7.347893]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da42:ByteConst] execution.
[    7.347900]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da3d:LLess] execution.
[    7.347906]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da3c:LNot] execution.
[    7.347909]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da3a:If] execution.
[    7.347912]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da46:If] execution.
[    7.347915]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da48:LEqual] execution.
[    7.347918]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da49:-NamePath-] execution.
[    7.347939]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da49:-NamePath-] execution.
[    7.347942]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da4d:One] execution.
[    7.347944]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da4d:One] execution.
[    7.347949]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da48:LEqual] execution.
[    7.347952]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da46:If] execution.
[    7.347955]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da50:If] execution.
[    7.347958]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da52:LEqual] execution.
[    7.347961]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da53:-NamePath-] execution.
[    7.347966]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da53:-NamePath-] execution.
[    7.347969]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da57:Ones] execution.
[    7.347971]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da57:Ones] execution.
[    7.347976]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da52:LEqual] execution.
[    7.347978]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da50:If] execution.
[    7.347981]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da6a:Return] execution.
[    7.347984]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002da6b:-NamePath-] execution.
[    7.347989]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da6b:-NamePath-] execution.
[    7.347992]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002da6a:Return] execution.
[    7.347998]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002da3a:\_SB.PCI0.LPCB.EC0.ECAV] execution.
[    7.348003]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003377a:Acquire] execution.
[    7.348012]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003377a:Acquire] execution.
[    7.348015]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033782:Store] execution.
[    7.348018]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033783:ByteConst] execution.
[    7.348020]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033783:ByteConst] execution.
[    7.348031]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033782:Store] execution.
[    7.348034]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033789:Store] execution.
[    7.348037]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003378a:ByteConst] execution.
[    7.348039]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003378a:ByteConst] execution.
[    7.348046]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033789:Store] execution.
[    7.348049]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033790:Store] execution.
[    7.348052]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033791:Arg0] execution.
[    7.348055]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033791:Arg0] execution.
[    7.348063]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033790:Store] execution.
[    7.348065]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033796:Store] execution.
[    7.348069]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000033797:Arg1] execution.
[    7.348071]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033797:Arg1] execution.
[    7.348078]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033796:Store] execution.
[    7.348081]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003379c:-NamePath-] execution.
[    7.348086]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003379c:-MethodCall-] execution.
[    7.348090]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.348094]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003465b:Store] execution.
[    7.348097]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003465c:-NamePath-] execution.
[    7.348117]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003465c:-NamePath-] execution.
[    7.348138]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003465b:Store] execution.
[    7.348141]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034664:Store] execution.
[    7.348144]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034665:ByteConst] execution.
[    7.348147]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034665:ByteConst] execution.
[    7.348155]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034664:Store] execution.
[    7.348158]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003466b:Store] execution.
[    7.348160]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003466c:One] execution.
[    7.348163]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003466c:One] execution.
[    7.348170]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003466b:Store] execution.
[    7.348172]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034671:Store] execution.
[    7.348175]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034672:ByteConst] execution.
[    7.348178]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034672:ByteConst] execution.
[    7.348185]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034671:Store] execution.
[    7.348188]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034678:Store] execution.
[    7.348190]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034679:Zero] execution.
[    7.348193]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034679:Zero] execution.
[    7.348200]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034678:Store] execution.
[    7.348202]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003467e:Store] execution.
[    7.348205]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000003467f:One] execution.
[    7.348207]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003467f:One] execution.
[    7.348214]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000003467e:Store] execution.
[    7.348217]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034684:-NamePath-] execution.
[    7.348255]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000034688:ByteConst] execution.
[    7.348258]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034688:ByteConst] execution.
[    7.348261]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000034684:-MethodCall-] execution.
[    7.348265]   exdebug-0435 ex_trace_point        : Method Begin [0xffffc9000002e16e:\ISMI] execution.
[    7.348270]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002e16e:Store] execution.
[    7.348272]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc9000002e16f:Arg0] execution.
[    7.348275]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002e16f:Arg0] execution.
[    7.349016]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc9000002e16e:Store] execution.
[    7.349022]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000002e16e:\ISMI] execution.
[    7.349027]   exdebug-0435 ex_trace_point        : Method End [0xffffc9000003465b:\_SB.PCI0.LPCB.EC0.ECAC] execution.
[    7.349031]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc900000337a0:Release] execution.
[    7.349038]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc900000337a0:Release] execution.
[    7.349041]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc900000337a6:Return] execution.
[    7.349044]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc900000337a7:-NamePath-] execution.
[    7.349051]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc900000337a7:-NamePath-] execution.
[    7.349054]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc900000337a6:Return] execution.
[    7.349057]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000033774:If] execution.
[    7.349062]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000033774:\_SB.PCI0.LPCB.EC0.ST8E] execution.
[    7.349066]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c37:Local0] execution.
[    7.349068]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c37:Local0] execution.
[    7.349074]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c2f:Store] execution.
[    7.349077]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c38:If] execution.
[    7.349080]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c3a:LEqual] execution.
[    7.349083]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c3b:And] execution.
[    7.349086]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c3c:Local0] execution.
[    7.349088]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c3c:Local0] execution.
[    7.349091]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c3d:One] execution.
[    7.349094]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c3d:One] execution.
[    7.349101]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c3b:And] execution.
[    7.349104]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c3f:One] execution.
[    7.349107]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c3f:One] execution.
[    7.349112]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c3a:LEqual] execution.
[    7.349115]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c40:Notify] execution.
[    7.349124]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c45:ByteConst] execution.
[    7.349126]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c45:ByteConst] execution.
[    7.349139]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c40:Notify] execution.
[    7.349144]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c38:If] execution.
[    7.349152]   exdebug-0435 ex_trace_point        : Method End [0xffffc90000035c2f:\_SB.PCI0.LPCB.EC0._QAC] execution.
[    7.349157] ACPI : EC: ##### Query(0xac) stopped #####
Comment 24 Lv Zheng 2015-11-03 01:35:02 UTC
We can see this:
[    7.349115]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c40:Notify] execution.
[    7.349124]   exdebug-0435 ex_trace_point        : Opcode Begin [0xffffc90000035c45:ByteConst] execution.
[    7.349126]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c45:ByteConst] execution.
[    7.349139]   exdebug-0435 ex_trace_point        : Opcode End [0xffffc90000035c40:Notify] execution.

So the battery device should have been correctly notified.

Aaron, could you take this over to dig into battery driver?

Thanks and best regards
-Lv
Comment 25 Aaron Lu 2015-11-03 05:36:58 UTC
Created attachment 191891 [details]
Add debug print in acpi_battery_notify

I have prepared a patch to add a debug print in acpi_battery_notify, let's see if it got the notification.
Apply on top of v4.3(may apply on older kernels, I didn't test, it's a one line patch so should be pretty easy to adjust).
Comment 26 doa379 2015-11-03 10:07:23 UTC
I applied the patch. There was one initial notification:

Nov  3 09:02:08 ux305 kernel: [  225.468206] battery PNP0C0A:00: acpi_battery_notify: event=128

But no more notifications since.
Comment 27 doa379 2015-11-03 19:39:20 UTC
The notification only gets triggered when there is a change of state (same like before) such as:

- Suspend/Resume to/from Standby
- PSU gets plugged in or removed
- Battery level reaches 10%

At all other times there are no notifications provided.
Comment 28 Aaron Lu 2015-11-04 02:15:29 UTC
I think we will need to enable EC debug output together with the driver debug print patch.

Lv,
What is the necessary things to enable the EC debug output?
Comment 29 Aaron Lu 2015-11-04 02:25:54 UTC
(In reply to Lv Zheng from comment #24)
> We can see this:
> [    7.349115]   exdebug-0435 ex_trace_point        : Opcode Begin
> [0xffffc90000035c40:Notify] execution.
> [    7.349124]   exdebug-0435 ex_trace_point        : Opcode Begin
> [0xffffc90000035c45:ByteConst] execution.
> [    7.349126]   exdebug-0435 ex_trace_point        : Opcode End
> [0xffffc90000035c45:ByteConst] execution.
> [    7.349139]   exdebug-0435 ex_trace_point        : Opcode End
> [0xffffc90000035c40:Notify] execution.
> 
> So the battery device should have been correctly notified.

My guess is that the above only happened once during boot, and also when the AC plug in/out, and when the battery level drops to 10%. But not when the battery level changed. If it is the case, the OS have no way to provide the event.

But as I said, better to enable the above output with the driver's debug print to see if it is the case.
Comment 30 doa379 2015-11-04 19:56:51 UTC
Created attachment 192121 [details]
Linux 4.3 syslog w/ event traces

I am submitting another syslog which holds more realtime updates than the dmesg log.

Towards the end of the file we can see that there are repeated reports being generated. I have also captured a report being generated when the AC is plugged back into the unit causing an event to be generated. Line #3519.
Comment 31 Aaron Lu 2015-11-05 02:55:01 UTC
From the log, there is only one notify during kernel boot, no more afterwards.
There are a lot of queries, number 0xd3 and 0xd4, but the two queries do not send notification to battery device, so the driver doesn't get the notification and thus, didn't send event to user space.

I don't see anything we can do here, if the firmware doesn't send notification, Linux simply can not send event to user space.

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