Stdout
Using IGT_SRANDOM=1722567139 for randomisation
Opened device: /dev/dri/card0
Starting subtest: flip-basic
Default Mode: 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
Starting dynamic subtest: pipe-A-eDP-1
VRR Test execution on eDP-1, PIPE_A with VRR range: (40-60) Hz
Override Mode: 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
Requested rate[0]: 20000000 ns, Expected rate between: 19607844 ns to 20408164 ns
Completed 14 flips, 9 were in threshold for [(50 Hz) 20000000ns]
Requested rate[0]: 20000000 ns, Expected rate between: 19607844 ns to 20408164 ns
Timed out: Scheduling page flip
Dynamic subtest pipe-A-eDP-1: FAIL (2.792s)
Subtest flip-basic: FAIL (3.447s)
Stderr
Starting subtest: flip-basic
Starting dynamic subtest: pipe-A-eDP-1
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe4/228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 20003000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(229)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe5/229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 20026000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(228)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe4/228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 19970000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(229)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe5/229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 20010000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(228)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe4/228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 19993000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(229)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe5/229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 20033000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(228)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe4/228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 19962000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(229)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe5/229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 19998000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(228)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe4/228
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: display: }
(kms_vrr:939) DEBUG: event_ns - last_event_ns: 20016000
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_fb(229)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: plane_set_size (2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
(kms_vrr:939) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
(kms_vrr:939) igt_kms-DEBUG: display: commit {
(kms_vrr:939) igt_kms-DEBUG: display: populating plane data: A.0, fb 229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_X" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y" to 0x0/0
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_W" to 0xb400000/188743680
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "SRC_H" to 0x7080000/117964800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W" to 0xb40/2880
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H" to 0x708/1800
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "FB_ID" to 0xe5/229
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "CRTC_ID" to 0x52/82
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_ENCODING" to 0x1/1
(kms_vrr:939) igt_kms-DEBUG: plane A.0: Setting property "COLOR_RANGE" to 0x0/0
(kms_vrr:939) igt_core-INFO: Timed out: Scheduling page flip
**** END ****
Dynamic subtest pipe-A-eDP-1: FAIL (2.792s)
Subtest flip-basic: FAIL (3.447s)
Dmesg
<7> [199.284152] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [199.284342] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [199.284476] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [199.317553] Console: switching to colour dummy device 80x25
<6> [199.317839] [IGT] kms_vrr: executing
<7> [199.323091] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1]
<7> [199.323109] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1]
<7> [199.323220] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt)
<7> [199.323283] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected
<7> [199.323502] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2]
<7> [199.323508] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2]
<7> [199.323590] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt)
<7> [199.323646] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected
<7> [199.323858] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3]
<7> [199.323865] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3]
<7> [199.323943] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt)
<7> [199.324074] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected