igt@perf_pmu@rc6@runtime-pm-gt0 on shard-rkl@CI_DRM

Detail	Value
Duration	11.59 seconds
Hostname	shard-rkl-7
Igt-Version	IGT-Version: 2.1-g98f7b89b2 (x86_64) (Linux: 6.17.0-rc6-CI_DRM_17241-gfeed9240365b+ x86_64)
Out	Starting dynamic subtest: runtime-pm-gt0 i915_runtime_pm_status: GPU idle: yes IRQs disabled: no Usage count: 1 PCI device power state: D0 [0] ref_tracker: intel_runtime_pm@ffff88810e51d7f8 has 1/1 users at track_intel_runtime_pm_wakeref+0x90/0xb0 [i915] intel_runtime_pm_get_if_in_use+0x4d/0x120 [i915] pxp_session_work+0x90/0x2a0 [i915] process_one_work+0x22b/0x6f0 worker_thread+0x1e8/0x3d0 kthread+0x11f/0x250 ret_from_fork+0x2d7/0x310 ret_from_fork_asm+0x1a/0x30 Test requirement not met in function test_rc6, file ../tests/intel/perf_pmu.c:1735: Test requirement: wait_for_suspended(gem_fd) Dynamic subtest runtime-pm-gt0: SKIP (11.589s)
Err	Starting dynamic subtest: runtime-pm-gt0 (perf_pmu:2327) igt_pm-WARNING: timeout: pm_status expected:suspended, got:active Dynamic subtest runtime-pm-gt0: SKIP (11.589s)
Dmesg Scroll to first warning	<6> [512.296071] [IGT] perf_pmu: starting dynamic subtest runtime-pm-gt0 <7> [512.296393] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <7> [512.297853] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A] <7> [512.298081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [512.298500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [512.298651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [512.298795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [512.298937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [512.299082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [512.299225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [512.299429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0) <7> [512.299566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [512.299703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0) <7> [512.299839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [512.299974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0) <7> [512.300110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [512.300244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [512.300416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [512.300561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [512.300692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [512.300828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [512.300959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [512.301089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0) <7> [512.301220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [512.301373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0) <7> [512.301529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [512.301662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0) <7> [512.301797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [512.301932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [512.302068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [512.302202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [512.302354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [512.302498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [512.302628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [512.302764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [512.302895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [512.303044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [512.303214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0) <7> [512.303358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0) <7> [512.303548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 139929, found 0) <7> [512.303679] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset <7> [512.303836] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:88:pipe A] releasing DPLL 0 <7> [512.303997] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no <7> [512.304134] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:88:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [512.304290] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0 <7> [512.304472] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:83:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0 <7> [512.304606] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [512.304740] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [512.304875] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [512.305009] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [512.305159] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:88:pipe A] data rate 0 num active planes 0 <7> [512.305296] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0 <7> [512.305495] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [512.305631] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [512.305769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:88:pipe A] enable: no [modeset] <7> [512.305906] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no <7> [512.306043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no <7> [512.306179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no <7> [512.306314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no <7> [512.306517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no <7> [512.306652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:cursor A] fb: [NOFB], visible: no <7> [512.306834] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7> [512.306974] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00 <7> [512.322263] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [512.341474] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B <7> [512.342146] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output <7> [512.347607] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:88:pipe A] <7> [512.348512] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0 <7> [512.349176] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A] <7> [512.349801] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B] <7> [512.350454] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC1/PHY C] <7> [512.350917] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:161:DDI TC2/PHY D] <7> [512.351405] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DP-MST A] <7> [512.351863] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:164:DP-MST B] <7> [512.352318] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:148:HDMI-A-1] <7> [512.353021] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [512.353603] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:88:pipe A] <7> [512.354527] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B] <7> [512.398657] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7> [512.399298] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6 <7> [512.399954] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02 <7> [512.401684] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <3> [517.334388] mei_me 0000:00:16.0: timer: connect/disconnect timeout. <3> [517.335317] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect <3> [517.335356] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14 <3> [517.335371] i915 0000:00:02.0: [drm] ERROR* Failed to send PXP TEE message <4> [517.335384] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel... <3> [523.478236] mei_me 0000:00:16.0: timer: connect/disconnect timeout. <3> [523.479176] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect <3> [523.479211] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14 <3> [523.479223] i915 0000:00:02.0: [drm] ERROR Failed to send PXP TEE message <4> [523.479236] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel... <6> [523.886065] [IGT] perf_pmu: finished subtest runtime-pm-gt0, SKIP

<6> [512.296071] [IGT] perf_pmu: starting dynamic subtest runtime-pm-gt0

<7> [512.296393] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]

<7> [512.297853] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]

<7> [512.298081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected yes, found no)

<7> [512.298500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected yes, found no)

<7> [512.298651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)

<7> [512.298795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 4, found 0)

<7> [512.298937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)

<7> [512.299082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [512.299225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)

<7> [512.299429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)

<7> [512.299566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)

<7> [512.299703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)

<7> [512.299839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)

<7> [512.299974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)

<7> [512.300110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)

<7> [512.300244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)

<7> [512.300416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)

<7> [512.300561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)

<7> [512.300692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)

<7> [512.300828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)

<7> [512.300959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)

<7> [512.301089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)

<7> [512.301220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)

<7> [512.301373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)

<7> [512.301529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)

<7> [512.301662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)

<7> [512.301797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)

<7> [512.301932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)

<7> [512.302068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)

<7> [512.302202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)

<7> [512.302354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)

<7> [512.302498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)

<7> [512.302628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [512.302764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)

<7> [512.302895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)

<7> [512.303044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)

<7> [512.303214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)

<7> [512.303358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)

<7> [512.303548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)

<7> [512.303679] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset

<7> [512.303836] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:88:pipe A] releasing DPLL 0

<7> [512.303997] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no

<7> [512.304134] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:88:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0

<7> [512.304290] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0

<7> [512.304472] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:83:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0

<7> [512.304606] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm

<7> [512.304740] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [512.304875] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [512.305009] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [512.305159] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:88:pipe A] data rate 0 num active planes 0

<7> [512.305296] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0

<7> [512.305495] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz

<7> [512.305631] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0

<7> [512.305769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:88:pipe A] enable: no [modeset]

<7> [512.305906] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no

<7> [512.306043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no

<7> [512.306179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no

<7> [512.306314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no

<7> [512.306517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no

<7> [512.306652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:cursor A] fb: [NOFB], visible: no

<7> [512.306834] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off

<7> [512.306974] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00

<7> [512.322263] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A

<7> [512.341474] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B

<7> [512.342146] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output

<7> [512.347607] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:88:pipe A]

<7> [512.348512] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0

<7> [512.349176] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]

<7> [512.349801] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]

<7> [512.350454] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC1/PHY C]

<7> [512.350917] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:161:DDI TC2/PHY D]

<7> [512.351405] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DP-MST A]

<7> [512.351863] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:164:DP-MST B]

<7> [512.352318] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:148:HDMI-A-1]

<7> [512.353021] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1

<7> [512.353603] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:88:pipe A]

<7> [512.354527] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]

<7> [512.398657] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<7> [512.399298] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6

<7> [512.399954] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02

<7> [512.401684] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on

<3> [517.334388] mei_me 0000:00:16.0: timer: connect/disconnect timeout.

<3> [517.335317] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect

<3> [517.335356] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14

<3> [517.335371] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message

<4> [517.335384] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel...

<3> [523.478236] mei_me 0000:00:16.0: timer: connect/disconnect timeout.

<3> [523.479176] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect

<3> [523.479211] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14

<3> [523.479223] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message

<4> [523.479236] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel...

<6> [523.886065] [IGT] perf_pmu: finished subtest runtime-pm-gt0, SKIP

Results for igt@perf_pmu@rc6@runtime-pm-gt0