I've improved the ec_debug code (and attached the update) so the init subsystem is reported, and it correctly deals with potential vprintk() buffer over-runs by flushing the ec_messages buffer in blocks of no more than 1020 characters. I added a dump_stack() followed by mdelay() but even with the video camera recording it the reports scroll up the screen far too fast (and blurred) to be readable even on freeze-frame.
>From a successful session however, here's the relevant part: [ 45.084736] ACPI: bus type pci registered [ 45.084867] PCI: Using configuration type 1 [ 45.086043] ACPI: EC: acpi_init acpi_ec_ecdt_probe() [ 45.086045] ACPI: EC: acpi_init make_acpi_ec() [ 45.086046] ACPI: EC: Look up EC in DSDT [ 45.092877] ACPI: Interpreter enabled [ 45.092939] ACPI: (supports S0 S3 S4 S5) [ 45.093194] ACPI: Using IOAPIC for interrupt routing [ 45.093362] ACPI: EC: acpi_scan_init acpi_boot_ec_enable() [ 45.093363] ACPI: EC: acpi_scan_init acpi_boot_ec_enable() call ec_install_handlers(boot_ec) [ 45.093364] ACPI: EC: acpi_scan_init ec_install_handlers(<ec>) ec->handlers_installed=0 [ 45.093365] ACPI: EC: acpi_scan_init ec_install_handlers() acpi_install_gpe_handler() succeeded [ 45.093400] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.093402] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.093403] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.093404] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.093405] ACPI: EC: acpi_scan_init acpi_ec_wait() else [ 45.093408] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.093411] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.093412] ACPI: EC: acpi_scan_init acpi_ec_wait() else [ 45.093559] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.093560] ACPI: EC: acpi_scan_init acpi_ec_wait() else [ 45.093618] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.093622] ACPI: EC: non-query interrupt received, switching to interrupt mode [ 45.093930] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.093942] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.093943] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.093944] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.093945] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.093948] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.093951] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.094339] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.094350] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.094777] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.094800] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.094801] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.094802] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.094803] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.094806] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.094809] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.095173] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.095185] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.095484] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.095551] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.095552] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.095553] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.095554] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.095557] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.095560] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.095767] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.095778] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.096217] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.096235] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.096236] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.096237] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.096238] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.096241] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.096244] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.096598] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.096609] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.096925] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.102523] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.102524] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.102525] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.102526] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.102529] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.102532] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.102762] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.102773] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.103199] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.110516] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.110518] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.110518] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.110520] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.110523] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.110526] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.110893] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.110904] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 1, 0) [ 45.111204] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.111305] ACPI: EC: acpi_scan_init acpi_ec_space_handler(<args...>) [ 45.111307] ACPI: EC: acpi_scan_init acpi_ec_read(<ec>, <address>, <data>) [ 45.111307] ACPI: EC: acpi_scan_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.111309] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.111312] ACPI: EC: acpi_scan_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.111315] ACPI: EC: acpi_scan_init acpi_ec_wait(<ec>, 2, 0) [ 45.111567] ACPI: EC: acpi_scan_init acpi_ec_gpe_handler(<data>) [ 45.115479] ACPI: EC: GPE = 0x17, I/O: command/status = 0x66, data = 0x62 [ 45.115544] ACPI: EC: driver started in interrupt mode [ 45.123068] ACPI: PCI Root Bridge [PCI0] (0000:00) [ 45.123898] PCI quirk: region 1000-107f claimed by ICH6 ACPI/GPIO/TCO [ 45.123965] PCI quirk: region 1180-11bf claimed by ICH6 GPIO [ 45.125346] PCI: Transparent bridge - 0000:00:1e.0 [ 45.125500] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 45.125776] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEGP._PRT] [ 45.125894] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP01._PRT] [ 45.126006] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP02._PRT] [ 45.126118] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP03._PRT] [ 45.126230] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP04._PRT] [ 45.126358] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCIB._PRT] [ 45.132718] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 *7 10 12 14 15) [ 45.133425] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 *3 4 5 6 7 11 12 14 15) [ 45.134128] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 5 6 7 10 12 14 15) *11 [ 45.134888] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 7 11 12 14 15) *10 [ 45.135642] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 7 10 12 14 15) *11 [ 45.136398] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 *7 11 12 14 15) [ 45.137097] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 *4 5 6 7 10 12 14 15) [ 45.137799] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 *5 6 7 11 12 14 15) [ 45.138526] Linux Plug and Play Support v0.97 (c) Adam Belay [ 45.138620] pnp: PnP ACPI init [ 45.138686] ACPI: bus type pnp registered [ 45.146512] ACPI: EC: acpi_ec_init acpi_ec_space_handler(<args...>) [ 45.146514] ACPI: EC: acpi_ec_init acpi_ec_read(<ec>, <address>, <data>) [ 45.146515] ACPI: EC: acpi_ec_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.146516] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 2, 0) [ 45.146519] ACPI: EC: acpi_ec_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.146522] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 2, 0) [ 45.146807] ACPI: EC: acpi_ec_init acpi_ec_gpe_handler(<data>) [ 45.146818] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 1, 0) [ 45.147322] ACPI: EC: acpi_ec_init acpi_ec_gpe_handler(<data>) [ 45.147383] ACPI: EC: acpi_ec_init acpi_ec_space_handler(<args...>) [ 45.147384] ACPI: EC: acpi_ec_init acpi_ec_read(<ec>, <address>, <data>) [ 45.147385] ACPI: EC: acpi_ec_init acpi_ec_transaction(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.147386] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 2, 0) [ 45.147389] ACPI: EC: acpi_ec_init acpi_ec_transaction_unlocked(<ec>, 128, <wdata>, 1, <rdata>, 1, 0) [ 45.147392] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 2, 0) [ 45.147593] ACPI: EC: acpi_ec_init acpi_ec_gpe_handler(<data>) [ 45.147604] ACPI: EC: acpi_ec_init acpi_ec_wait(<ec>, 1, 0) [ 45.147917] ACPI: EC: acpi_ec_init acpi_ec_gpe_handler(<data>) [ 45.149254] pnp: PnP ACPI: found 10 devices [ 45.149316] ACPI: ACPI bus type pnp unregistered ** Attachment added: "ec_debug ACPI EC delayed printk() messaging" http://launchpadlibrarian.net/15964921/ec_debug.patch -- [Hardy] ACPI Embedded Controller (EC) stops boot when kernel boot 'quiet' option is enabled or AC power is connected https://bugs.launchpad.net/bugs/191137 You received this bug notification because you are a member of Ubuntu Bugs, which is subscribed to Ubuntu. -- ubuntu-bugs mailing list ubuntu-bugs@lists.ubuntu.com https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
