igt@gem_eio@hibernate - incomplete - Kernel panic - not syncing: nvme 0000:7f:00.0: unrecoverable failure
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_469/fi-tgl-dsi/igt@gem_eio@hibernate.html https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_469/filelist.html-->fi-tgl-dsi/pstore11-1517155442_Panic_1.log
<14>[ 303.561369] [IGT] gem_eio: starting subtest hibernate
<6>[ 303.567022] PM: hibernation: hibernation entry
<6>[ 303.595855] Filesystems sync: 0.015 seconds
<6>[ 303.595903] Freezing user space processes ... (elapsed 0.007 seconds) done.
<6>[ 303.603385] OOM killer disabled.
<7>[ 303.605677] PM: hibernation: Marking nosave pages: [mem 0x00000000-0x00000fff]
<7>[ 303.605727] PM: hibernation: Marking nosave pages: [mem 0x0009f000-0x000fffff]
<7>[ 303.605750] PM: hibernation: Marking nosave pages: [mem 0x3e6ed000-0x3e719fff]
<7>[ 303.605767] PM: hibernation: Marking nosave pages: [mem 0x3e720000-0x3e720fff]
<7>[ 303.605782] PM: hibernation: Marking nosave pages: [mem 0x41cb6000-0x44ffefff]
<7>[ 303.606777] PM: hibernation: Marking nosave pages: [mem 0x45000000-0xffffffff]
<7>[ 303.617606] PM: hibernation: Basic memory bitmaps created
<6>[ 303.619333] PM: hibernation: Preallocating image memory
<6>[ 306.199027] PM: hibernation: Allocated 301199 pages for snapshot
<6>[ 306.199057] PM: hibernation: Allocated 1204796 kbytes in 2.57 seconds (468.79 MB/s)
<6>[ 306.199073] Freezing remaining freezable tasks ... (elapsed 0.003 seconds) done.
<6>[ 306.231764] printk: Suspending console(s) (use no_console_suspend to debug)
<7>[ 306.424987] i915 0000:00:02.0: [drm:i915_hdcp_component_unbind [i915]] I915 HDCP comp unbind
<7>[ 306.431529] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC off
<7>[ 306.433132] i915 0000:00:02.0: [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7>[ 306.433720] i915 0000:00:02.0: [drm:intel_combo_phy_init [i915]] Combo PHY A already enabled, won't reprogram it.
<7>[ 306.434140] i915 0000:00:02.0: [drm:intel_combo_phy_init [i915]] Combo PHY B already enabled, won't reprogram it.
<7>[ 306.434447] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 3
<7>[ 306.436174] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 4
<7>[ 306.436543] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling power well 5
<7>[ 306.442322] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in cpu_transcoder (expected 5, found -1)
<7>[ 306.442723] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in output_types (expected 0x00000200, found 0x00000000)
<7>[ 306.443134] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_hdisplay (expected 1440, found 0)
<7>[ 306.443579] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_htotal (expected 1992, found 0)
<7>[ 306.444026] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_hblank_start (expected 1440, found 0)
<7>[ 306.444490] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_hblank_end (expected 1992, found 0)
<7>[ 306.445001] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_hsync_start (expected 1896, found 0)
<7>[ 306.445434] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_hsync_end (expected 1944, found 0)
<7>[ 306.445909] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vdisplay (expected 2880, found 0)
<7>[ 306.446181] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vtotal (expected 2896, found 0)
<7>[ 306.446454] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vblank_start (expected 2880, found 0)
<7>[ 306.446807] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vblank_end (expected 2896, found 0)
<7>[ 306.447070] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vsync_start (expected 2894, found 0)
<7>[ 306.447330] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_vsync_end (expected 2895, found 0)
<7>[ 306.447596] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in pixel_multiplier (expected 1, found 0)
<7>[ 306.447901] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in output_format (expected 1, found 0)
<7>[ 306.448181] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in pipe_bpp (expected 24, found 0)
<7>[ 306.448479] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in hw.adjusted_mode.crtc_clock (expected 432660, found 0)
<7>[ 306.449002] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in port_clock (expected 173064, found 0)
<7>[ 306.449426] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in dsc.compression_enable (expected 1, found 0)
<7>[ 306.449938] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in dsc.dsc_split (expected 1, found 0)
<7>[ 306.450250] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:91:pipe A] fastset mismatch in dsc.compressed_bpp (expected 8, found 0)
<7>[ 306.450891] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7>[ 306.451273] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7>[ 306.451776] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7>[ 306.452317] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] ddb ( 0 - 1953) -> ( 0 - 0), size 1953 -> 0
<7>[ 306.452602] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:87:cursor A] ddb (1953 - 2048) -> ( 0 - 0), size 95 -> 0
<7>[ 306.452855] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6, wm7,*twm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm
<7>[ 306.453063] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] lines 2, 14, 16, 17, 24, 27, 28, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0
<7>[ 306.453254] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] blocks 27, 183, 209, 222, 313, 352, 365, 0, 41 -> 0, 0, 0, 0, 0, 0, 0, 0, 0
<7>[ 306.453444] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] min_ddb 31, 203, 231, 246, 346, 389, 403, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0
<7>[ 306.453895] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] pipe A data rate 0 num active planes 0
<7>[ 306.454211] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [CRTC:91:pipe A] enable: no [modeset]
<7>[ 306.454515] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:31:plane 1A] fb: [NOFB], visible: no
<7>[ 306.454848] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:39:plane 2A] fb: [NOFB], visible: no
<7>[ 306.455128] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:47:plane 3A] fb: [NOFB], visible: no
<7>[ 306.455453] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:55:plane 4A] fb: [NOFB], visible: no
<7>[ 306.455998] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:63:plane 5A] fb: [NOFB], visible: no
<7>[ 306.456413] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:71:plane 6A] fb: [NOFB], visible: no
<7>[ 306.456942] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:79:plane 7A] fb: [NOFB], visible: no
<7>[ 306.457345] i915 0000:00:02.0: [drm:intel_dump_pipe_config [i915]] [PLANE:87:cursor A] fb: [NOFB], visible: no
<7>[ 306.467458] i915 0000:00:02.0: [drm:intel_dsi_vbt_exec_sequence [i915]] Starting MIPI sequence 7 - MIPI_SEQ_BACKLIGHT_OFF
<7>[ 306.468013] i915 0000:00:02.0: [drm:mipi_exec_gpio [i915]]
<7>[ 306.468314] i915 0000:00:02.0: [drm:mipi_exec_gpio [i915]] Skipping ICL GPIO element execution
<7>[ 306.468635] [drm:intel_panel_actually_set_backlight [i915]] set backlight PWM = 0
<7>[ 306.481666] i915 0000:00:02.0: [drm:intel_dsi_vbt_exec_sequence [i915]] Starting MIPI sequence 4 - MIPI_SEQ_DISPLAY_OFF
<7>[ 306.482349] i915 0000:00:02.0: [drm:mipi_exec_send_packet [i915]]
<7>[ 306.482900] i915 0000:00:02.0: [drm:mipi_exec_delay [i915]]
<7>[ 306.508433] i915 0000:00:02.0: [drm:mipi_exec_send_packet [i915]]
<7>[ 306.508983] i915 0000:00:02.0: [drm:mipi_exec_delay [i915]]
<7>[ 306.594921] i915 0000:00:02.0: [drm:intel_dsi_vbt_exec_sequence [i915]] Starting MIPI sequence 11 - MIPI_SEQ_POWER_OFF
<7>[ 306.595259] i915 0000:00:02.0: [drm:mipi_exec_gpio [i915]]
<7>[ 306.595569] i915 0000:00:02.0: [drm:mipi_exec_gpio [i915]] Skipping ICL GPIO element execution
<7>[ 306.597099] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI A IO
<7>[ 306.597857] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on pipe A
<7>[ 306.598279] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 1, on? 1) for crtc 91
<7>[ 306.598757] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7>[ 306.599111] [drm:intel_dump_cdclk_config [i915]] Changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7>[ 306.599806] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:275:DDI B]
<7>[ 306.600097] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:285:DDI D]
<7>[ 306.600378] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:287:DP-MST A]
<7>[ 306.600667] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:288:DP-MST B]
<7>[ 306.600998] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:289:DP-MST C]
<7>[ 306.601266] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:290:DP-MST D]
<7>[ 306.601574] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:293:DDI E]
<7>[ 306.601892] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:295:DP-MST A]
<7>[ 306.602162] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:296:DP-MST B]
<7>[ 306.602431] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:297:DP-MST C]
<7>[ 306.602743] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:298:DP-MST D]
<7>[ 306.603005] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:301:DDI F]
<7>[ 306.603265] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:303:DP-MST A]
<7>[ 306.603532] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:304:DP-MST B]
<7>[ 306.603843] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:305:DP-MST C]
<7>[ 306.604142] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:306:DP-MST D]
<7>[ 306.604400] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [ENCODER:309:DSI A]
<7>[ 306.604779] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:310:DSI-1]
<7>[ 306.605118] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] DPLL 0
<7>[ 306.605440] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] DPLL 1
<7>[ 306.605810] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TBT PLL
<7>[ 306.606112] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 1
<7>[ 306.606409] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 2
<7>[ 306.606881] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 3
<7>[ 306.607181] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 4
<7>[ 306.607472] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 5
<7>[ 306.607814] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.146 [i915]] TC PLL 6
<7>[ 306.608251] i915 0000:00:02.0: [drm:intel_atomic_commit_tail [i915]] [CRTC:91:pipe A]
<0>[ 369.336312] nvme 0000:7f:00.0: **** DPM device timeout ****
<4>[ 369.336383] Call Trace:
<4>[ 369.336428] ? __schedule+0x2e5/0x810
<4>[ 369.336457] ? mark_held_locks+0x49/0x70
<4>[ 369.336485] schedule+0x37/0xe0
<4>[ 369.336504] schedule_timeout+0x1cc/0x3f0
<4>[ 369.336537] ? __next_timer_interrupt+0xc0/0xc0
<4>[ 369.336570] ? wait_for_completion_io_timeout+0x115/0x1a0
<4>[ 369.336586] io_schedule_timeout+0x14/0x40
<4>[ 369.336606] wait_for_completion_io_timeout+0x13d/0x1a0
<4>[ 369.336625] ? wake_up_q+0xa0/0xa0
<4>[ 369.336661] __nvme_disable_io_queues+0x176/0x1b0
<4>[ 369.336678] ? nvme_del_queue_end+0x20/0x20
<4>[ 369.336721] nvme_dev_disable+0x5a4/0x5d0
<4>[ 369.336760] nvme_simple_suspend+0x22/0x30
<4>[ 369.336781] pci_pm_freeze+0x53/0xc0
<4>[ 369.336799] ? pci_pm_poweroff+0xd0/0xd0
<4>[ 369.336821] dpm_run_callback+0x64/0x280
<4>[ 369.336847] __device_suspend+0x153/0x5f0
<4>[ 369.336871] ? dpm_watchdog_set+0x60/0x60
<4>[ 369.336906] async_suspend+0x15/0x90
<4>[ 369.336925] async_run_entry_fn+0x34/0x160
<4>[ 369.336953] process_one_work+0x260/0x600
<4>[ 369.336968] ? worker_thread+0xc9/0x380
<4>[ 369.337005] worker_thread+0x37/0x380
<4>[ 369.337031] ? process_one_work+0x600/0x600
<4>[ 369.337044] kthread+0x119/0x130
<4>[ 369.337060] ? kthread_park+0x80/0x80
<4>[ 369.337081] ret_from_fork+0x24/0x50
<0>[ 369.337133] Kernel panic - not syncing: nvme 0000:7f:00.0: unrecoverable failure
<0>[ 369.337556] Kernel Offset: 0x2d000000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)
<4>[ 369.337588] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G U 5.6.0-g48a3176bdce7-drmtip_469+ #1
<4>[ 369.337597] Hardware name: Intel Corporation Tiger Lake Client Platform/TigerLake Y LPDDR4x T4 Crb, BIOS TGLSFWI1.R00.2527.A03.2001170231 01/17/2020
<4>[ 369.337604] Call Trace:
<4>[ 369.337615] <IRQ>
<4>[ 369.337635] dump_stack+0x71/0x9b
<4>[ 369.337655] panic+0x132/0x2d3
<4>[ 369.337690] ? dpm_watchdog_set+0x60/0x60
<4>[ 369.337702] ? dpm_watchdog_set+0x60/0x60
<4>[ 369.337718] dpm_watchdog_handler+0x43/0x50
<4>[ 369.337734] call_timer_fn+0x98/0x2d0
<4>[ 369.337758] ? dpm_watchdog_set+0x60/0x60
<4>[ 369.337778] run_timer_softirq+0x4b7/0x5a0
<4>[ 369.337823] __do_softirq+0xdf/0x47f
<4>[ 369.337856] irq_exit+0xba/0xc0
<4>[ 369.337868] smp_apic_timer_interrupt+0xb7/0x280
<4>[ 369.337883] apic_timer_interrupt+0xf/0x20
<4>[ 369.337894] </IRQ>
<4>[ 369.337916] RIP: 0010:cpuidle_enter_state+0xb6/0x420
<4>[ 369.337929] Code: 00 31 ff e8 9c 41 8a ff 80 7c 24 0b 00 74 12 9c 58 f6 c4 02 0f 85 37 03 00 00 31 ff e8 03 8c 91 ff e8 be b3 95 ff fb 45 85 ed <0f> 88 cd 02 00 00 49 63 c5 4c 2b 24 24 48 8d 14 40 48 8d 14 90 48
<4>[ 369.337939] RSP: 0018:ffffa12e8012be78 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff13
<4>[ 369.337952] RAX: ffff9e3ba187c040 RBX: ffffc12e7fa80000 RCX: 0000000000000000
<4>[ 369.337961] RDX: 0000000000000046 RSI: 0000000000000006 RDI: ffffffffaf34a7f5
<4>[ 369.337969] RBP: ffffffffaf4b4d80 R08: 0000000000000000 R09: 0000000000000000
<4>[ 369.337977] R10: 0000000000000000 R11: 0000000000000000 R12: 00000055fe014cf0
<4>[ 369.337985] R13: 0000000000000003 R14: 0000000000000003 R15: 0000000000000003
<4>[ 369.338031] ? cpuidle_enter_state+0xb2/0x420
<4>[ 369.338056] cpuidle_enter+0x24/0x40
<4>[ 369.338077] do_idle+0x1f7/0x260
<4>[ 369.338103] cpu_startup_entry+0x14/0x20
<4>[ 369.338123] start_secondary+0x15f/0x1b0
<4>[ 369.338142] secondary_startup_64+0xb6/0xc0