PROBLEM: mtk_pcie_suspend_noirq sleep hang breaking suspend on MT8192 Asurada Spherion

Leonard Lausen leonard at lausen.nl
Tue Sep 19 18:28:09 PDT 2023


Dear AngeloGioacchino, Dear Maintainers,

on MT8192 Asurada Spherion (Acer 514), I observe the following sleep 
hang, causing a failure to suspend the system. The hang looks related to 
the deadlock you fixed for MT8195 Tomato Chromebook in the past, thus 
I'm including you in the To: line. The sleep hang happens with both 
v6.5.4 as well as v6.5.4 with tags/mediatek-drm-next-6.6 merged in. (I'm 
unable to validate v6.6-rc2 currently, as there's a regression breaking 
boot.) Please let me know if I can provide any additional information to 
help debug this issue or if you have any ideas how to address this bug.

[   42.539500] Freezing user space processes
[   42.547466] Freezing user space processes completed (elapsed 0.003 
seconds)
[   42.554563] OOM killer disabled.
[   42.557800] Freezing remaining freezable tasks
[   42.563843] Freezing remaining freezable tasks completed (elapsed 
0.001 seconds)
[   42.571243] printk: Suspending console(s) (use no_console_suspend to 
debug)
[   42.655255] queueing ieee80211 work while going to suspend
[   42.791520]
[   42.791528] ========================================================
[   42.791530] WARNING: possible irq lock inversion dependency detected
[   42.791533] 6.5.4-cos-mt9 #1 Tainted: G        W
[   42.791538] --------------------------------------------------------
[   42.791540] systemd-sleep/1138 just changed the state of lock:
[   42.791544] ffff676344fc1560 (&pcie->irq_lock){+...}-{2:2}, at: 
mtk_pcie_suspend_noirq+0xd0/0x14c
[   42.791568] but this lock was taken by another, HARDIRQ-safe lock in 
the past:
[   42.791571]  (&irq_desc_lock_class){-.-.}-{2:2}
[   42.791576]
[   42.791576]
[   42.791576] and interrupts could create inverse lock ordering between 
them.
[   42.791576]
[   42.791578]
[   42.791578] other info that might help us debug this:
[   42.791580]  Possible interrupt unsafe locking scenario:
[   42.791580]
[   42.791582]        CPU0                    CPU1
[   42.791583]        ----                    ----
[   42.791585]   lock(&pcie->irq_lock);
[   42.791589]                                local_irq_disable();
[   42.791591]                                lock(&irq_desc_lock_class);
[   42.791595]                                lock(&pcie->irq_lock);
[   42.791599]   <Interrupt>
[   42.791601]     lock(&irq_desc_lock_class);
[   42.791604]
[   42.791604]  *** DEADLOCK ***
[   42.791604]
[   42.791606] 4 locks held by systemd-sleep/1138:
[   42.791609]  #0: ffff6763486953e8 (sb_writers#6){.+.+}-{0:0}, at: 
vfs_write+0xa4/0x2e4
[   42.791628]  #1: ffff67636def1288 (&of->mutex){+.+.}-{3:3}, at: 
kernfs_fop_write_iter+0xf0/0x1b0
[   42.791643]  #2: ffff676340a89158 (kn->active#114){.+.+}-{0:0}, at: 
kernfs_fop_write_iter+0xf8/0x1b0
[   42.791658]  #3: ffffc5a79f9624a8 
(system_transition_mutex){+.+.}-{3:3}, at: pm_suspend+0x98/0x330
[   42.791673]
[   42.791673] the shortest dependencies between 2nd lock and 1st lock:
[   42.791677]  -> (&irq_desc_lock_class){-.-.}-{2:2} {
[   42.791685]     IN-HARDIRQ-W at:
[   42.791688]                       lock_acquire+0x11c/0x324
[   42.791698]                       _raw_spin_lock+0x48/0x60
[   42.791705]                       handle_fasteoi_irq+0x2c/0x234
[   42.791714]                       generic_handle_domain_irq+0x2c/0x44
[   42.791719]                       gic_handle_irq+0x180/0x2c0
[   42.791724]                       call_on_irq_stack+0x24/0x4c
[   42.791730]                       do_interrupt_handler+0x80/0x84
[   42.791736]                       el1_interrupt+0x48/0xac
[   42.791741]                       el1h_64_irq_handler+0x18/0x24
[   42.791747]                       el1h_64_irq+0x78/0x7c
[   42.791750]                       default_idle_call+0xa0/0x14c
[   42.791756]                       do_idle+0x270/0x2d0
[   42.791762]                       cpu_startup_entry+0x24/0x2c
[   42.791767]                       rest_init+0x118/0x1a8
[   42.791773]                       arch_post_acpi_subsys_init+0x0/0x8
[   42.791781]                       start_kernel+0x5b8/0x6ac
[   42.791785]                       __primary_switched+0xbc/0xc4
[   42.791790]     IN-SOFTIRQ-W at:
[   42.791793]                       lock_acquire+0x11c/0x324
[   42.791801]                       _raw_spin_lock+0x48/0x60
[   42.791805]                       handle_fasteoi_irq+0x2c/0x234
[   42.791811]                       generic_handle_domain_irq+0x2c/0x44
[   42.791816]                       gic_handle_irq+0x180/0x2c0
[   42.791820]                       do_interrupt_handler+0x50/0x84
[   42.791826]                       el1_interrupt+0x48/0xac
[   42.791830]                       el1h_64_irq_handler+0x18/0x24
[   42.791835]                       el1h_64_irq+0x78/0x7c
[   42.791839]                       _nohz_idle_balance.isra.0+0x280/0x384
[   42.791847]                       run_rebalance_domains+0x64/0x74
[   42.791854]                       __do_softirq+0x148/0x51c
[   42.791858]                       ____do_softirq+0x10/0x1c
[   42.791863]                       call_on_irq_stack+0x24/0x4c
[   42.791868]                       do_softirq_own_stack+0x1c/0x2c
[   42.791873]                       __irq_exit_rcu+0x144/0x160
[   42.791881]                       irq_exit_rcu+0x10/0x38
[   42.791887]                       el1_interrupt+0x4c/0xac
[   42.791892]                       el1h_64_irq_handler+0x18/0x24
[   42.791897]                       el1h_64_irq+0x78/0x7c
[   42.791900]                       default_idle_call+0xa0/0x14c
[   42.791906]                       do_idle+0x270/0x2d0
[   42.791910]                       cpu_startup_entry+0x24/0x2c
[   42.791915]                       rest_init+0x118/0x1a8
[   42.791921]                       arch_post_acpi_subsys_init+0x0/0x8
[   42.791926]                       start_kernel+0x5b8/0x6ac
[   42.791931]                       __primary_switched+0xbc/0xc4
[   42.791935]     INITIAL USE at:
[   42.791938]                      lock_acquire+0x11c/0x324
[   42.791945]                      _raw_spin_lock_irqsave+0x68/0xbc
[   42.791950]                      __irq_get_desc_lock+0x58/0x98
[   42.791955]                      irq_modify_status+0x38/0x140
[   42.791962]                      irq_set_percpu_devid+0x70/0x94
[   42.791967]                      gic_irq_domain_alloc+0x208/0x248
[   42.791976]                      irq_domain_alloc_irqs_locked+0xf8/0x358
[   42.791981]                      __irq_domain_alloc_irqs+0x6c/0xc0
[   42.791986]                      gic_init_bases+0x34c/0x6bc
[   42.791992]                      gic_of_init+0x290/0x314
[   42.791997]                      of_irq_init+0x304/0x390
[   42.792004]                      irqchip_init+0x18/0x24
[   42.792008]                      init_IRQ+0xb4/0x15c
[   42.792014]                      start_kernel+0x26c/0x6ac
[   42.792019]                      __primary_switched+0xbc/0xc4
[   42.792022]   }
[   42.792024]   ... key      at: [<ffffc5a7a0780f60>] 
irq_desc_lock_class+0x0/0x10
[   42.792034]   ... acquired at:
[   42.792036]    _raw_spin_lock_irqsave+0x68/0xbc
[   42.792041]    mtk_msi_bottom_irq_unmask+0x30/0x70
[   42.792047]    irq_chip_unmask_parent+0x1c/0x28
[   42.792054]    mtk_pcie_msi_irq_unmask+0x20/0x30
[   42.792060]    irq_enable+0x40/0x8c
[   42.792067]    __irq_startup+0x78/0xa4
[   42.792074]    irq_startup+0xfc/0x164
[   42.792081]    __setup_irq+0x43c/0x784
[   42.792087]    request_threaded_irq+0xec/0x1a4
[   42.792093]    pcie_pme_probe+0xf4/0x1bc
[   42.792101]    pcie_port_probe_service+0x38/0x64
[   42.792107]    really_probe+0x148/0x2ac
[   42.792115]    __driver_probe_device+0x78/0x12c
[   42.792122]    driver_probe_device+0x3c/0x160
[   42.792128]    __device_attach_driver+0xb8/0x138
[   42.792135]    bus_for_each_drv+0x80/0xdc
[   42.792141]    __device_attach+0x9c/0x188
[   42.792148]    device_initial_probe+0x14/0x20
[   42.792155]    bus_probe_device+0xac/0xb0
[   42.792161]    device_add+0x5bc/0x778
[   42.792166]    device_register+0x20/0x30
[   42.792171]    pcie_portdrv_probe+0x340/0x5c4
[   42.792176]    local_pci_probe+0x40/0xa4
[   42.792183]    pci_device_probe+0xac/0x1e0
[   42.792189]    really_probe+0x148/0x2ac
[   42.792195]    __driver_probe_device+0x78/0x12c
[   42.792202]    driver_probe_device+0x3c/0x160
[   42.792208]    __device_attach_driver+0xb8/0x138
[   42.792215]    bus_for_each_drv+0x80/0xdc
[   42.792221]    __device_attach+0x9c/0x188
[   42.792227]    device_attach+0x14/0x20
[   42.792234]    pci_bus_add_device+0x64/0xd4
[   42.792239]    pci_bus_add_devices+0x3c/0x88
[   42.792243]    pci_host_probe+0x44/0xbc
[   42.792249]    mtk_pcie_probe+0x288/0x47c
[   42.792255]    platform_probe+0x68/0xc4
[   42.792260]    really_probe+0x148/0x2ac
[   42.792266]    __driver_probe_device+0x78/0x12c
[   42.792272]    driver_probe_device+0x3c/0x160
[   42.792279]    __device_attach_driver+0xb8/0x138
[   42.792285]    bus_for_each_drv+0x80/0xdc
[   42.792291]    __device_attach+0x9c/0x188
[   42.792298]    device_initial_probe+0x14/0x20
[   42.792304]    bus_probe_device+0xac/0xb0
[   42.792311]    deferred_probe_work_func+0x8c/0xc8
[   42.792317]    process_one_work+0x2d0/0x598
[   42.792324]    worker_thread+0x70/0x434
[   42.792329]    kthread+0xfc/0x100
[   42.792333]    ret_from_fork+0x10/0x20
[   42.792338]
[   42.792340] -> (&pcie->irq_lock){+...}-{2:2} {
[   42.792347]    HARDIRQ-ON-W at:
[   42.792350]                     lock_acquire+0x11c/0x324
[   42.792357]                     _raw_spin_lock+0x48/0x60
[   42.792361]                     mtk_pcie_suspend_noirq+0xd0/0x14c
[   42.792367]                     dpm_run_callback+0x34/0x9c
[   42.792376]                     __device_suspend_noirq+0x68/0x1f0
[   42.792384]                     dpm_noirq_suspend_devices+0x1b8/0x24c
[   42.792391]                     dpm_suspend_noirq+0x24/0x98
[   42.792395]                     suspend_devices_and_enter+0x3b0/0x65c
[   42.792401]                     pm_suspend+0x1fc/0x330
[   42.792406]                     state_store+0x80/0xec
[   42.792411]                     kobj_attr_store+0x18/0x2c
[   42.792418]                     sysfs_kf_write+0x4c/0x78
[   42.792425]                     kernfs_fop_write_iter+0x120/0x1b0
[   42.792431]                     vfs_write+0x1a4/0x2e4
[   42.792437]                     ksys_write+0x6c/0x100
[   42.792443]                     __arm64_sys_write+0x1c/0x28
[   42.792449]                     invoke_syscall+0x48/0x114
[   42.792456]                     el0_svc_common.constprop.0+0x64/0x138
[   42.792464]                     do_el0_svc+0x38/0x98
[   42.792471]                     el0_svc+0x40/0xe0
[   42.792475]                     el0t_64_sync_handler+0x100/0x12c
[   42.792480]                     el0t_64_sync+0x1a4/0x1a8
[   42.792484]    INITIAL USE at:
[   42.792487]                    lock_acquire+0x11c/0x324
[   42.792494]                    _raw_spin_lock_irqsave+0x68/0xbc
[   42.792499]                    mtk_msi_bottom_irq_unmask+0x30/0x70
[   42.792505]                    irq_chip_unmask_parent+0x1c/0x28
[   42.792512]                    mtk_pcie_msi_irq_unmask+0x20/0x30
[   42.792518]                    irq_enable+0x40/0x8c
[   42.792525]                    __irq_startup+0x78/0xa4
[   42.792532]                    irq_startup+0xfc/0x164
[   42.792539]                    __setup_irq+0x43c/0x784
[   42.792545]                    request_threaded_irq+0xec/0x1a4
[   42.792550]                    pcie_pme_probe+0xf4/0x1bc
[   42.792557]                    pcie_port_probe_service+0x38/0x64
[   42.792563]                    really_probe+0x148/0x2ac
[   42.792569]                    __driver_probe_device+0x78/0x12c
[   42.792576]                    driver_probe_device+0x3c/0x160
[   42.792582]                    __device_attach_driver+0xb8/0x138
[   42.792589]                    bus_for_each_drv+0x80/0xdc
[   42.792595]                    __device_attach+0x9c/0x188
[   42.792601]                    device_initial_probe+0x14/0x20
[   42.792608]                    bus_probe_device+0xac/0xb0
[   42.792614]                    device_add+0x5bc/0x778
[   42.792619]                    device_register+0x20/0x30
[   42.792624]                    pcie_portdrv_probe+0x340/0x5c4
[   42.792630]                    local_pci_probe+0x40/0xa4
[   42.792635]                    pci_device_probe+0xac/0x1e0
[   42.792640]                    really_probe+0x148/0x2ac
[   42.792647]                    __driver_probe_device+0x78/0x12c
[   42.792653]                    driver_probe_device+0x3c/0x160
[   42.792660]                    __device_attach_driver+0xb8/0x138
[   42.792666]                    bus_for_each_drv+0x80/0xdc
[   42.792672]                    __device_attach+0x9c/0x188
[   42.792679]                    device_attach+0x14/0x20
[   42.792685]                    pci_bus_add_device+0x64/0xd4
[   42.792690]                    pci_bus_add_devices+0x3c/0x88
[   42.792694]                    pci_host_probe+0x44/0xbc
[   42.792700]                    mtk_pcie_probe+0x288/0x47c
[   42.792706]                    platform_probe+0x68/0xc4
[   42.792710]                    really_probe+0x148/0x2ac
[   42.792716]                    __driver_probe_device+0x78/0x12c
[   42.792723]                    driver_probe_device+0x3c/0x160
[   42.792729]                    __device_attach_driver+0xb8/0x138
[   42.792736]                    bus_for_each_drv+0x80/0xdc
[   42.792742]                    __device_attach+0x9c/0x188
[   42.792748]                    device_initial_probe+0x14/0x20
[   42.792755]                    bus_probe_device+0xac/0xb0
[   42.792761]                    deferred_probe_work_func+0x8c/0xc8
[   42.792767]                    process_one_work+0x2d0/0x598
[   42.792773]                    worker_thread+0x70/0x434
[   42.792778]                    kthread+0xfc/0x100
[   42.792782]                    ret_from_fork+0x10/0x20
[   42.792787]  }
[   42.792788]  ... key      at: [<ffffc5a7a07bdfa0>] __key.1+0x0/0x10
[   42.792794]  ... acquired at:
[   42.792796]    __lock_acquire+0x73c/0x1970
[   42.792803]    lock_acquire+0x11c/0x324
[   42.792810]    _raw_spin_lock+0x48/0x60
[   42.792814]    mtk_pcie_suspend_noirq+0xd0/0x14c
[   42.792821]    dpm_run_callback+0x34/0x9c
[   42.792828]    __device_suspend_noirq+0x68/0x1f0
[   42.792835]    dpm_noirq_suspend_devices+0x1b8/0x24c
[   42.792842]    dpm_suspend_noirq+0x24/0x98
[   42.792846]    suspend_devices_and_enter+0x3b0/0x65c
[   42.792852]    pm_suspend+0x1fc/0x330
[   42.792857]    state_store+0x80/0xec
[   42.792862]    kobj_attr_store+0x18/0x2c
[   42.792868]    sysfs_kf_write+0x4c/0x78
[   42.792874]    kernfs_fop_write_iter+0x120/0x1b0
[   42.792880]    vfs_write+0x1a4/0x2e4
[   42.792886]    ksys_write+0x6c/0x100
[   42.792892]    __arm64_sys_write+0x1c/0x28
[   42.792898]    invoke_syscall+0x48/0x114
[   42.792905]    el0_svc_common.constprop.0+0x64/0x138
[   42.792912]    do_el0_svc+0x38/0x98
[   42.792919]    el0_svc+0x40/0xe0
[   42.792923]    el0t_64_sync_handler+0x100/0x12c
[   42.792928]    el0t_64_sync+0x1a4/0x1a8
[   42.792932]
[   42.792933]
[   42.792933] stack backtrace:
[   42.792936] CPU: 4 PID: 1138 Comm: systemd-sleep Tainted: G        W 
         6.5.4-cos-mt9 #1
[   42.792942] Hardware name: Google Spherion (rev0 - 3) (DT)
[   42.792945] Call trace:
[   42.792947]  dump_backtrace+0x98/0xf0
[   42.792951]  show_stack+0x18/0x24
[   42.792955]  dump_stack_lvl+0xb8/0x118
[   42.792960]  dump_stack+0x18/0x24
[   42.792964]  print_irq_inversion_bug.part.0+0x1ec/0x27c
[   42.792971]  mark_lock+0x300/0x720
[   42.792978]  __lock_acquire+0x73c/0x1970
[   42.792985]  lock_acquire+0x11c/0x324
[   42.792993]  _raw_spin_lock+0x48/0x60
[   42.792996]  mtk_pcie_suspend_noirq+0xd0/0x14c
[   42.793003]  dpm_run_callback+0x34/0x9c
[   42.793010]  __device_suspend_noirq+0x68/0x1f0
[   42.793017]  dpm_noirq_suspend_devices+0x1b8/0x24c
[   42.793025]  dpm_suspend_noirq+0x24/0x98
[   42.793028]  suspend_devices_and_enter+0x3b0/0x65c
[   42.793033]  pm_suspend+0x1fc/0x330
[   42.793039]  state_store+0x80/0xec
[   42.793043]  kobj_attr_store+0x18/0x2c
[   42.793050]  sysfs_kf_write+0x4c/0x78
[   42.793056]  kernfs_fop_write_iter+0x120/0x1b0
[   42.793062]  vfs_write+0x1a4/0x2e4
[   42.793068]  ksys_write+0x6c/0x100
[   42.793073]  __arm64_sys_write+0x1c/0x28
[   42.793079]  invoke_syscall+0x48/0x114
[   42.793086]  el0_svc_common.constprop.0+0x64/0x138
[   42.793093]  do_el0_svc+0x38/0x98
[   42.793100]  el0_svc+0x40/0xe0
[   42.793105]  el0t_64_sync_handler+0x100/0x12c
[   42.793110]  el0t_64_sync+0x1a4/0x1a8
[   42.796314] Disabling non-boot CPUs ...
[   42.799859] IRQ273: set affinity failed(-22).
[   42.799921] IRQ290: set affinity failed(-22).
[   42.800023] psci: CPU1 killed (polled 0 ms)
[   42.803929] IRQ273: set affinity failed(-22).
[   42.803982] IRQ290: set affinity failed(-22).
[   42.804336] psci: CPU2 killed (polled 1 ms)
[   42.807855] IRQ273: set affinity failed(-22).
[   42.807907] IRQ290: set affinity failed(-22).
[   42.807992] psci: CPU3 killed (polled 0 ms)
[   42.809756] IRQ273: set affinity failed(-22).
[   42.809775] IRQ290: set affinity failed(-22).
[   42.809829] psci: CPU4 killed (polled 0 ms)
[   42.813594] IRQ273: set affinity failed(-22).
[   42.813616] IRQ290: set affinity failed(-22).
[   42.813672] psci: CPU5 killed (polled 0 ms)
[   42.816944] psci: CPU6 killed (polled 0 ms)
[   42.818815] psci: CPU7 killed (polled 0 ms)
[   42.821422] Enabling non-boot CPUs ...
[   42.822247] Detected VIPT I-cache on CPU1
[   42.822306] GICv3: CPU1: found redistributor 100 region 
0:0x000000000c060000
[   42.822376] CPU1: Booted secondary processor 0x0000000100 [0x412fd050]
[   42.824953] CPU1 is up
[   42.825773] Detected VIPT I-cache on CPU2
[   42.825822] GICv3: CPU2: found redistributor 200 region 
0:0x000000000c080000
[   42.825880] CPU2: Booted secondary processor 0x0000000200 [0x412fd050]
[   42.828463] CPU2 is up
[   42.829162] Detected VIPT I-cache on CPU3
[   42.829206] GICv3: CPU3: found redistributor 300 region 
0:0x000000000c0a0000
[   42.829256] CPU3: Booted secondary processor 0x0000000300 [0x412fd050]
[   42.831963] CPU3 is up
[   42.832750] CPU features: detected: Hardware dirty bit management
[   42.832778] Detected PIPT I-cache on CPU4
[   42.832799] GICv3: CPU4: found redistributor 400 region 
0:0x000000000c0c0000
[   42.832824] CPU4: Booted secondary processor 0x0000000400 [0x414fd0b0]
[   42.834001] CPU4 is up
[   42.834870] Detected PIPT I-cache on CPU5
[   42.834895] GICv3: CPU5: found redistributor 500 region 
0:0x000000000c0e0000
[   42.834920] CPU5: Booted secondary processor 0x0000000500 [0x414fd0b0]
[   42.836053] CPU5 is up
[   42.836871] Detected PIPT I-cache on CPU6
[   42.836897] GICv3: CPU6: found redistributor 600 region 
0:0x000000000c100000
[   42.836921] CPU6: Booted secondary processor 0x0000000600 [0x414fd0b0]
[   42.838100] CPU6 is up
[   42.838936] Detected PIPT I-cache on CPU7
[   42.838962] GICv3: CPU7: found redistributor 700 region 
0:0x000000000c120000
[   42.838986] CPU7: Booted secondary processor 0x0000000700 [0x414fd0b0]
[   42.840235] CPU7 is up
[   42.978808] ------------[ cut here ]------------
[   42.978823] EC detected sleep transition timeout. Total sleep 
transitions: 0
[   42.978919] WARNING: CPU: 0 PID: 1138 at 
drivers/platform/chrome/cros_ec.c:142 cros_ec_sleep_event.isra.0+0xf8/0x104
[   42.978952] Modules linked in: rfcomm qrtr bnep btusb btrtl btintel 
mt7921e btmtk btbcm mt7921_common mt76_connac_lib mt76 bluetooth 
mtk_vcodec_dec_hw ecdh_generic mac80211 ecc mtk_vcodec_dec uvcvideo uvc 
v4l2_vp9 videobuf2_vmalloc cfg80211 mtk_vcodec_enc v4l2_h264 
mtk_vcodec_dbgfs mtk_vcodec_common mtk_vpu elants_i2c rfkill elan_i2c 
tpm_tis_spi cros_usbpd_logger tpm_tis_core cros_usbpd_charger 
sbs_battery coreboot_table fuse ip_tables x_tables ipv6
[   42.979171] CPU: 0 PID: 1138 Comm: systemd-sleep Tainted: G        W 
         6.5.4-cos-mt9 #1
[   42.979184] Hardware name: Google Spherion (rev0 - 3) (DT)
[   42.979191] pstate: 60400009 (nZCv daif +PAN -UAO -TCO -DIT -SSBS 
BTYPE=--)
[   42.979203] pc : cros_ec_sleep_event.isra.0+0xf8/0x104
[   42.979219] lr : cros_ec_sleep_event.isra.0+0xf8/0x104
[   42.979233] sp : ffff8000831fba40
[   42.979239] x29: ffff8000831fba40 x28: ffff676363e38000 x27: 
ffffc5a79f1cd938
[   42.979261] x26: ffffc5a79fc8ac38 x25: ffffc5a79f968abc x24: 
0000000000000010
[   42.979282] x23: ffffc5a7a07c7fe0 x22: ffff676340e2f080 x21: 
ffffc5a79e866dc4
[   42.979303] x20: 0000000000000002 x19: ffff676343034480 x18: 
ffffffffffffffff
[   42.979323] x17: 0000000000000000 x16: 0000000000000001 x15: 
ffffffffffffffff
[   42.979343] x14: ffffc5a7a07520a1 x13: ffffc5a7a075209f x12: 
736e617274207065
[   42.979364] x11: 656c73206c61746f x10: 54202e74756f656d x9 : 
6d6974206e6f6974
[   42.979384] x8 : ffffc5a79f49c008 x7 : 0000000000000000 x6 : 
ffffc5a79db3df70
[   42.979404] x5 : ffff67647ee9ec08 x4 : ffffa1bcdfa00000 x3 : 
ffff676363e38000
[   42.979424] x2 : 0000000000000000 x1 : 0000000000000000 x0 : 
ffff676363e38000
[   42.979444] Call trace:
[   42.979449]  cros_ec_sleep_event.isra.0+0xf8/0x104
[   42.979464]  cros_ec_resume+0x3c/0xec
[   42.979479]  cros_ec_spi_resume+0x14/0x20
[   42.979489]  dpm_run_callback+0x34/0x9c
[   42.979505]  device_resume+0x8c/0x198
[   42.979519]  dpm_resume+0x12c/0x258
[   42.979529]  dpm_resume_end+0x18/0x30
[   42.979539]  suspend_devices_and_enter+0xbc/0x65c
[   42.979554]  pm_suspend+0x1fc/0x330
[   42.979566]  state_store+0x80/0xec
[   42.979576]  kobj_attr_store+0x18/0x2c
[   42.979592]  sysfs_kf_write+0x4c/0x78
[   42.979607]  kernfs_fop_write_iter+0x120/0x1b0
[   42.979621]  vfs_write+0x1a4/0x2e4
[   42.979635]  ksys_write+0x6c/0x100
[   42.979647]  __arm64_sys_write+0x1c/0x28
[   42.979659]  invoke_syscall+0x48/0x114
[   42.979677]  el0_svc_common.constprop.0+0x64/0x138
[   42.979691]  do_el0_svc+0x38/0x98
[   42.979704]  el0_svc+0x40/0xe0
[   42.979716]  el0t_64_sync_handler+0x100/0x12c
[   42.979727]  el0t_64_sync+0x1a4/0x1a8
[   42.979739] irq event stamp: 66299
[   42.979745] hardirqs last  enabled at (66299): [<ffffc5a79eb89890>] 
__schedule+0xab8/0xbe8
[   42.979761] hardirqs last disabled at (66298): [<ffffc5a79eb891d0>] 
__schedule+0x3f8/0xbe8
[   42.979776] softirqs last  enabled at (65704): [<ffffc5a79da10794>] 
__do_softirq+0x424/0x51c
[   42.979788] softirqs last disabled at (65699): [<ffffc5a79da16af0>] 
____do_softirq+0x10/0x1c
[   42.979803] ---[ end trace 0000000000000000 ]---
[   45.407309] OOM killer enabled.
[   45.410445] Restarting tasks ... done.
[   45.414855] random: crng reseeded on system resumption
[   45.423237] PM: suspend exit

Please also find the CrOS EC log starting with the first message printed 
after "suspend" begins:

[651878.494408 HC 0x400b err 1]
[651878.497188 HC 0x8d err 1]
[651878.501734 HC 0x2b err 1]
[651878.505423 HC 0x134 err 1]
[651878.510886 HC 0x26 err 3]
[651878.917783 Handle sleep: 0]
[651878.931282 HC 0x67 err 9]
[651891.650633 Unhandled VB reg 11]
[651891.654386 Unhandled VB reg 11]
[651891.688083 Unhandled VB reg 11]
[651891.690206 Unhandled VB reg 11]
[651891.865486 Unhandled VB reg 11]
[651891.867628 Unhandled VB reg 11]
[651891.953322 Unhandled VB reg 11]
[651891.957136 Unhandled VB reg 11]
[651892.034630 Unhandled VB reg 11]
[651892.038440 Unhandled VB reg 11]
[651892.073436 Unhandled VB reg 11]
[651892.075545 Unhandled VB reg 11]
[651892.097180 Unhandled VB reg 11]
[651892.099232 Unhandled VB reg 11]
[651892.314986 Unhandled VB reg 11]
[651892.319744 Unhandled VB reg 11]
[651892.327483 Unhandled VB reg 11]
[651892.350593 Unhandled VB reg 11]
[651892.355511 Unhandled VB reg 11]
[651892.371166 Unhandled VB reg 11]
[651892.374825 Unhandled VB reg 11]
[651892.411053 Unhandled VB reg 11]
[651892.415335 Unhandled VB reg 11]
[651892.479204 Unhandled VB reg 11]
[651892.481260 Unhandled VB reg 11]
[651892.500444 Unhandled VB reg 11]
[651892.504106 Unhandled VB reg 11]
[651918.803375 Handle sleep: 1]
[651928.805549 Warning: Detected sleep hang! Waking host up!]
[651928.806288 event set 0x0000000000080000]
[651928.966033 Handle sleep: 2]
[651928.970547 HC 0x67 err 9]




More information about the Linux-mediatek mailing list