igt@gem_eio@hibernate - Incomplete - watchdog: BUG: soft lockup - CPU#\d+ stuck for \d+s!
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_10340/shard-rkl-5/igt@gem_eio@hibernate.html
<7> [487.710670] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 3
<7> [487.710749] i915 0000:00:02.0: [drm:i915_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x8010
<7> [487.724412] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling power well 3
<7> [487.724586] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC off
<7> [487.724657] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [487.724720] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.15 [i915]] Setting DC state from 00 to 02
<7> [487.730963] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<3> [487.792472] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
<6> [487.871354] ata2: SATA link down (SStatus 4 SControl 300)
<6> [487.871639] ata4: SATA link down (SStatus 4 SControl 300)
<6> [487.871765] ata3: SATA link down (SStatus 4 SControl 300)
<6> [487.958890] usb 1-6: reset high-speed USB device number 2 using xhci_hcd
<6> [488.091866] smsc75xx 1-6:1.0 enx803f5d096780: resuming from SUSPEND2
<3> [488.168480] atkbd serio0: Failed to enable keyboard on isa0060/serio0
<0> [512.495170] watchdog: BUG: soft lockup - CPU#10 stuck for 26s! [kworker/u24:9:1270]
<4> [512.495181] Modules linked in: vgem snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio i915 mei_hdcp snd_hda_intel snd_intel_dspcfg x86_pkg_temp_thermal smsc75xx snd_hda_codec usbnet coretemp mii crct10dif_pclmul snd_hwdep crc32_pclmul snd_hda_core ghash_clmulni_intel igc mei_me snd_pcm e1000e intel_lpss_pci mei ttm ptp pps_core prime_numbers
<4> [512.495212] irq event stamp: 4526325
<4> [512.495214] hardirqs last enabled at (4526324): [<ffffffff81adf130>] _raw_spin_unlock_irqrestore+0x50/0x60
<4> [512.495220] hardirqs last disabled at (4526325): [<ffffffff81ad321a>] sysvec_apic_timer_interrupt+0xa/0xb0
<4> [512.495223] softirqs last enabled at (2286): [<ffffffff81e0032d>] __do_softirq+0x32d/0x493
<4> [512.495226] softirqs last disabled at (2289): [<ffffffff810b74d8>] irq_exit_rcu+0xe8/0xf0
<4> [512.495229] CPU: 10 PID: 1270 Comm: kworker/u24:9 Not tainted 5.14.0-rc1-CI-CI_DRM_10340+ #1
<4> [512.495229] Hardware name: Intel Corporation Rocket Lake Client Platform/RocketLake S SODIMM RVP, BIOS RKLSFWI1.R00.1435.A00.2010232019 10/23/2020
<4> [512.495229] Workqueue: events_unbound async_run_entry_fn
<4> [512.495229] RIP: 0010:_raw_spin_unlock_irqrestore+0x38/0x60
<4> [512.495229] Code: 74 24 10 e8 8a 1f 64 ff 48 89 ef e8 02 4c 64 ff 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2c 48 85 db 74 01 fb bf 01 00 00 00 <e8> 03 66 60 ff 65 8b 05 dc 7d 53 7e 85 c0 74 0a 5b 5d c3 e8 b0 cd
<4> [512.495229] RSP: 0018:ffffc90000344e80 EFLAGS: 00000206
<4> [512.495229] RAX: 0000000000000012 RBX: 0000000000000200 RCX: 0000000000000002
<4> [512.495229] RDX: 0000000000000000 RSI: ffffffff823911b7 RDI: 0000000000000001
<4> [512.495229] RBP: ffff88810eb12d28 R08: 0000000000000001 R09: 0000000000000001
<4> [512.495229] R10: 00000000486c3837 R11: 00000000fcfb2e4f R12: ffff88831214e640
<4> [512.495229] R13: ffff88810eb12d28 R14: 0000000000000000 R15: 0000000000000282
<4> [512.495229] FS: 0000000000000000(0000) GS:ffff888450500000(0000) knlGS:0000000000000000
<4> [512.495229] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [512.495229] CR2: 000055bb3c922ad8 CR3: 0000000006612003 CR4: 0000000000770ee0
<4> [512.495229] PKRU: 55555554
<4> [512.495229] Call Trace:
<4> [512.495229] <IRQ>
<4> [512.495229] rx_submit+0x1e6/0x2f0 [usbnet]
<4> [512.495229] rx_complete+0x21e/0x2c0 [usbnet]
<4> [512.495229] __usb_hcd_giveback_urb+0x4d/0xb0
<4> [512.495229] usb_giveback_urb_bh+0xa2/0x100
<4> [512.495229] tasklet_action_common.isra.21+0xb3/0xf0
<4> [512.495229] __do_softirq+0xdc/0x493
<4> [512.495229] irq_exit_rcu+0xe8/0xf0
<4> [512.495229] common_interrupt+0x9b/0xc0
<4> [512.495229] </IRQ>
<4> [512.495229] asm_common_interrupt+0x1e/0x40
<4> [512.495229] RIP: 0010:delay_tsc+0x2e/0xb0
<4> [512.495229] Code: 49 89 fc bf 01 00 00 00 55 53 e8 4d b2 b1 ff e8 e8 95 50 00 41 89 c5 0f 01 f9 66 90 48 c1 e2 20 48 09 c2 48 89 d5 eb 16 f3 90 <bf> 01 00 00 00 e8 28 b2 b1 ff e8 c3 95 50 00 44 39 e8 75 36 0f 01
<4> [512.495229] RSP: 0018:ffffc90000f2fc90 EFLAGS: 00000286
<4> [512.495229] RAX: 0000000080000000 RBX: 00000144d583853b RCX: 0000000000000001
<4> [512.495229] RDX: 0000000080000001 RSI: ffffffff8230974d RDI: 00000000ffffffff
<4> [512.495229] RBP: 00000144d583607c R08: 00000000043f6080 R09: 00000000fffffffe
<4> [512.495229] R10: 0000000000000001 R11: 00000000fcfb2e4f R12: 00000000000211b2
<4> [512.495229] R13: 000000000000000a R14: 0000000000000001 R15: 0000000000000304
<4> [512.495229] e1000e_write_phy_reg_mdic+0x51/0x170 [e1000e]
<4> [512.495229] ? e1000_acquire_swflag_ich8lan+0x50/0x130 [e1000e]
<4> [512.495229] __e1000_write_phy_reg_hv+0x1af/0x200 [e1000e]
<4> [512.495229] e1000e_pm_resume+0x2eb/0x3f0 [e1000e]
<4> [512.495229] ? pci_pm_resume_noirq+0x100/0x100
<4> [512.495229] dpm_run_callback+0x5a/0x240
<4> [512.495229] device_resume+0xb2/0x1e0
<4> [512.495229] ? pm_dev_err+0x25/0x25
<4> [512.495229] async_resume+0x14/0x30
<4> [512.495229] async_run_entry_fn+0x28/0x130
<4> [512.495229] process_one_work+0x26d/0x5c0
<4> [512.495229] worker_thread+0x37/0x390
<4> [512.495229] ? process_one_work+0x5c0/0x5c0
<4> [512.495229] kthread+0x13b/0x170
<4> [512.495229] ? set_kthread_struct+0x40/0x40
<4> [512.495229] ret_from_fork+0x1f/0x30
<7> [537.008758] PM: hibernation: Basic memory bitmaps freed
<6> [537.008762] OOM killer enabled.
<6> [537.008764] Restarting tasks ...
<7> [537.009841] i915 0000:00:02.0: [drm:i915_hdcp_component_bind [i915]] I915 HDCP comp bind
<6> [537.009971] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
<4> [537.012407] done.
<6> [537.029037] video LNXVIDEO:00: Restoring backlight state
<6> [537.029281] PM: hibernation: hibernation exit
<7> [537.031721] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<6> [537.054973] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000
<7> [537.136376] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<5> [538.699566] i915 0000:00:02.0: [drm] Resetting chip for Manually set wedged engine mask = ffffffffffffffff
<7> [538.699813] i915 0000:00:02.0: [drm:intel_gt_reset [i915]] GPU reset disabled
<3> [544.169578] i915 0000:00:02.0: [drm] *ERROR* intel_gt_reset_global timed out, cancelling all in-flight rendering.
<6> [550.093982] perf: interrupt took too long (3695 > 3207), lowering kernel.perf_event_max_sample_rate to 54000
<0> [560.495161] watchdog: BUG: soft lockup - CPU#10 stuck for 23s! [migration/10:61]
<4> [560.495226] Modules linked in: vgem snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio i915 mei_hdcp snd_hda_intel snd_intel_dspcfg x86_pkg_temp_thermal smsc75xx snd_hda_codec usbnet coretemp mii crct10dif_pclmul snd_hwdep crc32_pclmul snd_hda_core ghash_clmulni_intel igc mei_me snd_pcm e1000e intel_lpss_pci mei ttm ptp pps_core prime_numbers
<4> [560.495226] irq event stamp: 3920097
<4> [560.495226] hardirqs last enabled at (3920096): [<ffffffff81adf130>] _raw_spin_unlock_irqrestore+0x50/0x60
<4> [560.495226] hardirqs last disabled at (3920097): [<ffffffff81ad321a>] sysvec_apic_timer_interrupt+0xa/0xb0
<4> [560.495226] softirqs last enabled at (98): [<ffffffff81e0032d>] __do_softirq+0x32d/0x493
<4> [560.495226] softirqs last disabled at (1583): [<ffffffff810b74d8>] irq_exit_rcu+0xe8/0xf0
<4> [560.495226] CPU: 10 PID: 61 Comm: migration/10 Tainted: G W L 5.14.0-rc1-CI-CI_DRM_10340+ #1
<4> [560.495226] Hardware name: Intel Corporation Rocket Lake Client Platform/RocketLake S SODIMM RVP, BIOS RKLSFWI1.R00.1435.A00.2010232019 10/23/2020
<4> [560.495226] Stopper: 0x0 <- 0x0
<4> [560.495226] RIP: 0010:_raw_spin_unlock_irqrestore+0x38/0x60
<4> [560.495226] Code: 74 24 10 e8 8a 1f 64 ff 48 89 ef e8 02 4c 64 ff 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2c 48 85 db 74 01 fb bf 01 00 00 00 <e8> 03 66 60 ff 65 8b 05 dc 7d 53 7e 85 c0 74 0a 5b 5d c3 e8 b0 cd
<4> [560.495226] RSP: 0018:ffffc90000344e80 EFLAGS: 00000206
<4> [560.495226] RAX: 0000000000000006 RBX: 0000000000000200 RCX: ffffc90000344e44
<4> [560.495226] RDX: 0000000000000000 RSI: ffffffff823911b7 RDI: 0000000000000001
<4> [560.495226] RBP: ffff88810eb12d28 R08: 0000000000000001 R09: 0000000000000001
<4> [560.495226] R10: 0000000000000001 R11: 0000000000000000 R12: ffff8882bb12b0c0
<4> [560.495226] R13: ffff88810eb12d28 R14: 0000000000000000 R15: 0000000000000286
<4> [560.495226] FS: 0000000000000000(0000) GS:ffff888450500000(0000) knlGS:0000000000000000
<4> [560.495226] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [560.495226] CR2: 000055bb3c922ad8 CR3: 0000000006612003 CR4: 0000000000770ee0
<4> [560.495226] PKRU: 55555554
<4> [560.495226] Call Trace:
<4> [560.495226] <IRQ>
<4> [560.495226] rx_submit+0x1e6/0x2f0 [usbnet]
<4> [560.495226] rx_complete+0x21e/0x2c0 [usbnet]
<4> [560.495226] __usb_hcd_giveback_urb+0x4d/0xb0
<4> [560.495226] usb_giveback_urb_bh+0xa2/0x100
<4> [560.495226] tasklet_action_common.isra.21+0xb3/0xf0
<4> [560.495226] __do_softirq+0xdc/0x493
<4> [560.495226] irq_exit_rcu+0xe8/0xf0
<4> [560.495226] common_interrupt+0x9b/0xc0
<4> [560.495226] </IRQ>
<4> [560.495226] asm_common_interrupt+0x1e/0x40
<4> [560.495226] RIP: 0010:finish_task_switch+0xa7/0x260
<4> [560.495226] Code: 0e 81 41 b8 01 00 00 00 31 f6 48 8d 7b 18 e8 d0 88 03 00 48 89 df e8 18 ff ff ff 48 89 df e8 20 84 9f 00 e8 cb 52 0c 00 fb 58 <4d> 85 ed 74 21 65 48 8b 04 25 40 6f 01 00 4c 3b a8 88 04 00 00 0f
<4> [560.495226] RSP: 0018:ffffc90000327e30 EFLAGS: 00000206
<4> [560.495226] RAX: ffffffff810e6bda RBX: ffff88845053b480 RCX: 0000000000000002
<4> [560.495226] RDX: 0000000000000000 RSI: ffffffff823911b7 RDI: ffffffff82331ad7
<4> [560.495226] RBP: ffffc90000327e68 R08: 0000000000000001 R09: 0000000000000001
<4> [560.495226] R10: 0000000000000001 R11: 0000000000000000 R12: ffff8881016f8040
<4> [560.495226] R13: 0000000000000000 R14: ffff888100fd4040 R15: 0000000000000000
<4> [560.495226] ? finish_task_switch+0x6a/0x260
<4> [560.495226] __schedule+0x439/0xaa0
<4> [560.495226] ? smpboot_thread_fn+0x1e/0x260
<4> [560.495226] schedule+0x36/0xe0
<4> [560.495226] smpboot_thread_fn+0xd8/0x260
<4> [560.495226] ? sort_range+0x20/0x20
<4> [560.495226] kthread+0x13b/0x170
<4> [560.495226] ? set_kthread_struct+0x40/0x40
<4> [560.495226] ret_from_fork+0x1f/0x30
<3> [574.376302] INFO: task kworker/2:1:138 blocked for more than 30 seconds.