[U-Boot] [PATCH v2 00/22] x86: Add ACPI S3 resume support
Stefan Roese
sr at denx.de
Tue Apr 25 11:11:52 UTC 2017
Hi Bin,
On 25.04.2017 11:34, Bin Meng wrote:
<snip>
>>> What do you mean by "display
>>> stays in power safe mode"? Is it a black screen, or is it just
>>> U-Boot's video output screen?
>>
>> Blank screen and the monitor does not wake up from power-safe
>> mode. I see the log on the serial console, since I've changed the
>> environment (stdio) to not use the LCD as the console in U-Boot.
>> U-Boot uses the console as a splash screen instead.
>>
>
> Since you were seeing black screen I guess your U-Boot was not running
> the VGA BIOS for the Intel Baytrail IGD?
My U-Boot configuration runs the VGA BIOS via the "preboot" env
command:
=> printenv preboot
preboot=load scsi 0:${ubuntu_part} ${loadaddr} /boot/logo/logo.bmp;bmp display ${loadaddr}
> The LCD driver is not the
> Intel i915 graphics driver?
In Linux it is. But in the resume case, the preboot env command is
not run, so this explains why the screen is not initialized by
U-Boot (again).
>>>> Jumping to OS waking vector 0009a1d0
>>>>
>>>> And the POST debug byte is also identical (0x40).
>>>>
>>>
>>> These logs looks good. Nothing strange.
>>>
>>>> Do you have any ideas, where this might come from? Could you
>>>> perhaps check this on MinnoxMax as well, if such a setup also
>>>> sometimes has problems with resuming? Is there anything that I could
>>>> do to test this on my platform?
>>>>
>>>
>>> To match your env, I will install a 16.04 from U-Boot for testing. Can
>>> you add the following kernel parameters for testing suspend/resume?
>>>
>>> "console=ttyS0,115200 initcall_debug ignore_loglevel no_console_suspend"
>>
>> Ah, this brings some infos. Here the resume log:
>>
>> [ 49.810439] smpboot: CPU 3 is now offline
>> [ 49.828153] PM: Calling kvm_suspend+0x0/0x30 [kvm]
>> [ 49.833517] PM: Calling mce_syscore_suspend+0x0/0x20
>> [ 49.839072] PM: Calling ledtrig_cpu_syscore_suspend+0x0/0x20
>> [ 49.845404] PM: Calling acpi_processor_suspend+0x0/0x1e
>> [ 49.851255] PM: Calling timekeeping_suspend+0x0/0x2a0
>> [ 49.856925] PM: Calling irq_gc_suspend+0x0/0x70
>> [ 49.861992] PM: Calling save_ioapic_entries+0x0/0x90
>> [ 49.867738] PM: Calling i8259A_suspend+0x0/0x30
>> [ 49.872806] PM: Calling fw_suspend+0x0/0x20
>> [ 49.877484] PM: Calling lapic_suspend+0x0/0x1b0
>> ��
>>
>> U-Boot 2017.05-rc2-00122-gf6a9c0b4fd-dirty (Apr 24 2017 - 10:50:06 +0200)
>>
>> CPU: x86_64, vendor Intel, device 30679h
>> DRAM: 4 GiB
>> MMC: ValleyView SDHCI: 0, ValleyView SDHCI: 1, ValleyView SDHCI: 2
>> SF: Detected w25q64cv with page size 256 Bytes, erase size 4 KiB, total 8 MiB
>> Model: congatec-QEVAL20-QA3-E3845
>> SCSI: SATA link 0 timeout.
>> Target spinup took 0 ms.
>> AHCI 0001.0300 32 slots 2 ports 3 Gbps 0x3 impl SATA mode
>> flags: 64bit ncq stag pm led clo pio slum part sxs
>> scanning bus for devices...
>> Device 0: (1:0) Vendor: ATA Prod.: SanDisk Ultra II Rev: X411
>> Type: Hard Disk
>> Capacity: 457862.8 MB = 447.1 GB (937703088 x 512)
>> Found 1 device(s).
>> Net: e1000: 00:13:95:1c:51:e8
>> eth0: e1000#0
>> Jumping to OS waking vector 0009a1d0
>> [ 49.882704] ACPI: Low-level resume complete
>> [ 49.887483] PM: Restoring platform NVS memory
>> [ 49.892361] PM: Calling bsp_resume+0x0/0x20
>> [ 49.897040] PM: Calling lapic_resume+0x0/0x2b0
>> [ 49.902035] PM: Calling irqrouter_resume+0x0/0x3f
>> [ 49.907297] PM: Calling i8259A_resume+0x0/0x30
>> [ 49.912393] PM: Calling i8237A_resume+0x0/0x90
>> [ 49.917434] PM: Calling ioapic_resume+0x0/0xa0
>> [ 49.922495] PM: Calling irq_gc_resume+0x0/0x60
>> [ 49.927464] PM: Calling irq_pm_syscore_resume+0x0/0x20
>> [ 49.933256] PM: Calling timekeeping_resume+0x0/0x200
>> [ 49.938844] PM: Calling acpi_processor_resume+0x0/0x5c
>> [ 49.944595] PM: Calling ledtrig_cpu_syscore_resume+0x0/0x20
>> [ 49.950829] PM: Calling mce_syscore_resume+0x0/0x30
>> [ 49.956291] PM: Calling mc_bp_resume+0x0/0x50
>> [ 49.961193] PM: Calling kvm_resume+0x0/0x40 [kvm]
>> [ 49.966523] Enabling non-boot CPUs ...
>> [ 49.990192] x86: Booting SMP configuration:
>> [ 49.994894] smpboot: Booting Node 0 Processor 1 APIC 0x2
>> [ 50.011048] cache: parent cpu1 should not be sleeping
>> [ 50.017239] CPU1 is up
>> [ 50.038380] smpboot: Booting Node 0 Processor 2 APIC 0x4
>> [ 50.054552] cache: parent cpu2 should not be sleeping
>> [ 50.060685] CPU2 is up
>> [ 50.082550] smpboot: Booting Node 0 Processor 3 APIC 0x6
>> [ 50.098820] cache: parent cpu3 should not be sleeping
>> [ 50.104972] CPU3 is up
>> [ 50.108534] ACPI: Waking up from system sleep state S3
>> [ 50.114576] calling 0000:00:00.0+ @ 2269, parent: pci0000:00
>> [ 50.114630] calling 0000:00:02.0+ @ 163, parent: pci0000:00
>> [ 50.114728] calling 0000:00:11.0+ @ 2265, parent: pci0000:00
>> [ 50.114759] calling 0000:00:12.0+ @ 2268, parent: pci0000:00
>> [ 50.114800] calling 0000:00:13.0+ @ 159, parent: pci0000:00
>> [ 50.114830] calling 0000:00:15.0+ @ 149, parent: pci0000:00
>> [ 50.114926] call 0000:00:15.0+ returned 0 after 86 usecs
>> [ 50.114942] calling 0000:00:17.0+ @ 149, parent: pci0000:00
>> [ 50.114979] calling 0000:00:1c.0+ @ 2267, parent: pci0000:00
>> [ 50.115003] calling 0000:00:1c.1+ @ 40, parent: pci0000:00
>> [ 50.115020] calling 0000:00:1c.2+ @ 2266, parent: pci0000:00
>> [ 50.115261] call 0000:00:1c.0+ returned 0 after 267 usecs
>> [ 50.115282] calling 0000:00:1c.3+ @ 2267, parent: pci0000:00
>> [ 50.115289] call 0000:00:1c.1+ returned 0 after 268 usecs
>> [ 50.115304] calling 0000:00:1d.0+ @ 40, parent: pci0000:00
>> [ 50.115409] calling 0000:00:1e.0+ @ 6, parent: pci0000:00
>> [ 50.115446] calling 0000:00:1e.1+ @ 158, parent: pci0000:00
>> [ 50.115465] call 0000:00:1c.3+ returned 0 after 171 usecs
>> [ 50.115482] calling 0000:00:1e.2+ @ 2271, parent: pci0000:00
>> [ 50.115487] calling 0000:00:1e.4+ @ 2267, parent: pci0000:00
>> [ 50.115689] calling 0000:00:1e.5+ @ 2270, parent: pci0000:00
>> [ 50.115748] call 0000:00:1e.5+ returned 0 after 51 usecs
>> [ 50.115759] calling 0000:00:1f.0+ @ 2270, parent: pci0000:00
>> [ 50.115804] call 0000:00:1f.0+ returned 0 after 38 usecs
>> [ 50.115813] calling 0000:00:1f.3+ @ 2270, parent: pci0000:00
>> [ 50.115899] call 0000:00:1f.3+ returned 0 after 77 usecs
>> [ 50.115907] calling 0000:04:00.0+ @ 2270, parent: 0000:00:1c.3
>> [ 50.129887] call 0000:00:1e.1+ returned 0 after 14073 usecs
>> [ 50.129890] call 0000:00:1e.2+ returned 0 after 14040 usecs
>> [ 50.129959] call 0000:00:1e.0+ returned 0 after 14181 usecs
>> [ 50.130106] call 0000:04:00.0+ returned 0 after 13839 usecs
>> [ 50.130142] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
>> [ 50.130149] call 0000:00:1e.4+ returned 0 after 14290 usecs
>> [ 50.130151] call 0000:00:1d.0+ returned 0 after 14471 usecs
>> [ 50.130210] call 0000:00:17.0+ returned 0 after 14882 usecs
>> [ 50.130256] call 0000:00:13.0+ returned 0 after 15063 usecs
>> [ 50.130268] call 0000:00:11.0+ returned 0 after 15145 usecs
>> [ 50.130311] call 0000:00:12.0+ returned 0 after 15158 usecs
>> [ 50.130327] call 0000:00:02.0+ returned 0 after 15299 usecs
>> [ 50.359559] call 0000:00:00.0+ returned 0 after 16 usecs
>> [ 76.935630] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u8:7:2266]
>> [ 76.944393] Modules linked in: intel_rapl intel_soc_dts_iosf intel_powerclamp spi_pxa2xx_platform kvm_intel kvm irqbypass punit_atom_debug crct10dif_pclmul crc32_pclmul aesni_intel aes_x86_64 joydev input_leds lrw gf128mul glue_helper ablk_helper cryptd lpc_ich serio_raw pwm_lpss_pci spi_pxa2xx_pci pwm_lpss dw_dmac_pci dw_dmac_core shpchp 8250_fintek mac_hid nfsd auth_rpcgss nfs_acl lockd coretemp grace parport_pc ppdev lp parport sunrpc autofs4 hid_cherry usbhid hid mmc_block igb dca ptp i915 video drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops ahci psmouse pps_core drm i2c_algo_bit sdhci_pci libahci sdhci fjes
>> [ 77.006747] CPU: 0 PID: 2266 Comm: kworker/u8:7 Not tainted 4.4.0-36-generic #55-Ubuntu
>> [ 77.015702] Hardware name: congatec conga-qeval20-qa3-e3845/conga-qeval20-qa3-e3845, BIOS 2017.05-rc2-00122-gf6a9c0b4fd-dirty 04/24/2017
>> [ 77.029429] Workqueue: events_unbound async_run_entry_fn
>> [ 77.035374] task: ffff8800628b0c80 ti: ffff880176dc8000 task.ti: ffff880176dc8000
>> [ 77.043746] RIP: 0010:[<ffffffff81085ad1>] [<ffffffff81085ad1>] __do_softirq+0x81/0x290
>> [ 77.052807] RSP: 0018:ffff88017fc03f28 EFLAGS: 00000206
>> [ 77.058747] RAX: ffff880176dcc000 RBX: 0000000000000000 RCX: 00000000000006e0
>> [ 77.066730] RDX: 0000000000000000 RSI: 0000000004208060 RDI: 00000000000006e0
>> [ 77.074704] RBP: ffff88017fc03f80 R08: ffff88017820e400 R09: ffff88017fc16d30
>> [ 77.082687] R10: 0000000000000020 R11: 0000000000000004 R12: 0000000000000246
>> [ 77.090669] R13: 00000000000000e2 R14: 000000000000002c R15: 000000000000000c
>> [ 77.098653] FS: 0000000000000000(0000) GS:ffff88017fc00000(0000) knlGS:0000000000000000
>> [ 77.107706] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
>> [ 77.114132] CR2: 00007f7a3c016028 CR3: 0000000176cb5000 CR4: 00000000001006f0
>> [ 77.122115] Stack:
>> [ 77.124359] 042080607fc0ede8 ffff880176dcc000 00000000ffff0b90 ffffffff81f361c0
>> [ 77.132648] ffff88010000000a 000002827fc0d640 0000000000000000 0000000000000246
>> [ 77.140937] 00000000000000e2 000000000000002c 000000000000000c ffff88017fc03f90
>> [ 77.149226] Call Trace:
>> [ 77.151957] <IRQ>
>> [ 77.154107] [<ffffffff81085e53>] irq_exit+0xa3/0xb0
>> [ 77.159860] [<ffffffff81830a62>] smp_apic_timer_interrupt+0x42/0x50
>> [ 77.166969] [<ffffffff8182ed22>] apic_timer_interrupt+0x82/0x90
>> [ 77.173686] <EOI>
>> [ 77.175834] [<ffffffff8182da05>] ? _raw_spin_unlock_irqrestore+0x15/0x20
>> [ 77.183630] [<ffffffff81436269>] pci_bus_write_config_dword.part.7+0x59/0x70
>> [ 77.191614] [<ffffffff81436299>] pci_bus_write_config_dword+0x19/0x20
>> [ 77.198919] [<ffffffff8143c335>] pci_restore_config_dword+0x65/0xe0
>> [ 77.206029] [<ffffffff8143e1a2>] pci_restore_state.part.33+0x222/0x250
>> [ 77.213429] [<ffffffff8143e1e8>] pci_restore_state+0x18/0x20
>> [ 77.219858] [<ffffffff8144237f>] pci_pm_resume_noirq+0x2f/0xa0
>> [ 77.226480] [<ffffffff81442350>] ? pci_add_dynid+0xd0/0xd0
>> [ 77.232715] [<ffffffff81560ebe>] dpm_run_callback+0x4e/0x140
>> [ 77.239143] [<ffffffff81561060>] device_resume_noirq+0xb0/0x190
>> [ 77.245861] [<ffffffff8156115d>] async_resume_noirq+0x1d/0x50
>> [ 77.252378] [<ffffffff810a3208>] async_run_entry_fn+0x48/0x150
>> [ 77.259001] [<ffffffff8109a2c5>] process_one_work+0x165/0x480
>> [ 77.265526] [<ffffffff8109a62b>] worker_thread+0x4b/0x4c0
>> [ 77.271662] [<ffffffff8109a5e0>] ? process_one_work+0x480/0x480
>> [ 77.278381] [<ffffffff810a0808>] kthread+0xd8/0xf0
>> [ 77.283826] [<ffffffff810a0730>] ? kthread_create_on_node+0x1e0/0x1e0
>> [ 77.291130] [<ffffffff8182e34f>] ret_from_fork+0x3f/0x70
>> [ 77.297168] [<ffffffff810a0730>] ? kthread_create_on_node+0x1e0/0x1e0
>> [ 77.304460] Code: 00 c7 45 c8 0a 00 00 00 48 89 45 c0 65 48 8b 04 25 04 42 01 00 48 89 45 b0 65 c7 05 76 e6 f8 7e 00 00 00 00 fb 66 0f 1f 44 00 00 <b8> ff ff ff ff 49 c7 c4 c0 90 e0 81 0f bc 45 d4 83 c0 01 89 45
>
> Thanks for the logs. Can you do the following before suspend and test again?
>
> # echo 0 > /sys/power/pm_async
Here you go:
[ 104.710546] smpboot: CPU 3 is now offline
[ 104.720268] PM: Calling kvm_suspend+0x0/0x30 [kvm]
[ 104.725632] PM: Calling mce_syscore_suspend+0x0/0x20
[ 104.731187] PM: Calling ledtrig_cpu_syscore_suspend+0x0/0x20
[ 104.737518] PM: Calling acpi_processor_suspend+0x0/0x1e
[ 104.743369] PM: Calling timekeeping_suspend+0x0/0x2a0
[ 104.749038] PM: Calling irq_gc_suspend+0x0/0x70
[ 104.754106] PM: Calling save_ioapic_entries+0x0/0x90
[ 104.759852] PM: Calling i8259A_suspend+0x0/0x30
[ 104.764921] PM: Calling fw_suspend+0x0/0x20
[ 104.769599] PM: Calling lapic_suspend+0x0/0x1b0
�
U-Boot 2017.05-rc2-00122-gf6a9c0b4fd-dirty (Apr 24 2017 - 10:50:06 +0200)
CPU: x86_64, vendor Intel, device 30679h
DRAM: 4 GiB
MMC: ValleyView SDHCI: 0, ValleyView SDHCI: 1, ValleyView SDHCI: 2
SF: Detected w25q64cv with page size 256 Bytes, erase size 4 KiB, total 8 MiB
Model: congatec-QEVAL20-QA3-E3845
SCSI: SATA link 0 timeout.
Target spinup took 0 ms.
AHCI 0001.0300 32 slots 2 ports 3 Gbps 0x3 impl SATA mode
flags: 64bit ncq stag pm led clo pio slum part sxs
scanning bus for devices...
Device 0: (1:0) Vendor: ATA Prod.: SanDisk Ultra II Rev: X411
Type: Hard Disk
Capacity: 457862.8 MB = 447.1 GB (937703088 x 512)
Found 1 device(s).
Net: e1000: 00:13:95:1c:51:e8
eth0: e1000#0
Jumping to OS waking vector 0009a1d0
[ 104.774820] ACPI: Low-level resume complete
[ 104.779598] PM: Restoring platform NVS memory
[ 104.784477] PM: Calling bsp_resume+0x0/0x20
[ 104.789157] PM: Calling lapic_resume+0x0/0x2b0
[ 104.794152] PM: Calling irqrouter_resume+0x0/0x3f
[ 104.799413] PM: Calling i8259A_resume+0x0/0x30
[ 104.804510] PM: Calling i8237A_resume+0x0/0x90
[ 104.809552] PM: Calling ioapic_resume+0x0/0xa0
[ 104.814613] PM: Calling irq_gc_resume+0x0/0x60
[ 104.819581] PM: Calling irq_pm_syscore_resume+0x0/0x20
[ 104.825374] PM: Calling timekeeping_resume+0x0/0x200
[ 104.830961] PM: Calling acpi_processor_resume+0x0/0x5c
[ 104.836714] PM: Calling ledtrig_cpu_syscore_resume+0x0/0x20
[ 104.842949] PM: Calling mce_syscore_resume+0x0/0x30
[ 104.848411] PM: Calling mc_bp_resume+0x0/0x50
[ 104.853312] PM: Calling kvm_resume+0x0/0x40 [kvm]
[ 104.858659] Enabling non-boot CPUs ...
[ 104.882349] x86: Booting SMP configuration:
[ 104.887046] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 104.903208] cache: parent cpu1 should not be sleeping
[ 104.909380] CPU1 is up
[ 104.930208] smpboot: Booting Node 0 Processor 2 APIC 0x4
[ 104.946181] cache: parent cpu2 should not be sleeping
[ 104.952121] CPU2 is up
[ 104.974291] smpboot: Booting Node 0 Processor 3 APIC 0x6
[ 104.990309] cache: parent cpu3 should not be sleeping
[ 104.996258] CPU3 is up
[ 104.999800] ACPI: Waking up from system sleep state S3
[ 105.005792] calling 0000:00:00.0+ @ 2365, parent: pci0000:00
[ 105.012277] call 0000:00:00.0+ returned 0 after 24 usecs
[ 105.018227] calling 0000:00:02.0+ @ 2365, parent: pci0000:00
[ 105.038022] call 0000:00:02.0+ returned 0 after 13022 usecs
[ 105.044270] calling 0000:00:11.0+ @ 2365, parent: pci0000:00
[ 105.066068] call 0000:00:11.0+ returned 0 after 14977 usecs
[ 105.072308] calling 0000:00:12.0+ @ 2365, parent: pci0000:00
[ 105.094119] call 0000:00:12.0+ returned 0 after 14988 usecs
[ 105.100367] calling 0000:00:13.0+ @ 2365, parent: pci0000:00
[ 105.122179] call 0000:00:13.0+ returned 0 after 14990 usecs
[ 105.128428] calling 0000:00:15.0+ @ 2365, parent: pci0000:00
[ 105.134909] call 0000:00:15.0+ returned 0 after 43 usecs
[ 105.140856] calling 0000:00:17.0+ @ 2365, parent: pci0000:00
[ 105.162230] call 0000:00:17.0+ returned 0 after 14563 usecs
[ 105.168479] calling 0000:00:1c.0+ @ 2365, parent: pci0000:00
[ 105.174981] call 0000:00:1c.0+ returned 0 after 62 usecs
[ 105.180932] calling 0000:00:1c.1+ @ 2365, parent: pci0000:00
[ 105.187430] call 0000:00:1c.1+ returned 0 after 58 usecs
[ 105.193378] calling 0000:00:1c.2+ @ 2365, parent: pci0000:00
[ 105.199874] call 0000:00:1c.2+ returned 0 after 60 usecs
[ 105.205824] calling 0000:00:1c.3+ @ 2365, parent: pci0000:00
[ 105.212321] call 0000:00:1c.3+ returned 0 after 57 usecs
[ 105.218279] calling 0000:00:1d.0+ @ 2365, parent: pci0000:00
[ 105.238461] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[ 105.245363] call 0000:00:1d.0+ returned 0 after 20129 usecs
[ 105.251607] calling 0000:00:1e.0+ @ 2365, parent: pci0000:00
[ 105.270418] call 0000:00:1e.0+ returned 0 after 12066 usecs
[ 105.276667] calling 0000:00:1e.1+ @ 2365, parent: pci0000:00
[ 105.298464] call 0000:00:1e.1+ returned 0 after 14973 usecs
[ 105.304712] calling 0000:00:1e.2+ @ 2365, parent: pci0000:00
[ 105.326515] call 0000:00:1e.2+ returned 0 after 14979 usecs
[ 105.332762] calling 0000:00:1e.4+ @ 2365, parent: pci0000:00
[ 105.354560] call 0000:00:1e.4+ returned 0 after 14976 usecs
[ 105.360807] calling 0000:00:1e.5+ @ 2365, parent: pci0000:00
[ 105.367278] call 0000:00:1e.5+ returned 0 after 34 usecs
[ 105.373228] calling 0000:00:1f.0+ @ 2365, parent: pci0000:00
[ 105.379686] call 0000:00:1f.0+ returned 0 after 24 usecs
[ 105.385640] calling 0000:00:1f.3+ @ 2365, parent: pci0000:00
[ 105.392149] call 0000:00:1f.3+ returned 0 after 73 usecs
[ 105.398101] calling 0000:04:00.0+ @ 2365, parent: 0000:00:1c.3
[ 105.418739] call 0000:04:00.0+ returned 0 after 13656 usecs
[ 105.425076] calling i8042+ @ 2365, parent: platform
[ 105.430627] call i8042+ returned 0 after 0 usecs
[ 105.435885] PM: noirq resume of devices complete after 429.458 msecs
[ 105.443164] calling 0000:00:02.0+ @ 2365, parent: pci0000:00
[ 105.457692] call 0000:00:02.0+ returned 0 after 7880 usecs
[ 105.463860] calling 0000:00:1e.0+ @ 2365, parent: pci0000:00
[ 105.470297] call 0000:00:1e.0+ returned 0 after 0 usecs
[ 105.476156] calling INT33FC:00+ @ 2365, parent: platform
[ 105.482442] byt_gpio INT33FC:00: restored pin 50 conf0 0x2003cc81
[ 105.489376] byt_gpio INT33FC:00: restored pin 73 conf0 0xf0fffff8
[ 105.496332] call INT33FC:00+ returned 0 after 13774 usecs
[ 105.502379] calling INT33FC:01+ @ 2365, parent: platform
[ 105.508417] byt_gpio INT33FC:01: restored pin 0 conf0 0x2003cd02
[ 105.515152] byt_gpio INT33FC:01: restored pin 1 conf0 0x2083cd02
[ 105.521889] byt_gpio INT33FC:01: restored pin 2 conf0 0x2003cc82
[ 105.528625] byt_gpio INT33FC:01: restored pin 3 conf0 0x2003cd02
[ 105.535360] byt_gpio INT33FC:01: restored pin 4 conf0 0x2003cd02
[ 105.542089] byt_gpio INT33FC:01: restored pin 5 conf0 0x2003cd02
[ 105.548851] byt_gpio INT33FC:01: restored pin 13 conf0 0x2003cd01
[ 105.555680] byt_gpio INT33FC:01: restored pin 14 conf0 0x2003cc81
[ 105.562560] call INT33FC:01+ returned 0 after 52786 usecs
[ 105.568609] calling INT33FC:02+ @ 2365, parent: platform
[ 105.574800] byt_gpio INT33FC:02: restored pin 27 conf0 0x2003cc80
[ 105.581719] call INT33FC:02+ returned 0 after 6892 usecs
[ 105.587849] PM: early resume of devices complete after 144.493 msecs
[ 105.594990] calling reg-dummy+ @ 2365, parent: platform
[ 105.600940] call reg-dummy+ returned 0 after 0 usecs
[ 105.606519] calling PNP0C0C:00+ @ 2365, parent: LNXSYSTM:00
[ 105.612846] call PNP0C0C:00+ returned 0 after 0 usecs
[ 105.618503] calling 0000:00:00.0+ @ 2365, parent: pci0000:00
[ 105.624945] call 0000:00:00.0+ returned 0 after 6 usecs
[ 105.630796] calling 0000:00:02.0+ @ 2365, parent: pci0000:00
[ 106.007582] call 0000:00:02.0+ returned 0 after 361049 usecs
[ 106.013934] calling 0000:00:11.0+ @ 2365, parent: pci0000:00
[ 106.022037] call 0000:00:11.0+ returned 0 after 1611 usecs
[ 106.028314] calling 0000:00:12.0+ @ 2365, parent: pci0000:00
[ 106.036356] call 0000:00:12.0+ returned 0 after 1527 usecs
[ 106.042609] calling 0000:00:13.0+ @ 2365, parent: pci0000:00
[ 106.064197] call 0000:00:13.0+ returned 0 after 14752 usecs
[ 106.070460] calling 0000:00:15.0+ @ 2365, parent: pci0000:00
[ 106.076912] call 0000:00:15.0+ returned 0 after 2 usecs
[ 106.082764] calling 0000:00:17.0+ @ 2365, parent: pci0000:00
[ 106.090939] call 0000:00:17.0+ returned 0 after 1684 usecs
[ 106.097131] calling 0000:00:1c.0+ @ 2365, parent: pci0000:00
[ 106.103588] call 0000:00:1c.0+ returned 0 after 19 usecs
[ 106.109536] calling 0000:00:1c.1+ @ 2365, parent: pci0000:00
[ 106.115982] call 0000:00:1c.1+ returned 0 after 11 usecs
[ 106.121932] calling 0000:00:1c.2+ @ 2365, parent: pci0000:00
[ 106.128383] call 0000:00:1c.2+ returned 0 after 12 usecs
[ 106.134331] calling 0000:00:1c.3+ @ 2365, parent: pci0000:00
[ 106.244499] pciehp 0000:00:1c.3:pcie04: Device 0000:04:00.0 already exists at 0000:04:00, cannot hot-add
[ 106.255143] pciehp 0000:00:1c.3:pcie04: Cannot add device at 0000:04:00
[ 106.262566] call 0000:00:1c.3+ returned 0 after 118738 usecs
[ 106.268909] calling 0000:00:1d.0+ @ 2365, parent: pci0000:00
[ 106.275452] call 0000:00:1d.0+ returned 0 after 101 usecs
[ 106.281498] calling 0000:00:1e.0+ @ 2365, parent: pci0000:00
[ 106.287935] call 0000:00:1e.0+ returned 0 after 1 usecs
[ 106.293785] calling 0000:00:1e.1+ @ 2365, parent: pci0000:00
[ 106.300219] call 0000:00:1e.1+ returned 0 after 0 usecs
[ 106.306069] calling 0000:00:1e.2+ @ 2365, parent: pci0000:00
[ 106.312505] call 0000:00:1e.2+ returned 0 after 0 usecs
[ 106.318355] calling 0000:00:1e.4+ @ 2365, parent: pci0000:00
[ 106.324795] call 0000:00:1e.4+ returned 0 after 5 usecs
[ 106.330646] calling 0000:00:1e.5+ @ 2365, parent: pci0000:00
[ 106.337088] call 0000:00:1e.5+ returned 0 after 5 usecs
[ 106.342939] calling 0000:00:1f.0+ @ 2365, parent: pci0000:00
[ 106.349365] call 0000:00:1f.0+ returned 0 after 0 usecs
[ 106.355214] calling 0000:00:1f.3+ @ 2365, parent: pci0000:00
[ 106.361648] call 0000:00:1f.3+ returned 0 after 0 usecs
[ 106.367500] calling 0000:04:00.0+ @ 2365, parent: 0000:00:1c.3
[ 106.374172] pcieport 0000:00:1c.3: System wakeup disabled by ACPI
[ 106.441673] call 0000:04:00.0+ returned 0 after 65835 usecs
[ 106.448075] calling INT0800:00+ @ 2365, parent: 0000:00:1f.0
[ 106.454513] call INT0800:00+ returned 0 after 0 usecs
[ 106.460176] calling PNP0C0C:00+ @ 2365, parent: platform
[ 106.466219] call PNP0C0C:00+ returned 0 after 0 usecs
[ 106.471878] calling LNXPWRBN:00+ @ 2365, parent: LNXSYSTM:00
[ 106.478312] call LNXPWRBN:00+ returned 0 after 0 usecs
[ 106.484070] calling 00:00+ @ 2365, parent: pnp0
[ 106.489240] call 00:00+ returned 0 after 0 usecs
[ 106.494410] calling 00:01+ @ 2365, parent: pnp0
[ 106.499578] call 00:01+ returned 0 after 0 usecs
[ 106.504751] calling 00:02+ @ 2365, parent: pnp0
[ 106.509929] call 00:02+ returned 0 after 10 usecs
[ 106.515211] calling 00:03+ @ 2365, parent: pnp0
[ 106.520385] rtc_cmos 00:03: System wakeup disabled by ACPI
[ 106.526527] call 00:03+ returned 0 after 5991 usecs
[ 106.531991] calling 00:04+ @ 2365, parent: pnp0
[ 106.537161] call 00:04+ returned 0 after 0 usecs
[ 106.542355] calling pcspkr+ @ 2365, parent: platform
[ 106.548011] call pcspkr+ returned 0 after 0 usecs
[ 106.553277] calling vesa-framebuffer.0+ @ 2365, parent: platform
[ 106.560098] call vesa-framebuffer.0+ returned 0 after 0 usecs
[ 106.566533] calling alarmtimer+ @ 2365, parent: platform
[ 106.572576] call alarmtimer+ returned 0 after 0 usecs
[ 106.578245] calling input0+ @ 2365, parent: PNP0C0C:00
[ 106.584094] call input0+ returned 0 after 0 usecs
[ 106.589363] calling input1+ @ 2365, parent: LNXPWRBN:00
[ 106.595311] call input1+ returned 0 after 0 usecs
[ 106.600578] calling serial8250+ @ 2365, parent: platform
[ 106.606625] call serial8250+ returned 0 after 2 usecs
[ 106.612324] calling Fixed MDIO bus.0+ @ 2365, parent: platform
[ 106.618952] call Fixed MDIO bus.0+ returned 0 after 0 usecs
[ 106.625193] calling usb1+ @ 2365, parent: 0000:00:1d.0
[ 106.631247] call usb1+ returned 0 after 195 usecs
[ 106.636539] calling i8042+ @ 2365, parent: platform
[ 106.645069] call i8042+ returned 0 after 2880 usecs
[ 106.650532] calling serio0+ @ 2365, parent: i8042
[ 106.655901] call serio0+ returned 0 after 3 usecs
[ 106.661209] calling serio1+ @ 2365, parent: i8042
[ 106.666580] call serio1+ returned 0 after 3 usecs
[ 106.671856] calling rtc0+ @ 2365, parent: 00:03
[ 106.677027] call rtc0+ returned 0 after 0 usecs
[ 106.682101] calling 1-1+ @ 2365, parent: usb1
[ 106.747570] call 1-1+ returned 0 after 58972 usecs
[ 106.753000] calling microcode+ @ 2365, parent: platform
[ 106.758960] call microcode+ returned 0 after 0 usecs
[ 106.764512] calling 1-1.2+ @ 2365, parent: 1-1
[ 106.830648] call 1-1.2+ returned 0 after 59525 usecs
[ 106.836238] calling 1-1.2.1+ @ 2365, parent: 1-1.2
[ 106.903067] call 1-1.2.1+ returned 0 after 59823 usecs
[ 106.908839] calling 1-1.2.1.4+ @ 2365, parent: 1-1.2.1
[ 106.974939] call 1-1.2.1.4+ returned 0 after 58742 usecs
[ 106.980907] calling ata1+ @ 2365, parent: 0000:00:13.0
[ 106.986768] call ata1+ returned 0 after 8 usecs
[ 106.991845] calling ata2+ @ 2365, parent: 0000:00:13.0
[ 106.997705] call ata2+ returned 0 after 6 usecs
[ 107.002772] calling host0+ @ 2365, parent: ata1
[ 107.007945] call host0+ returned 0 after 1 usecs
[ 107.013115] calling host1+ @ 2365, parent: ata2
[ 107.018287] call host1+ returned 0 after 0 usecs
[ 107.023459] calling 1-1.2.1.4.1+ @ 2365, parent: 1-1.2.1.4
[ 107.091641] call 1-1.2.1.4.1+ returned 0 after 60384 usecs
[ 107.097789] calling mmc0::+ @ 2365, parent: 0000:00:11.0
[ 107.103837] call mmc0::+ returned 0 after 0 usecs
[ 107.109105] calling mmc1::+ @ 2365, parent: 0000:00:12.0
[ 107.115151] call mmc1::+ returned 0 after 0 usecs
[ 107.120413] calling mmc2::+ @ 2365, parent: 0000:00:17.0
[ 107.126460] call mmc2::+ returned 0 after 0 usecs
[ 107.131731] calling input5+ @ 2365, parent: 0003:046A:0023.0001
[ 107.138470] call input5+ returned 0 after 12 usecs
[ 107.143856] calling controlD64+ @ 2365, parent: 0000:00:02.0
[ 107.150293] call controlD64+ returned 0 after 0 usecs
[ 107.155954] calling renderD128+ @ 2365, parent: 0000:00:02.0
[ 107.162390] call renderD128+ returned 0 after 0 usecs
[ 107.168049] calling card0+ @ 2365, parent: 0000:00:02.0
[ 107.174002] call card0+ returned 0 after 0 usecs
[ 107.179175] calling input6+ @ 2365, parent: 0003:046A:0023.0002
[ 107.185900] call input6+ returned 0 after 0 usecs
[ 107.191170] calling input7+ @ 2365, parent: 0003:046A:0023.0003
[ 107.197895] call input7+ returned 0 after 0 usecs
[ 107.203172] calling card0-VGA-1+ @ 2365, parent: card0
[ 107.209021] call card0-VGA-1+ returned 0 after 0 usecs
[ 107.214774] calling card0-DP-1+ @ 2365, parent: card0
[ 107.220539] call card0-DP-1+ returned 0 after 0 usecs
[ 107.226199] calling card0-HDMI-A-1+ @ 2365, parent: card0
[ 107.232342] call card0-HDMI-A-1+ returned 0 after 0 usecs
[ 107.238389] calling card0-DP-2+ @ 2365, parent: card0
[ 107.244140] call card0-DP-2+ returned 0 after 0 usecs
[ 107.249799] calling card0-HDMI-A-2+ @ 2365, parent: card0
[ 107.255941] call card0-HDMI-A-2+ returned 0 after 0 usecs
[ 107.262057] calling target1:0:0+ @ 2365, parent: host1
[ 107.267909] call target1:0:0+ returned 0 after 0 usecs
[ 107.273660] calling 1:0:0:0+ @ 2365, parent: target1:0:0
[ 107.279711] call 1:0:0:0+ returned 0 after 5 usecs
[ 107.279809] sd 1:0:0:0: [sda] Starting disk
[ 107.289764] calling mmc2:0001+ @ 2365, parent: mmc2
[ 107.306320] ata1: SATA link down (SStatus 0 SControl 300)
[ 107.318210] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 107.327916] ata2.00: configured for UDMA/133
[ 107.380517] call mmc2:0001+ returned 0 after 83052 usecs
[ 107.386512] calling parport_pc.888+ @ 2365, parent: platform
[ 107.392954] call parport_pc.888+ returned 0 after 0 usecs
[ 107.399026] calling coretemp.0+ @ 2365, parent: platform
[ 107.405077] call coretemp.0+ returned 0 after 0 usecs
[ 107.410764] calling pxa2xx-spi.0+ @ 2365, parent: 0000:00:1e.5
[ 107.417408] call pxa2xx-spi.0+ returned 0 after 9 usecs
[ 107.423277] calling input5::numlock+ @ 2365, parent: input5
[ 107.429624] call input5::numlock+ returned 0 after 0 usecs
[ 107.435773] calling input5::capslock+ @ 2365, parent: input5
[ 107.442221] call input5::capslock+ returned 0 after 0 usecs
[ 107.448473] calling input5::scrolllock+ @ 2365, parent: input5
[ 107.455112] call input5::scrolllock+ returned 0 after 0 usecs
[ 107.461553] calling iTCO_wdt.0.auto+ @ 2365, parent: 0000:00:1f.0
[ 107.468484] call iTCO_wdt.0.auto+ returned 0 after 0 usecs
[ 107.474653] PM: resume of devices complete after 1876.472 msecs
[ 107.663708] PM: Finishing wakeup.
[ 107.667482] Restarting tasks ... done.
[ 109.225751] igb 0000:04:00.0 ens3: igb: ens3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
I can't reproduce the resume problem with this pm_async setting
any more. At least all my 10 attempts were successful now.
Thanks,
Stefan
More information about the U-Boot
mailing list