Starting subtest: pat-sw-hw-reset-compare
[108.917399] (xe_pat:3792) CRITICAL: Test assertion failure function pat_sw_hw...
[108.917439] (xe_pat:3792) CRITICAL: Failed assertion: matches == 1
[108.917457] (xe_pat:3792) CRITICAL: Last errno: 2, No such file or directory
[108.917467] (xe_pat:3792) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[108.840633] (xe_pat:3792) igt_device-DEBUG: Test requirement passed: pci_dev
[108.841406] (xe_pat:3792) igt_sriov_device-DEBUG: Test requirement passed: (v...
[108.841407] (xe_pat:3792) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[108.868942] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[108.868945] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[108.868945] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[108.868946] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[108.868947] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[108.868947] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[108.868948] (xe_pat:3792) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[105.677233] (xe_pat:3787) CRITICAL: Test assertion failure function pat_sw_hw...
[105.677267] (xe_pat:3787) CRITICAL: Failed assertion: matches == 1
[105.677282] (xe_pat:3787) CRITICAL: Last errno: 2, No such file or directory
[105.677289] (xe_pat:3787) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[105.617237] (xe_pat:3787) igt_device-DEBUG: Test requirement passed: pci_dev
[105.618585] (xe_pat:3787) igt_sriov_device-DEBUG: Test requirement passed: (v...
[105.618587] (xe_pat:3787) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[105.644525] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[105.644527] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[105.644528] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[105.644528] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[105.644529] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[105.644529] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[105.644530] (xe_pat:3787) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[107.143057] (xe_pat:3817) CRITICAL: Test assertion failure function pat_sw_hw...
[107.143096] (xe_pat:3817) CRITICAL: Failed assertion: matches == 1
[107.143114] (xe_pat:3817) CRITICAL: Last errno: 2, No such file or directory
[107.143123] (xe_pat:3817) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[107.070916] (xe_pat:3817) igt_device-DEBUG: Test requirement passed: pci_dev
[107.071711] (xe_pat:3817) igt_sriov_device-DEBUG: Test requirement passed: (v...
[107.071712] (xe_pat:3817) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[107.102659] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[107.102665] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[107.102665] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[107.102666] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[107.102667] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[107.102668] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[107.102668] (xe_pat:3817) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[246.015375] (xe_pat:6807) CRITICAL: Test assertion failure function pat_sw_hw...
[246.015417] (xe_pat:6807) CRITICAL: Failed assertion: matches == 1
[246.015436] (xe_pat:6807) CRITICAL: Last errno: 2, No such file or directory
[246.015446] (xe_pat:6807) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[245.959241] (xe_pat:6807) igt_device-DEBUG: Test requirement passed: pci_dev
[245.959826] (xe_pat:6807) igt_sriov_device-DEBUG: Test requirement passed: (v...
[245.959827] (xe_pat:6807) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[245.981047] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[245.981052] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[245.981052] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[245.981053] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[245.981054] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[245.981054] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[245.981055] (xe_pat:6807) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[124.169862] (xe_pat:3660) CRITICAL: Test assertion failure function pat_sw_hw...
[124.169886] (xe_pat:3660) CRITICAL: Failed assertion: matches == 1
[124.169897] (xe_pat:3660) CRITICAL: Last errno: 2, No such file or directory
[124.169905] (xe_pat:3660) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[124.094717] (xe_pat:3660) igt_device-DEBUG: Test requirement passed: pci_dev
[124.095630] (xe_pat:3660) igt_sriov_device-DEBUG: Test requirement passed: (v...
[124.095631] (xe_pat:3660) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[124.129002] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[124.129005] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[124.129006] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[124.129007] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[124.129008] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[124.129009] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[124.129010] (xe_pat:3660) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[125.974820] (xe_pat:3632) CRITICAL: Test assertion failure function pat_sw_hw...
[125.974840] (xe_pat:3632) CRITICAL: Failed assertion: matches == 1
[125.974852] (xe_pat:3632) CRITICAL: Last errno: 2, No such file or directory
[125.974860] (xe_pat:3632) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[125.903570] (xe_pat:3632) igt_device-DEBUG: Test requirement passed: pci_dev
[125.904614] (xe_pat:3632) igt_sriov_device-DEBUG: Test requirement passed: (v...
[125.904616] (xe_pat:3632) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[125.935222] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[125.935225] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[125.935226] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[125.935226] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[125.935227] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[125.935228] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[125.935228] (xe_pat:3632) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[305.216783] (xe_pat:5353) CRITICAL: Test assertion failure function pat_sw_hw...
[305.216817] (xe_pat:5353) CRITICAL: Failed assertion: matches == 1
[305.216833] (xe_pat:5353) CRITICAL: Last errno: 2, No such file or directory
[305.216841] (xe_pat:5353) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[305.147952] (xe_pat:5353) igt_device-DEBUG: Test requirement passed: pci_dev
[305.148715] (xe_pat:5353) igt_sriov_device-DEBUG: Test requirement passed: (v...
[305.148717] (xe_pat:5353) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[305.175582] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[305.175590] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[305.175591] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[305.175592] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[305.175593] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[305.175595] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[305.175595] (xe_pat:5353) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[297.658850] (xe_pat:5279) CRITICAL: Test assertion failure function pat_sw_hw...
[297.658872] (xe_pat:5279) CRITICAL: Failed assertion: matches == 1
[297.658884] (xe_pat:5279) CRITICAL: Last errno: 2, No such file or directory
[297.658890] (xe_pat:5279) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[297.605841] (xe_pat:5279) igt_device-DEBUG: Test requirement passed: pci_dev
[297.606837] (xe_pat:5279) igt_sriov_device-DEBUG: Test requirement passed: (v...
[297.606839] (xe_pat:5279) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[297.628836] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[297.628840] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[297.628841] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[297.628841] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[297.628842] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[297.628842] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[297.628843] (xe_pat:5279) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[298.501060] (xe_pat:5265) CRITICAL: Test assertion failure function pat_sw_hw...
[298.501103] (xe_pat:5265) CRITICAL: Failed assertion: matches == 1
[298.501122] (xe_pat:5265) CRITICAL: Last errno: 2, No such file or directory
[298.501134] (xe_pat:5265) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[298.419701] (xe_pat:5265) igt_device-DEBUG: Test requirement passed: pci_dev
[298.420281] (xe_pat:5265) igt_sriov_device-DEBUG: Test requirement passed: (v...
[298.420282] (xe_pat:5265) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[298.455849] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[298.455853] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[298.455854] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[298.455854] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[298.455855] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[298.455855] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[298.455856] (xe_pat:5265) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[299.275719] (xe_pat:5326) CRITICAL: Test assertion failure function pat_sw_hw...
[299.275758] (xe_pat:5326) CRITICAL: Failed assertion: matches == 1
[299.275775] (xe_pat:5326) CRITICAL: Last errno: 2, No such file or directory
[299.275784] (xe_pat:5326) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[299.219102] (xe_pat:5326) igt_device-DEBUG: Test requirement passed: pci_dev
[299.219747] (xe_pat:5326) igt_sriov_device-DEBUG: Test requirement passed: (v...
[299.219749] (xe_pat:5326) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[299.241645] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[299.241648] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[299.241649] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[299.241650] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[299.241650] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[299.241651] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[299.241651] (xe_pat:5326) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[296.663008] (xe_pat:5299) CRITICAL: Test assertion failure function pat_sw_hw...
[296.663026] (xe_pat:5299) CRITICAL: Failed assertion: matches == 1
[296.663036] (xe_pat:5299) CRITICAL: Last errno: 2, No such file or directory
[296.663043] (xe_pat:5299) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[296.599034] (xe_pat:5299) igt_device-DEBUG: Test requirement passed: pci_dev
[296.599532] (xe_pat:5299) igt_sriov_device-DEBUG: Test requirement passed: (v...
[296.599534] (xe_pat:5299) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[296.625342] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[296.625344] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[296.625345] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[296.625345] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[296.625346] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[296.625346] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[296.625347] (xe_pat:5299) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[296.136245] (xe_pat:5293) CRITICAL: Test assertion failure function pat_sw_hw...
[296.136289] (xe_pat:5293) CRITICAL: Failed assertion: matches == 1
[296.136309] (xe_pat:5293) CRITICAL: Last errno: 2, No such file or directory
[296.136323] (xe_pat:5293) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[296.077471] (xe_pat:5293) igt_device-DEBUG: Test requirement passed: pci_dev
[296.077968] (xe_pat:5293) igt_sriov_device-DEBUG: Test requirement passed: (v...
[296.077969] (xe_pat:5293) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[296.100673] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[296.100677] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[296.100678] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[296.100679] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[296.100679] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[296.100680] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[296.100680] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[298.889150] (xe_pat:5300) CRITICAL: Test assertion failure function pat_sw_hw...
[298.889194] (xe_pat:5300) CRITICAL: Failed assertion: matches == 1
[298.889217] (xe_pat:5300) CRITICAL: Last errno: 2, No such file or directory
[298.889228] (xe_pat:5300) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[298.830295] (xe_pat:5300) igt_device-DEBUG: Test requirement passed: pci_dev
[298.830835] (xe_pat:5300) igt_sriov_device-DEBUG: Test requirement passed: (v...
[298.830836] (xe_pat:5300) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[298.854609] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[298.854615] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[298.854616] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[298.854617] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[298.854617] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[298.854618] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[298.854618] (xe_pat:5300) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-reset-compare
[298.510314] (xe_pat:5293) CRITICAL: Test assertion failure function pat_sw_hw...
[298.510356] (xe_pat:5293) CRITICAL: Failed assertion: matches == 1
[298.510379] (xe_pat:5293) CRITICAL: Last errno: 2, No such file or directory
[298.510390] (xe_pat:5293) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-reset-compare failed.
**** DEBUG ****
[298.444388] (xe_pat:5293) igt_device-DEBUG: Test requirement passed: pci_dev
[298.445213] (xe_pat:5293) igt_sriov_device-DEBUG: Test requirement passed: (v...
[298.445214] (xe_pat:5293) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[298.470933] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[298.470942] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[298.470943] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[298.470944] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[298.470945] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[298.470945] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[298.470946] (xe_pat:5293) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[220.667243] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[220.667272] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[220.667320] (xe_pmu:4299) CRITICAL: Last errno: 2, No such file or directory
[220.667341] (xe_pmu:4299) CRITICAL: 87.029688 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[219.131976] (xe_pmu:4299) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[219.134958] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[219.136235] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[219.136236] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[219.136689] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[220.667150] (xe_pmu:4299) INFO: error=-2.52% (87.03% vs 89.55%)
[220.667243] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[220.667272] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[220.667320] (xe_pmu:4299) CRITICAL: Last errno: 2, No such file or directory
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[210.875131] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[210.875163] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[210.875219] (xe_pmu:4270) CRITICAL: Last errno: 2, No such file or directory
[210.875276] (xe_pmu:4270) CRITICAL: 92.608490 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[209.304374] (xe_pmu:4270) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[209.307324] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[209.308786] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[209.308787] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[209.309215] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[210.875033] (xe_pmu:4270) INFO: error=3.03% (92.61% vs 89.58%)
[210.875131] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[210.875163] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[210.875219] (xe_pmu:4270) CRITICAL: Last errno: 2, No such file or directory
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[212.515960] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[212.515989] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.516046] (xe_pmu:4619) CRITICAL: Last errno: 2, No such file or directory
[212.516077] (xe_pmu:4619) CRITICAL: 86.843513 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[210.975038] (xe_pmu:4619) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[210.977868] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[210.979310] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[210.979311] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[210.979853] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[212.515843] (xe_pmu:4619) INFO: error=-2.55% (86.84% vs 89.39%)
[212.515960] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[212.515989] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.516046] (xe_pmu:4619) CRITICAL: Last errno: 2, No such file or directory
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[220.667243] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[220.667272] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[220.667320] (xe_pmu:4299) CRITICAL: Last errno: 2, No such file or directory
[220.667341] (xe_pmu:4299) CRITICAL: 87.029688 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[219.131976] (xe_pmu:4299) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[219.134958] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[219.136235] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[219.136236] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[219.136689] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[220.667150] (xe_pmu:4299) INFO: error=-2.52% (87.03% vs 89.55%)
[220.667243] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[220.667272] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[220.667320] (xe_pmu:4299) CRITICAL: Last errno: 2, No such file or directory
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[210.875131] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[210.875163] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[210.875219] (xe_pmu:4270) CRITICAL: Last errno: 2, No such file or directory
[210.875276] (xe_pmu:4270) CRITICAL: 92.608490 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[209.304374] (xe_pmu:4270) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[209.307324] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[209.308786] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[209.308787] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[209.309215] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[210.875033] (xe_pmu:4270) INFO: error=3.03% (92.61% vs 89.58%)
[210.875131] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[210.875163] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[210.875219] (xe_pmu:4270) CRITICAL: Last errno: 2, No such file or directory
...

Starting subtest: engine-activity-accuracy-90
Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_RENDER0
[212.515960] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[212.515989] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.516046] (xe_pmu:4619) CRITICAL: Last errno: 2, No such file or directory
[212.516077] (xe_pmu:4619) CRITICAL: 86.843513 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
[210.975038] (xe_pmu:4619) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[210.977868] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[210.979310] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[210.979311] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[210.979853] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[212.515843] (xe_pmu:4619) INFO: error=-2.55% (86.84% vs 89.39%)
[212.515960] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[212.515989] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.516046] (xe_pmu:4619) CRITICAL: Last errno: 2, No such file or directory
...

Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_COPY0
[222.225867] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[222.225908] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[222.225964] (xe_pmu:4299) CRITICAL: 87.407464 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_COPY0 failed.
**** DEBUG ****
[220.691572] (xe_pmu:4299) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[220.694558] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[220.695260] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[220.695264] (xe_pmu:4299) DEBUG: Test requirement passed: !(has_engine_active...
[220.695589] (xe_pmu:4299) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[222.225785] (xe_pmu:4299) INFO: error=-2.13% (87.41% vs 89.54%)
[222.225867] (xe_pmu:4299) CRITICAL: Test assertion failure function accuracy,...
[222.225908] (xe_pmu:4299) CRITICAL: Failed assertion: (double)(100.0 * engine...
[222.225964] (xe_pmu:4299) CRITICAL: 87.407464 not within +2.000000/-2.000000 ...
[222.229319] (xe_pmu:4299) igt_core-INFO: Stack trace:
[222.232878] (xe_pmu:4299) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fa...
...

Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_COPY0
[212.483018] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[212.483056] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.483108] (xe_pmu:4270) CRITICAL: 91.814284 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_COPY0 failed.
**** DEBUG ****
[210.905990] (xe_pmu:4270) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[210.910495] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[210.911496] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[210.911504] (xe_pmu:4270) DEBUG: Test requirement passed: !(has_engine_active...
[210.911991] (xe_pmu:4270) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[212.482921] (xe_pmu:4270) INFO: error=2.36% (91.81% vs 89.45%)
[212.483018] (xe_pmu:4270) CRITICAL: Test assertion failure function accuracy,...
[212.483056] (xe_pmu:4270) CRITICAL: Failed assertion: (double)(100.0 * engine...
[212.483108] (xe_pmu:4270) CRITICAL: 91.814284 not within +2.000000/-2.000000 ...
[212.486591] (xe_pmu:4270) igt_core-INFO: Stack trace:
[212.489972] (xe_pmu:4270) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fa...
...

Starting dynamic subtest: engine-DRM_XE_ENGINE_CLASS_COPY0
[214.082740] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[214.082784] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[214.082842] (xe_pmu:4619) CRITICAL: 87.201236 not within +2.000000/-2.000000 ...
Dynamic subtest engine-DRM_XE_ENGINE_CLASS_COPY0 failed.
**** DEBUG ****
[212.543188] (xe_pmu:4619) INFO: calibration=500ms, test=1000ms, cycle=100ms; ...
[212.547621] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[212.548587] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[212.548597] (xe_pmu:4619) DEBUG: Test requirement passed: !(has_engine_active...
[212.549132] (xe_pmu:4619) DEBUG: Test requirement passed: !(fd < 0 && errno =...
[214.082641] (xe_pmu:4619) INFO: error=-2.15% (87.20% vs 89.36%)
[214.082740] (xe_pmu:4619) CRITICAL: Test assertion failure function accuracy,...
[214.082784] (xe_pmu:4619) CRITICAL: Failed assertion: (double)(100.0 * engine...
[214.082842] (xe_pmu:4619) CRITICAL: 87.201236 not within +2.000000/-2.000000 ...
[214.084942] (xe_pmu:4619) igt_core-INFO: Stack trace:
[214.088666] (xe_pmu:4619) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fa...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[268.691979] (kms_flip:7065) CRITICAL: Test assertion failure function calibra...
[268.692015] (kms_flip:7065) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[268.692058] (kms_flip:7065) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[267.236115] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.236127] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236142] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236473] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[267.236476] (kms_flip:7065) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[267.236520] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.236527] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236540] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.237087] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[267.237089] (kms_flip:7065) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[269.513573] (kms_flip:7041) CRITICAL: Test assertion failure function calibra...
[269.513597] (kms_flip:7041) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[269.513620] (kms_flip:7041) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[268.028574] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[268.028587] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.028601] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.028978] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[268.028981] (kms_flip:7041) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[268.029020] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[268.029026] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.029039] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.029639] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[268.029641] (kms_flip:7041) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[269.067156] (kms_flip:7070) CRITICAL: Test assertion failure function calibra...
[269.067181] (kms_flip:7070) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[269.067212] (kms_flip:7070) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[267.565095] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.565107] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565119] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565483] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[267.565487] (kms_flip:7070) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[267.565529] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.565536] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565549] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.566038] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[267.566040] (kms_flip:7070) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: threads-shared-vm-many-stride-mmap-huge-nomemset
Received signal SIGBUS.
Stack trace: 
 #0 [fatal_sig_handler+0x17b]
Received signal SIGBUS.
Stack trace: 
 #1 [__sigaction+0x50]
 #2 [test_exec+0x64c]
 #3 [thread+0x72]
 #0 [fatal_sig_handler+0x17b]
 #4 [pthread_condattr_setpshared+0x684]
 #1  #[5_ _[s__icglaoncet+i0x2o4cn]
+0x50]
 #2 [test_exec+0x64c]
 #3 [thread+0x72]
 #4 [pthread_condattr_setpshared+0x684]
 #5 [__clone+0x24c]
...

Starting subtest: deep-pkgc
[424.077789] (kms_pm_dc:9000) CRITICAL: Test assertion failure function test_d...
[424.077837] (kms_pm_dc:9000) CRITICAL: Failed assertion: pkgc_flag
[424.077858] (kms_pm_dc:9000) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[418.695004] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[418.695113] (kms_pm_dc:9000) igt_kms-DEBUG: Test requirement passed: plane_id...
[418.695114] (kms_pm_dc:9000) igt_kms-DEBUG: display:     Fixing up initial ro...
[418.695214] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[418.695330] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[418.695434] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[418.695532] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[418.695630] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[418.695724] (kms_pm_dc:9000) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[418.695817] (kms_pm_dc:9000) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[428.690328] (kms_pm_dc:9031) CRITICAL: Test assertion failure function test_d...
[428.690379] (kms_pm_dc:9031) CRITICAL: Failed assertion: pkgc_flag
[428.690398] (kms_pm_dc:9031) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[423.294091] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[423.294187] (kms_pm_dc:9031) igt_kms-DEBUG: Test requirement passed: plane_id...
[423.294189] (kms_pm_dc:9031) igt_kms-DEBUG: display:     Fixing up initial ro...
[423.294281] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[423.294409] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[423.294503] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[423.294596] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[423.294692] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[423.294781] (kms_pm_dc:9031) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[423.294869] (kms_pm_dc:9031) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[431.322389] (kms_pm_dc:8935) CRITICAL: Test assertion failure function test_d...
[431.322453] (kms_pm_dc:8935) CRITICAL: Failed assertion: pkgc_flag
[431.322485] (kms_pm_dc:8935) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[425.940003] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[425.940092] (kms_pm_dc:8935) igt_kms-DEBUG: Test requirement passed: plane_id...
[425.940094] (kms_pm_dc:8935) igt_kms-DEBUG: display:     Fixing up initial ro...
[425.940188] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[425.940311] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[425.940399] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[425.940488] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[425.940578] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[425.940669] (kms_pm_dc:8935) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[425.940754] (kms_pm_dc:8935) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[131.309984] (kms_pm_dc:3795) CRITICAL: Test assertion failure function test_d...
[131.310040] (kms_pm_dc:3795) CRITICAL: Failed assertion: pkgc_flag
[131.310070] (kms_pm_dc:3795) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[125.921586] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[125.921686] (kms_pm_dc:3795) igt_kms-DEBUG: Test requirement passed: plane_id...
[125.921688] (kms_pm_dc:3795) igt_kms-DEBUG: display:     Fixing up initial ro...
[125.921788] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[125.921916] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[125.922022] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[125.922122] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[125.922215] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[125.922316] (kms_pm_dc:3795) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[125.922410] (kms_pm_dc:3795) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[139.175747] (kms_pm_dc:3590) CRITICAL: Test assertion failure function test_d...
[139.175808] (kms_pm_dc:3590) CRITICAL: Failed assertion: pkgc_flag
[139.175834] (kms_pm_dc:3590) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[133.795668] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[133.795766] (kms_pm_dc:3590) igt_kms-DEBUG: Test requirement passed: plane_id...
[133.795767] (kms_pm_dc:3590) igt_kms-DEBUG: display:     Fixing up initial ro...
[133.795868] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[133.795984] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[133.796072] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[133.796161] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[133.796254] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[133.796342] (kms_pm_dc:3590) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[133.796430] (kms_pm_dc:3590) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[135.194597] (kms_pm_dc:3604) CRITICAL: Test assertion failure function test_d...
[135.194668] (kms_pm_dc:3604) CRITICAL: Failed assertion: pkgc_flag
[135.194694] (kms_pm_dc:3604) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[129.814450] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[129.814542] (kms_pm_dc:3604) igt_kms-DEBUG: Test requirement passed: plane_id...
[129.814544] (kms_pm_dc:3604) igt_kms-DEBUG: display:     Fixing up initial ro...
[129.814635] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[129.814741] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[129.814833] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[129.814951] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[129.815041] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[129.815135] (kms_pm_dc:3604) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[129.815232] (kms_pm_dc:3604) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[148.611590] (kms_pm_dc:4599) CRITICAL: Test assertion failure function test_d...
[148.611617] (kms_pm_dc:4599) CRITICAL: Failed assertion: pkgc_flag
[148.611624] (kms_pm_dc:4599) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[143.250508] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[143.250614] (kms_pm_dc:4599) igt_kms-DEBUG: Test requirement passed: plane_id...
[143.250616] (kms_pm_dc:4599) igt_kms-DEBUG: display:     Fixing up initial ro...
[143.250726] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[143.251150] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[143.251289] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[143.251429] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[143.251552] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[143.251658] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[143.251751] (kms_pm_dc:4599) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[186.625491] (kms_pm_dc:4630) CRITICAL: Test assertion failure function test_d...
[186.625586] (kms_pm_dc:4630) CRITICAL: Failed assertion: pkgc_flag
[186.625620] (kms_pm_dc:4630) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[181.255587] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[181.255672] (kms_pm_dc:4630) igt_kms-DEBUG: Test requirement passed: plane_id...
[181.255673] (kms_pm_dc:4630) igt_kms-DEBUG: display:     Fixing up initial ro...
[181.255757] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[181.255861] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[181.255951] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[181.256038] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[181.256123] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[181.256220] (kms_pm_dc:4630) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[181.256305] (kms_pm_dc:4630) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[150.268892] (kms_pm_dc:4612) CRITICAL: Test assertion failure function test_d...
[150.268932] (kms_pm_dc:4612) CRITICAL: Failed assertion: pkgc_flag
[150.268949] (kms_pm_dc:4612) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[144.882925] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[144.883026] (kms_pm_dc:4612) igt_kms-DEBUG: Test requirement passed: plane_id...
[144.883027] (kms_pm_dc:4612) igt_kms-DEBUG: display:     Fixing up initial ro...
[144.883119] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[144.883236] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[144.883326] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[144.883412] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[144.883498] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[144.883589] (kms_pm_dc:4612) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[144.883673] (kms_pm_dc:4612) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[151.718186] (kms_pm_dc:4596) CRITICAL: Test assertion failure function test_d...
[151.718235] (kms_pm_dc:4596) CRITICAL: Failed assertion: pkgc_flag
[151.718253] (kms_pm_dc:4596) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[146.328697] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[146.328790] (kms_pm_dc:4596) igt_kms-DEBUG: Test requirement passed: plane_id...
[146.328792] (kms_pm_dc:4596) igt_kms-DEBUG: display:     Fixing up initial ro...
[146.328886] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[146.329000] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[146.329089] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[146.329182] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[146.329275] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[146.329362] (kms_pm_dc:4596) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[146.329469] (kms_pm_dc:4596) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[143.100523] (kms_pm_dc:4625) CRITICAL: Test assertion failure function test_d...
[143.100614] (kms_pm_dc:4625) CRITICAL: Failed assertion: pkgc_flag
[143.100660] (kms_pm_dc:4625) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[137.702896] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[137.702991] (kms_pm_dc:4625) igt_kms-DEBUG: Test requirement passed: plane_id...
[137.702992] (kms_pm_dc:4625) igt_kms-DEBUG: display:     Fixing up initial ro...
[137.703091] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[137.703202] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[137.703290] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[137.703388] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[137.703476] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[137.703564] (kms_pm_dc:4625) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[137.703651] (kms_pm_dc:4625) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[147.386157] (kms_pm_dc:4624) CRITICAL: Test assertion failure function test_d...
[147.386237] (kms_pm_dc:4624) CRITICAL: Failed assertion: pkgc_flag
[147.386267] (kms_pm_dc:4624) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[142.002942] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[142.003076] (kms_pm_dc:4624) igt_kms-DEBUG: Test requirement passed: plane_id...
[142.003078] (kms_pm_dc:4624) igt_kms-DEBUG: display:     Fixing up initial ro...
[142.003206] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[142.003341] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[142.003456] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[142.003565] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[142.003672] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[142.003776] (kms_pm_dc:4624) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[142.003879] (kms_pm_dc:4624) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[145.797956] (kms_pm_dc:4581) CRITICAL: Test assertion failure function test_d...
[145.798032] (kms_pm_dc:4581) CRITICAL: Failed assertion: pkgc_flag
[145.798060] (kms_pm_dc:4581) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[140.414074] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[140.414165] (kms_pm_dc:4581) igt_kms-DEBUG: Test requirement passed: plane_id...
[140.414167] (kms_pm_dc:4581) igt_kms-DEBUG: display:     Fixing up initial ro...
[140.414435] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[140.414543] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[140.414636] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[140.414726] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[140.414810] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[140.414893] (kms_pm_dc:4581) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[140.414975] (kms_pm_dc:4581) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[151.510358] (kms_pm_dc:4603) CRITICAL: Test assertion failure function test_d...
[151.510423] (kms_pm_dc:4603) CRITICAL: Failed assertion: pkgc_flag
[151.510452] (kms_pm_dc:4603) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[146.125935] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[146.126017] (kms_pm_dc:4603) igt_kms-DEBUG: Test requirement passed: plane_id...
[146.126018] (kms_pm_dc:4603) igt_kms-DEBUG: display:     Fixing up initial ro...
[146.126106] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[146.126207] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[146.126288] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[146.126368] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[146.126448] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[146.126548] (kms_pm_dc:4603) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[146.126640] (kms_pm_dc:4603) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: deep-pkgc
[144.982168] (kms_pm_dc:4599) CRITICAL: Test assertion failure function test_d...
[144.982228] (kms_pm_dc:4599) CRITICAL: Failed assertion: pkgc_flag
[144.982256] (kms_pm_dc:4599) CRITICAL: PKGC10 is not achieved.
Subtest deep-pkgc failed.
**** DEBUG ****
    SetProp plane B.2 "SCALING_FILTER" to 0x0/0
[139.592679] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.2 "C...
[139.592771] (kms_pm_dc:4599) igt_kms-DEBUG: Test requirement passed: plane_id...
[139.592772] (kms_pm_dc:4599) igt_kms-DEBUG: display:     Fixing up initial ro...
[139.592867] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetPlane pipe B, pla...
[139.592990] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "r...
[139.593083] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "p...
[139.593173] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "a...
[139.593263] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "F...
[139.593350] (kms_pm_dc:4599) igt_kms-DEBUG: display:     SetProp plane B.3 "S...
[139.593438] (kms_pm_dc:4599) igt_kms-DEBUG: Test requirement passed: plane_id...
...

Starting subtest: dc6-dpms
[187.188572] (kms_pm_dc:4689) CRITICAL: Test assertion failure function check_...
[187.188748] (kms_pm_dc:4689) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[187.188891] (kms_pm_dc:4689) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[186.014419] (kms_pm_dc:4733) CRITICAL: Test assertion failure function check_...
[186.014540] (kms_pm_dc:4733) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[186.014671] (kms_pm_dc:4733) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[185.638899] (kms_pm_dc:4760) CRITICAL: Test assertion failure function check_...
[185.639028] (kms_pm_dc:4760) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[185.639095] (kms_pm_dc:4760) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[187.878448] (kms_pm_dc:4727) CRITICAL: Test assertion failure function check_...
[187.878545] (kms_pm_dc:4727) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[187.878638] (kms_pm_dc:4727) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[181.473288] (kms_pm_dc:4727) CRITICAL: Test assertion failure function check_...
[181.473361] (kms_pm_dc:4727) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[181.473424] (kms_pm_dc:4727) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[188.898616] (kms_pm_dc:4705) CRITICAL: Test assertion failure function check_...
[188.898713] (kms_pm_dc:4705) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[188.898833] (kms_pm_dc:4705) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[180.743327] (kms_pm_dc:4671) CRITICAL: Test assertion failure function check_...
[180.743403] (kms_pm_dc:4671) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[180.743487] (kms_pm_dc:4671) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[186.830500] (kms_pm_dc:4740) CRITICAL: Test assertion failure function check_...
[186.830565] (kms_pm_dc:4740) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[186.830630] (kms_pm_dc:4740) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-dpms
[183.999725] (kms_pm_dc:4737) CRITICAL: Test assertion failure function check_...
[183.999791] (kms_pm_dc:4737) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[183.999859] (kms_pm_dc:4737) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 0
  DISPLAY_CORE            0
  PIPE_A                  0
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[112.365975] (kms_pm_dc:3397) CRITICAL: Test assertion failure function check_...
[112.366069] (kms_pm_dc:3397) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[112.366120] (kms_pm_dc:3397) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[114.381869] (kms_pm_dc:3395) CRITICAL: Test assertion failure function check_...
[114.382001] (kms_pm_dc:3395) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[114.382046] (kms_pm_dc:3395) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[114.928509] (kms_pm_dc:3390) CRITICAL: Test assertion failure function check_...
[114.928576] (kms_pm_dc:3390) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[114.928609] (kms_pm_dc:3390) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[113.031786] (kms_pm_dc:3408) CRITICAL: Test assertion failure function check_...
[113.031876] (kms_pm_dc:3408) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[113.031925] (kms_pm_dc:3408) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[115.607534] (kms_pm_dc:3387) CRITICAL: Test assertion failure function check_...
[115.607623] (kms_pm_dc:3387) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[115.607702] (kms_pm_dc:3387) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[109.145675] (kms_pm_dc:3392) CRITICAL: Test assertion failure function check_...
[109.145750] (kms_pm_dc:3392) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[109.145789] (kms_pm_dc:3392) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[156.518708] (kms_pm_dc:3414) CRITICAL: Test assertion failure function check_...
[156.518795] (kms_pm_dc:3414) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[156.518839] (kms_pm_dc:3414) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[101.978614] (kms_pm_dc:3400) CRITICAL: Test assertion failure function check_...
[101.978694] (kms_pm_dc:3400) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[101.978736] (kms_pm_dc:3400) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: dc6-psr
[111.223292] (kms_pm_dc:3402) CRITICAL: Test assertion failure function check_...
[111.223379] (kms_pm_dc:3402) CRITICAL: Failed assertion: igt_dc_state_wait_en...
[111.223424] (kms_pm_dc:3402) CRITICAL: DC6 state is not achieved
i915_power_domain_info:
Runtime power status: enabled
Power well/domain         Use count
always-on                 5
  DISPLAY_CORE            1
  PIPE_A                  1
  PIPE_B                  0
  PIPE_C                  0
  PIPE_D                  0
  PIPE_PANEL_FITTER_A     0
  PIPE_PANEL_FITTER_B     0
  PIPE_PANEL_FITTER_C     0
  PIPE_PANEL_FITTER_D     0
...

Starting subtest: flip-basic-fastset
Starting dynamic subtest: pipe-A-eDP-1
[144.492638] (kms_vrr:3008) CRITICAL: Test assertion failure function test_bas...
[144.492647] (kms_vrr:3008) CRITICAL: Failed assertion: result > 75
[144.492658] (kms_vrr:3008) CRITICAL: Refresh rate (80 Hz) 12500000ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Ignoring property "COLOR...
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Ignoring property "COLOR...
[144.379623] (kms_vrr:3008) igt_kms-DEBUG: display: }
[144.380118] (kms_vrr:3008) DEBUG: event_ns - last_event_ns: 12345000 (81.00 Hz)
[144.389459] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: plane_set_fb(434)
[144.389460] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: plane_set_size (2880x...
[144.389460] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
[144.389461] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
[144.389462] (kms_vrr:3008) igt_kms-DEBUG: display: commit {
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.263s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (11.215s)
Starting dynamic subtest: C-eDP1
[495.492719] (kms_flip:6789) CRITICAL: Test assertion failure function run_tes...
[495.492766] (kms_flip:6789) CRITICAL: Failed assertion: end - start < 500
[495.492794] (kms_flip:6789) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[493.475440] (kms_flip:6789) DEBUG: Vblank took 96us
[493.491949] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.013245
last_received_ts = 495.012451
last_seq = 2180
current_ts = 495.029938
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.210s)
Starting dynamic subtest: B-eDP1
[396.606513] (kms_flip:8596) CRITICAL: Test assertion failure function run_tes...
[396.606626] (kms_flip:8596) CRITICAL: Failed assertion: end - start < 500
[396.606690] (kms_flip:8596) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[394.589530] (kms_flip:8596) DEBUG: Vblank took 53us
[394.606088] (kms_flip:8596) DEBUG: name = flip
last_ts = 395.265289
last_received_ts = 395.264618
last_seq = 881
current_ts = 395.281952
current_received_ts = 395.281219
current_seq = 882
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
[379.803667] (kms_flip:8588) CRITICAL: Test assertion failure function run_tes...
[379.803781] (kms_flip:8588) CRITICAL: Failed assertion: end - start < 500
[379.803858] (kms_flip:8588) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[377.785216] (kms_flip:8588) DEBUG: Vblank took 52us
[377.801800] (kms_flip:8588) DEBUG: name = flip
last_ts = 378.705292
last_received_ts = 378.704620
last_seq = 2055
current_ts = 378.721954
current_received_ts = 378.721252
current_seq = 2056
count = 289
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.196s)
Starting dynamic subtest: B-eDP1
[386.316318] (kms_flip:8583) CRITICAL: Test assertion failure function run_tes...
[386.316403] (kms_flip:8583) CRITICAL: Failed assertion: end - start < 500
[386.316454] (kms_flip:8583) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[384.299296] (kms_flip:8583) DEBUG: Vblank took 102us
[384.315851] (kms_flip:8583) DEBUG: name = flip
last_ts = 385.820282
last_received_ts = 385.819641
last_seq = 733
current_ts = 385.836945
current_received_ts = 385.836273
current_seq = 734
...

Starting subtest: non-zero-reason-all
Starting dynamic subtest: oag-0
[261.381965] (xe_oa:6894) CRITICAL: Test assertion failure function test_non_z...
[261.381996] (xe_oa:6894) CRITICAL: Failed assertion: reason != 0
[261.382010] (xe_oa:6894) CRITICAL: error: 0 == 0
Dynamic subtest oag-0 failed.
**** DEBUG ****
[261.344160] (xe_oa:6894) DEBUG: oa_unit 0:0 - TestOa metric set UUID = 24c33f...
[261.344178] (xe_oa:6894) DEBUG: Ready to read about 393216 bytes
[261.344187] (xe_oa:6894) intel_bufops-DEBUG: generation: 20, supported tiles:...
[261.347384] (xe_oa:6894) intel_allocator_simple-DEBUG: Using simple allocator
[261.381284] (xe_oa:6894) intel_allocator_simple-DEBUG: <ial: 0x58cb8f888370, ...
[261.381288] (xe_oa:6894) intel_allocator_simple-DEBUG: <ial: 0x58cb8f888370, ...
[261.381960] (xe_oa:6894) DEBUG: Got 392832 bytes
[261.381965] (xe_oa:6894) CRITICAL: Test assertion failure function test_non_z...
[261.381996] (xe_oa:6894) CRITICAL: Failed assertion: reason != 0
[261.382010] (xe_oa:6894) CRITICAL: error: 0 == 0
...

Starting subtest: non-zero-reason-all
Starting dynamic subtest: oag-0
[261.381965] (xe_oa:6894) CRITICAL: Test assertion failure function test_non_z...
[261.381996] (xe_oa:6894) CRITICAL: Failed assertion: reason != 0
[261.382010] (xe_oa:6894) CRITICAL: error: 0 == 0
Dynamic subtest oag-0 failed.
**** DEBUG ****
[261.344160] (xe_oa:6894) DEBUG: oa_unit 0:0 - TestOa metric set UUID = 24c33f...
[261.344178] (xe_oa:6894) DEBUG: Ready to read about 393216 bytes
[261.344187] (xe_oa:6894) intel_bufops-DEBUG: generation: 20, supported tiles:...
[261.347384] (xe_oa:6894) intel_allocator_simple-DEBUG: Using simple allocator
[261.381284] (xe_oa:6894) intel_allocator_simple-DEBUG: <ial: 0x58cb8f888370, ...
[261.381288] (xe_oa:6894) intel_allocator_simple-DEBUG: <ial: 0x58cb8f888370, ...
[261.381960] (xe_oa:6894) DEBUG: Got 392832 bytes
[261.381965] (xe_oa:6894) CRITICAL: Test assertion failure function test_non_z...
[261.381996] (xe_oa:6894) CRITICAL: Failed assertion: reason != 0
[261.382010] (xe_oa:6894) CRITICAL: error: 0 == 0
...

Starting subtest: seamless-rr-switch-virtual
Starting dynamic subtest: pipe-A-eDP-1
[476.223937] (kms_vrr:8803) CRITICAL: Test assertion failure function test_sea...
[476.223945] (kms_vrr:8803) CRITICAL: Failed assertion: result > 75
[476.223952] (kms_vrr:8803) CRITICAL: Refresh rate (120 Hz) 8333333ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
commit {
[476.162073] (kms_vrr:8803) igt_kms-DEBUG: display:     populating plane data:...
[476.162074] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_X"...
[476.162075] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y"...
[476.162075] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_W"...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_H"...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H...
[476.162077] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "FB_ID"...
[476.162077] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
...

Starting subtest: seamless-rr-switch-virtual
Starting dynamic subtest: pipe-A-eDP-1
[215.891468] (kms_vrr:6242) CRITICAL: Test assertion failure function test_sea...
[215.891477] (kms_vrr:6242) CRITICAL: Failed assertion: result > 75
[215.891487] (kms_vrr:6242) CRITICAL: Refresh rate (120 Hz) 8333333ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
commit {
[215.839685] (kms_vrr:6242) igt_kms-DEBUG: display:     populating plane data:...
[215.839685] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_X"...
[215.839686] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y"...
[215.839686] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_W"...
[215.839687] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_H"...
[215.839687] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "FB_ID"...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[374.328833] (kms_flip:9958) CRITICAL: Test assertion failure function run_tes...
[374.328922] (kms_flip:9958) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[372.311670] (kms_flip:9958) DEBUG: name = flip
last_ts = 373.845764
last_received_ts = 373.845154
last_seq = 764
current_ts = 373.862427
current_received_ts = 373.861877
current_seq = 765
count = 109
seq_step = 1
[372.311739] (kms_flip:9958) DEBUG: Vblank took 61us
[372.328310] (kms_flip:9958) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[368.722577] (kms_flip:8441) CRITICAL: Test assertion failure function run_tes...
[368.722671] (kms_flip:8441) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[366.704977] (kms_flip:8441) DEBUG: name = flip
last_ts = 368.212555
last_received_ts = 368.211975
last_seq = 5669
current_ts = 368.229218
current_received_ts = 368.228668
current_seq = 5670
count = 129
seq_step = 1
[366.705115] (kms_flip:8441) DEBUG: Vblank took 131us
[366.721612] (kms_flip:8441) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[246.344421] (kms_flip:5567) CRITICAL: Test assertion failure function run_tes...
[246.344521] (kms_flip:5567) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[244.225691] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.225706] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.225723] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226309] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[244.226314] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.226359] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.226367] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226383] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226773] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[244.226775] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.229204] (kms_flip:5567) intel_allocator_simple-DEBUG: Using simple alloca...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[542.109544] (kms_flip:9696) CRITICAL: Test assertion failure function run_tes...
[542.109678] (kms_flip:9696) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[540.090711] (kms_flip:9696) DEBUG: name = flip
last_ts = 541.723755
last_received_ts = 541.723083
last_seq = 4249
current_ts = 541.740417
current_received_ts = 541.739807
current_seq = 4250
count = 238
seq_step = 1
[540.090785] (kms_flip:9696) DEBUG: Vblank took 68us
[540.107438] (kms_flip:9696) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[546.957544] (kms_flip:9705) CRITICAL: Test assertion failure function run_tes...
[546.957612] (kms_flip:9705) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[544.940328] (kms_flip:9705) DEBUG: name = flip
last_ts = 545.668518
last_received_ts = 545.667969
last_seq = 4219
current_ts = 545.685242
current_received_ts = 545.684509
current_seq = 4220
count = 201
seq_step = 1
[544.940447] (kms_flip:9705) DEBUG: Vblank took 110us
[544.957018] (kms_flip:9705) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.214s)
Starting dynamic subtest: B-eDP1
[542.155362] (kms_flip:9726) CRITICAL: Test assertion failure function run_tes...
[542.155466] (kms_flip:9726) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[539.042739] (kms_flip:9726) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[539.042831] (kms_flip:9726) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[539.042858] (kms_flip:9726) drmtest-DEBUG: Test requirement passed: is_intel_...
[539.042914] (kms_flip:9726) drmtest-DEBUG: Test requirement passed: is_intel_...
[539.043985] (kms_flip:9726) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[539.043991] (kms_flip:9726) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[539.051419] (kms_flip:9726) intel_allocator_simple-DEBUG: Using simple alloca...
[539.060232] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71721...
[539.060235] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71721...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.240s)
Starting dynamic subtest: B-eDP1
[549.478265] (kms_flip:9727) CRITICAL: Test assertion failure function run_tes...
[549.478361] (kms_flip:9727) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[547.461349] (kms_flip:9727) DEBUG: name = flip
last_ts = 549.221375
last_received_ts = 549.220825
last_seq = 848
current_ts = 549.238037
current_received_ts = 549.237488
current_seq = 849
count = 38
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[551.090601] (kms_flip:9757) CRITICAL: Test assertion failure function run_tes...
[551.090683] (kms_flip:9757) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[549.073216] (kms_flip:9757) DEBUG: name = flip
last_ts = 550.328552
last_received_ts = 550.328003
last_seq = 4321
current_ts = 550.345215
current_received_ts = 550.344666
current_seq = 4322
count = 315
seq_step = 1
[549.073283] (kms_flip:9757) DEBUG: Vblank took 60us
[549.089876] (kms_flip:9757) DEBUG: name = flip
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
Dynamic subtest pipe-A-eDP-1: SUCCESS (2.815s)
Starting dynamic subtest: pipe-B-eDP-1
[299.802619] (kms_setmode:5403) CRITICAL: Test assertion failure function test...
[299.802649] (kms_setmode:5403) CRITICAL: Failed assertion: status
[299.802669] (kms_setmode:5403) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[294.961695] (kms_setmode:5403) INFO:   Test id#2 CRTC count 1
[294.961710] (kms_setmode:5403) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[294.961728] (kms_setmode:5403) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[294.962284] (kms_setmode:5403) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[294.962291] (kms_setmode:5403) ioctl_wrappers-DEBUG: Test requirement passed:...
[294.973567] (kms_setmode:5403) intel_allocator_simple-DEBUG: Using simple all...
[294.979306] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c8318c...
[294.979308] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c8318c...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[291.220846] (kms_setmode:5453) CRITICAL: Test assertion failure function test...
[291.220872] (kms_setmode:5453) CRITICAL: Failed assertion: status
[291.220897] (kms_setmode:5453) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[286.464854] (kms_setmode:5453) INFO:   Test id#1 CRTC count 1
[286.464864] (kms_setmode:5453) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[286.464872] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[286.465254] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[286.465257] (kms_setmode:5453) ioctl_wrappers-DEBUG: Test requirement passed:...
[286.468183] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.473213] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.473215] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.486606] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.491463] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6923...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[293.137448] (kms_setmode:5457) CRITICAL: Test assertion failure function test...
[293.137471] (kms_setmode:5457) CRITICAL: Failed assertion: status
[293.137495] (kms_setmode:5457) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[288.384136] (kms_setmode:5457) INFO:   Test id#1 CRTC count 1
[288.384150] (kms_setmode:5457) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[288.384163] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[288.384546] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[288.384550] (kms_setmode:5457) ioctl_wrappers-DEBUG: Test requirement passed:...
[288.387003] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.392198] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.392200] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.405609] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.410475] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[300.430383] (kms_setmode:5477) CRITICAL: Test assertion failure function test...
[300.430405] (kms_setmode:5477) CRITICAL: Failed assertion: status
[300.430427] (kms_setmode:5477) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[295.642906] (kms_setmode:5477) INFO:   Test id#1 CRTC count 1
[295.642916] (kms_setmode:5477) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[295.642925] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[295.643268] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[295.643271] (kms_setmode:5477) ioctl_wrappers-DEBUG: Test requirement passed:...
[295.645963] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.651640] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.651643] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.665793] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.671007] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccc...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[302.215870] (kms_setmode:5470) CRITICAL: Test assertion failure function test...
[302.215926] (kms_setmode:5470) CRITICAL: Failed assertion: status
[302.216385] (kms_setmode:5470) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[297.420923] (kms_setmode:5470) INFO:   Test id#1 CRTC count 1
[297.420949] (kms_setmode:5470) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[297.420958] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[297.421268] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[297.421270] (kms_setmode:5470) ioctl_wrappers-DEBUG: Test requirement passed:...
[297.423540] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.428479] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.428481] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.441152] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.446175] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f8...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[292.791464] (kms_setmode:5436) CRITICAL: Test assertion failure function test...
[292.791491] (kms_setmode:5436) CRITICAL: Failed assertion: status
[292.791518] (kms_setmode:5436) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[287.963504] (kms_setmode:5436) INFO:   Test id#1 CRTC count 1
[287.963512] (kms_setmode:5436) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[287.963521] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[287.963979] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[287.963981] (kms_setmode:5436) ioctl_wrappers-DEBUG: Test requirement passed:...
[287.966903] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.971979] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.971981] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.988462] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.993380] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e35...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[299.416436] (kms_setmode:5516) CRITICAL: Test assertion failure function test...
[299.416464] (kms_setmode:5516) CRITICAL: Failed assertion: status
[299.416491] (kms_setmode:5516) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[294.594679] (kms_setmode:5516) INFO:   Test id#1 CRTC count 1
[294.594687] (kms_setmode:5516) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[294.594695] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[294.595124] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[294.595127] (kms_setmode:5516) ioctl_wrappers-DEBUG: Test requirement passed:...
[294.597238] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.602465] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.602467] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.617706] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.622679] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[295.104061] (kms_setmode:5755) CRITICAL: Test assertion failure function test...
[295.104100] (kms_setmode:5755) CRITICAL: Failed assertion: status
[295.104136] (kms_setmode:5755) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[290.357277] (kms_setmode:5755) INFO:   Test id#1 CRTC count 1
[290.357293] (kms_setmode:5755) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[290.357307] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[290.357901] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[290.357909] (kms_setmode:5755) ioctl_wrappers-DEBUG: Test requirement passed:...
[290.361270] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[290.367142] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fee...
[290.367145] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fee...
[290.383978] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[290.389900] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fef...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
Dynamic subtest pipe-A-eDP-1: SUCCESS (2.720s)
Starting dynamic subtest: pipe-B-eDP-1
[297.817694] (kms_setmode:5510) CRITICAL: Test assertion failure function test...
[297.817714] (kms_setmode:5510) CRITICAL: Failed assertion: status
[297.817735] (kms_setmode:5510) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[293.068263] (kms_setmode:5510) INFO:   Test id#2 CRTC count 1
[293.068270] (kms_setmode:5510) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[293.068283] (kms_setmode:5510) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[293.068641] (kms_setmode:5510) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[293.068644] (kms_setmode:5510) ioctl_wrappers-DEBUG: Test requirement passed:...
[293.072810] (kms_setmode:5510) intel_allocator_simple-DEBUG: Using simple all...
[293.078484] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
[293.078487] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
...

Starting subtest: pat-sw-hw-compare
[239.044349] (xe_pat:7333) CRITICAL: Test assertion failure function pat_sw_hw...
[239.044369] (xe_pat:7333) CRITICAL: Failed assertion: matches == 1
[239.044380] (xe_pat:7333) CRITICAL: Last errno: 2, No such file or directory
[239.044388] (xe_pat:7333) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[239.042935] (xe_pat:7333) igt_device-DEBUG: Test requirement passed: pci_dev
[239.043551] (xe_pat:7333) igt_sriov_device-DEBUG: Test requirement passed: (v...
[239.043553] (xe_pat:7333) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[239.043956] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[239.043958] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[239.043958] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[239.043959] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[239.043960] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[239.043961] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[239.043961] (xe_pat:7333) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[245.310431] (xe_pat:7350) CRITICAL: Test assertion failure function pat_sw_hw...
[245.310449] (xe_pat:7350) CRITICAL: Failed assertion: matches == 1
[245.310458] (xe_pat:7350) CRITICAL: Last errno: 2, No such file or directory
[245.310466] (xe_pat:7350) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[245.309243] (xe_pat:7350) igt_device-DEBUG: Test requirement passed: pci_dev
[245.309755] (xe_pat:7350) igt_sriov_device-DEBUG: Test requirement passed: (v...
[245.309757] (xe_pat:7350) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[245.310105] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[245.310106] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[245.310107] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[245.310107] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[245.310108] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[245.310108] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[245.310108] (xe_pat:7350) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[241.632074] (xe_pat:7378) CRITICAL: Test assertion failure function pat_sw_hw...
[241.632091] (xe_pat:7378) CRITICAL: Failed assertion: matches == 1
[241.632101] (xe_pat:7378) CRITICAL: Last errno: 2, No such file or directory
[241.632107] (xe_pat:7378) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[241.630945] (xe_pat:7378) igt_device-DEBUG: Test requirement passed: pci_dev
[241.631457] (xe_pat:7378) igt_sriov_device-DEBUG: Test requirement passed: (v...
[241.631458] (xe_pat:7378) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[241.631811] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[241.631812] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[241.631812] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[241.631813] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[241.631813] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[241.631814] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[241.631814] (xe_pat:7378) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[78.999684] (xe_pat:2498) CRITICAL: Test assertion failure function pat_sw_hw_...
[78.999700] (xe_pat:2498) CRITICAL: Failed assertion: matches == 1
[78.999710] (xe_pat:2498) CRITICAL: Last errno: 2, No such file or directory
[78.999716] (xe_pat:2498) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[78.996979] (xe_pat:2498) igt_device-DEBUG: Test requirement passed: pci_dev
[78.999131] (xe_pat:2498) igt_sriov_device-DEBUG: Test requirement passed: (va...
[78.999137] (xe_pat:2498) DEBUG: Test requirement passed: !(intel_is_vf_device...
[78.999402] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[78.999403] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[78.999404] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[78.999404] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[78.999405] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[78.999405] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[78.999406] (xe_pat:2498) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[128.545199] (xe_pat:4531) CRITICAL: Test assertion failure function pat_sw_hw...
[128.545217] (xe_pat:4531) CRITICAL: Failed assertion: matches == 1
[128.545229] (xe_pat:4531) CRITICAL: Last errno: 2, No such file or directory
[128.545236] (xe_pat:4531) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[128.543858] (xe_pat:4531) igt_device-DEBUG: Test requirement passed: pci_dev
[128.544725] (xe_pat:4531) igt_sriov_device-DEBUG: Test requirement passed: (v...
[128.544727] (xe_pat:4531) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[128.544905] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[128.544905] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[128.544906] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[128.544906] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[128.544907] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[128.544907] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[128.544908] (xe_pat:4531) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[126.437829] (xe_pat:4567) CRITICAL: Test assertion failure function pat_sw_hw...
[126.437854] (xe_pat:4567) CRITICAL: Failed assertion: matches == 1
[126.437866] (xe_pat:4567) CRITICAL: Last errno: 2, No such file or directory
[126.437875] (xe_pat:4567) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[126.436067] (xe_pat:4567) igt_device-DEBUG: Test requirement passed: pci_dev
[126.437089] (xe_pat:4567) igt_sriov_device-DEBUG: Test requirement passed: (v...
[126.437095] (xe_pat:4567) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[126.437476] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[126.437478] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[126.437478] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[126.437479] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[126.437479] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[126.437480] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[126.437481] (xe_pat:4567) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[94.830089] (xe_pat:2509) CRITICAL: Test assertion failure function pat_sw_hw_...
[94.830109] (xe_pat:2509) CRITICAL: Failed assertion: matches == 1
[94.830119] (xe_pat:2509) CRITICAL: Last errno: 2, No such file or directory
[94.830129] (xe_pat:2509) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[94.828311] (xe_pat:2509) igt_device-DEBUG: Test requirement passed: pci_dev
[94.829396] (xe_pat:2509) igt_sriov_device-DEBUG: Test requirement passed: (va...
[94.829397] (xe_pat:2509) DEBUG: Test requirement passed: !(intel_is_vf_device...
[94.829762] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[94.829763] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[94.829764] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[94.829764] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[94.829765] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[94.829766] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[94.829766] (xe_pat:2509) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[101.323567] (xe_pat:2417) CRITICAL: Test assertion failure function pat_sw_hw...
[101.323583] (xe_pat:2417) CRITICAL: Failed assertion: matches == 1
[101.323595] (xe_pat:2417) CRITICAL: Last errno: 2, No such file or directory
[101.323605] (xe_pat:2417) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[101.321742] (xe_pat:2417) igt_device-DEBUG: Test requirement passed: pci_dev
[101.322831] (xe_pat:2417) igt_sriov_device-DEBUG: Test requirement passed: (v...
[101.322832] (xe_pat:2417) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[101.323204] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[101.323205] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[101.323206] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[101.323207] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[101.323207] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[101.323208] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[101.323208] (xe_pat:2417) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[92.039574] (xe_pat:2422) CRITICAL: Test assertion failure function pat_sw_hw_...
[92.039593] (xe_pat:2422) CRITICAL: Failed assertion: matches == 1
[92.039603] (xe_pat:2422) CRITICAL: Last errno: 2, No such file or directory
[92.039611] (xe_pat:2422) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[92.037790] (xe_pat:2422) igt_device-DEBUG: Test requirement passed: pci_dev
[92.038868] (xe_pat:2422) igt_sriov_device-DEBUG: Test requirement passed: (va...
[92.038870] (xe_pat:2422) DEBUG: Test requirement passed: !(intel_is_vf_device...
[92.039237] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[92.039238] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[92.039239] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[92.039240] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[92.039240] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[92.039241] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[92.039242] (xe_pat:2422) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[100.622097] (xe_pat:2402) CRITICAL: Test assertion failure function pat_sw_hw...
[100.622121] (xe_pat:2402) CRITICAL: Failed assertion: matches == 1
[100.622131] (xe_pat:2402) CRITICAL: Last errno: 2, No such file or directory
[100.622137] (xe_pat:2402) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[100.619919] (xe_pat:2402) igt_device-DEBUG: Test requirement passed: pci_dev
[100.621382] (xe_pat:2402) igt_sriov_device-DEBUG: Test requirement passed: (v...
[100.621387] (xe_pat:2402) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[100.621800] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[100.621801] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[100.621801] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[100.621802] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[100.621802] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[100.621803] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[100.621803] (xe_pat:2402) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[92.115299] (xe_pat:2421) CRITICAL: Test assertion failure function pat_sw_hw_...
[92.115309] (xe_pat:2421) CRITICAL: Failed assertion: matches == 1
[92.115316] (xe_pat:2421) CRITICAL: Last errno: 2, No such file or directory
[92.115320] (xe_pat:2421) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[92.113598] (xe_pat:2421) igt_device-DEBUG: Test requirement passed: pci_dev
[92.114667] (xe_pat:2421) igt_sriov_device-DEBUG: Test requirement passed: (va...
[92.114668] (xe_pat:2421) DEBUG: Test requirement passed: !(intel_is_vf_device...
[92.115016] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[92.115017] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[92.115018] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[92.115018] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[92.115019] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[92.115019] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[92.115020] (xe_pat:2421) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[125.998043] (xe_pat:2489) CRITICAL: Test assertion failure function pat_sw_hw...
[125.998054] (xe_pat:2489) CRITICAL: Failed assertion: matches == 1
[125.998063] (xe_pat:2489) CRITICAL: Last errno: 2, No such file or directory
[125.998070] (xe_pat:2489) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[125.995808] (xe_pat:2489) igt_device-DEBUG: Test requirement passed: pci_dev
[125.997330] (xe_pat:2489) igt_sriov_device-DEBUG: Test requirement passed: (v...
[125.997331] (xe_pat:2489) DEBUG: Test requirement passed: !(intel_is_vf_devic...
[125.997705] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[125.997706] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[125.997707] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[125.997708] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[125.997708] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[125.997709] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[125.997709] (xe_pat:2489) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[97.307419] (xe_pat:2414) CRITICAL: Test assertion failure function pat_sw_hw_...
[97.307438] (xe_pat:2414) CRITICAL: Failed assertion: matches == 1
[97.307448] (xe_pat:2414) CRITICAL: Last errno: 2, No such file or directory
[97.307455] (xe_pat:2414) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[97.305781] (xe_pat:2414) igt_device-DEBUG: Test requirement passed: pci_dev
[97.306778] (xe_pat:2414) igt_sriov_device-DEBUG: Test requirement passed: (va...
[97.306779] (xe_pat:2414) DEBUG: Test requirement passed: !(intel_is_vf_device...
[97.307137] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[97.307137] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[97.307138] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[97.307139] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[97.307139] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[97.307140] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[97.307140] (xe_pat:2414) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[95.329430] (xe_pat:2415) CRITICAL: Test assertion failure function pat_sw_hw_...
[95.329443] (xe_pat:2415) CRITICAL: Failed assertion: matches == 1
[95.329451] (xe_pat:2415) CRITICAL: Last errno: 2, No such file or directory
[95.329457] (xe_pat:2415) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[95.327037] (xe_pat:2415) igt_device-DEBUG: Test requirement passed: pci_dev
[95.328696] (xe_pat:2415) igt_sriov_device-DEBUG: Test requirement passed: (va...
[95.328697] (xe_pat:2415) DEBUG: Test requirement passed: !(intel_is_vf_device...
[95.329081] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[95.329082] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[95.329083] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[95.329084] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[95.329084] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[95.329085] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[95.329085] (xe_pat:2415) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: pat-sw-hw-compare
[91.346087] (xe_pat:2459) CRITICAL: Test assertion failure function pat_sw_hw_...
[91.346100] (xe_pat:2459) CRITICAL: Failed assertion: matches == 1
[91.346107] (xe_pat:2459) CRITICAL: Last errno: 2, No such file or directory
[91.346111] (xe_pat:2459) CRITICAL: error: 0 != 1
Subtest pat-sw-hw-compare failed.
**** DEBUG ****
[91.344505] (xe_pat:2459) igt_device-DEBUG: Test requirement passed: pci_dev
[91.345455] (xe_pat:2459) igt_sriov_device-DEBUG: Test requirement passed: (va...
[91.345456] (xe_pat:2459) DEBUG: Test requirement passed: !(intel_is_vf_device...
[91.345802] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
[91.345802] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
[91.345803] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
[91.345803] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
[91.345804] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
[91.345804] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
[91.345805] (xe_pat:2459) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
...

Starting subtest: dpms-vs-vblank-race
Starting dynamic subtest: A-eDP1
[168.731963] (kms_flip:3741) CRITICAL: Test assertion failure function calibra...
[168.732021] (kms_flip:3741) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[167.241549] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[167.241561] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.241574] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.241997] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[167.242000] (kms_flip:3741) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[167.242039] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[167.242046] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.242059] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.242443] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[167.242445] (kms_flip:3741) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[167.246045] (kms_flip:3741) intel_allocator_simple-DEBUG: Using simple alloca...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[268.691979] (kms_flip:7065) CRITICAL: Test assertion failure function calibra...
[268.692015] (kms_flip:7065) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[268.692058] (kms_flip:7065) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[267.236115] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.236127] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236142] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236473] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[267.236476] (kms_flip:7065) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[267.236520] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.236527] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.236540] (kms_flip:7065) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.237087] (kms_flip:7065) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[267.237089] (kms_flip:7065) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[269.513573] (kms_flip:7041) CRITICAL: Test assertion failure function calibra...
[269.513597] (kms_flip:7041) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[269.513620] (kms_flip:7041) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[268.028574] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[268.028587] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.028601] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.028978] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[268.028981] (kms_flip:7041) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[268.029020] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[268.029026] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.029039] (kms_flip:7041) drmtest-DEBUG: Test requirement passed: is_intel_...
[268.029639] (kms_flip:7041) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[268.029641] (kms_flip:7041) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-eDP1
[269.067156] (kms_flip:7070) CRITICAL: Test assertion failure function calibra...
[269.067181] (kms_flip:7070) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
[269.067212] (kms_flip:7070) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[267.565095] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.565107] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565119] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565483] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[267.565487] (kms_flip:7070) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[267.565529] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[267.565536] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.565549] (kms_flip:7070) drmtest-DEBUG: Test requirement passed: is_intel_...
[267.566038] (kms_flip:7070) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[267.566040] (kms_flip:7070) ioctl_wrappers-DEBUG: Test requirement passed: ig...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[374.328833] (kms_flip:9958) CRITICAL: Test assertion failure function run_tes...
[374.328922] (kms_flip:9958) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[372.311670] (kms_flip:9958) DEBUG: name = flip
last_ts = 373.845764
last_received_ts = 373.845154
last_seq = 764
current_ts = 373.862427
current_received_ts = 373.861877
current_seq = 765
count = 109
seq_step = 1
[372.311739] (kms_flip:9958) DEBUG: Vblank took 61us
[372.328310] (kms_flip:9958) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[368.722577] (kms_flip:8441) CRITICAL: Test assertion failure function run_tes...
[368.722671] (kms_flip:8441) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[366.704977] (kms_flip:8441) DEBUG: name = flip
last_ts = 368.212555
last_received_ts = 368.211975
last_seq = 5669
current_ts = 368.229218
current_received_ts = 368.228668
current_seq = 5670
count = 129
seq_step = 1
[366.705115] (kms_flip:8441) DEBUG: Vblank took 131us
[366.721612] (kms_flip:8441) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[246.344421] (kms_flip:5567) CRITICAL: Test assertion failure function run_tes...
[246.344521] (kms_flip:5567) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[244.225691] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.225706] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.225723] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226309] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[244.226314] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.226359] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.226367] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226383] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226773] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[244.226775] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.229204] (kms_flip:5567) intel_allocator_simple-DEBUG: Using simple alloca...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[542.109544] (kms_flip:9696) CRITICAL: Test assertion failure function run_tes...
[542.109678] (kms_flip:9696) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[540.090711] (kms_flip:9696) DEBUG: name = flip
last_ts = 541.723755
last_received_ts = 541.723083
last_seq = 4249
current_ts = 541.740417
current_received_ts = 541.739807
current_seq = 4250
count = 238
seq_step = 1
[540.090785] (kms_flip:9696) DEBUG: Vblank took 68us
[540.107438] (kms_flip:9696) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[546.957544] (kms_flip:9705) CRITICAL: Test assertion failure function run_tes...
[546.957612] (kms_flip:9705) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[544.940328] (kms_flip:9705) DEBUG: name = flip
last_ts = 545.668518
last_received_ts = 545.667969
last_seq = 4219
current_ts = 545.685242
current_received_ts = 545.684509
current_seq = 4220
count = 201
seq_step = 1
[544.940447] (kms_flip:9705) DEBUG: Vblank took 110us
[544.957018] (kms_flip:9705) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[551.090601] (kms_flip:9757) CRITICAL: Test assertion failure function run_tes...
[551.090683] (kms_flip:9757) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[549.073216] (kms_flip:9757) DEBUG: name = flip
last_ts = 550.328552
last_received_ts = 550.328003
last_seq = 4321
current_ts = 550.345215
current_received_ts = 550.344666
current_seq = 4322
count = 315
seq_step = 1
[549.073283] (kms_flip:9757) DEBUG: Vblank took 60us
[549.089876] (kms_flip:9757) DEBUG: name = flip
...

Starting dynamic subtest: B-eDP1
[542.155362] (kms_flip:9726) CRITICAL: Test assertion failure function run_tes...
[542.155466] (kms_flip:9726) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[539.042739] (kms_flip:9726) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[539.042831] (kms_flip:9726) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[539.042858] (kms_flip:9726) drmtest-DEBUG: Test requirement passed: is_intel_...
[539.042914] (kms_flip:9726) drmtest-DEBUG: Test requirement passed: is_intel_...
[539.043985] (kms_flip:9726) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[539.043991] (kms_flip:9726) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[539.051419] (kms_flip:9726) intel_allocator_simple-DEBUG: Using simple alloca...
[539.060232] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71721...
[539.060235] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71721...
[539.076598] (kms_flip:9726) intel_allocator_simple-DEBUG: Using simple alloca...
[539.082337] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71237...
[539.082341] (kms_flip:9726) intel_allocator_simple-DEBUG: <ial: 0x62d10a71237...
...

Starting dynamic subtest: B-eDP1
[549.478265] (kms_flip:9727) CRITICAL: Test assertion failure function run_tes...
[549.478361] (kms_flip:9727) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[547.461349] (kms_flip:9727) DEBUG: name = flip
last_ts = 549.221375
last_received_ts = 549.220825
last_seq = 848
current_ts = 549.238037
current_received_ts = 549.237488
current_seq = 849
count = 38
seq_step = 1
[547.461426] (kms_flip:9727) DEBUG: Vblank took 71us
[547.478002] (kms_flip:9727) DEBUG: name = flip
last_ts = 549.238037
...

Starting dynamic subtest: C-eDP1
[553.239847] (kms_flip:9727) CRITICAL: Test assertion failure function run_tes...
[553.239970] (kms_flip:9727) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[551.222322] (kms_flip:9727) DEBUG: name = flip
last_ts = 552.982483
last_received_ts = 552.981934
last_seq = 1139
current_ts = 552.999146
current_received_ts = 552.998596
current_seq = 1140
count = 15
seq_step = 1
[551.222420] (kms_flip:9727) DEBUG: Vblank took 84us
[551.238958] (kms_flip:9727) DEBUG: name = flip
last_ts = 552.999146
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[291.220846] (kms_setmode:5453) CRITICAL: Test assertion failure function test...
[291.220872] (kms_setmode:5453) CRITICAL: Failed assertion: status
[291.220897] (kms_setmode:5453) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[286.464854] (kms_setmode:5453) INFO:   Test id#1 CRTC count 1
[286.464864] (kms_setmode:5453) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[286.464872] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[286.465254] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[286.465257] (kms_setmode:5453) ioctl_wrappers-DEBUG: Test requirement passed:...
[286.468183] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.473213] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.473215] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.486606] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.491463] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6923...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[293.137448] (kms_setmode:5457) CRITICAL: Test assertion failure function test...
[293.137471] (kms_setmode:5457) CRITICAL: Failed assertion: status
[293.137495] (kms_setmode:5457) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[288.384136] (kms_setmode:5457) INFO:   Test id#1 CRTC count 1
[288.384150] (kms_setmode:5457) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[288.384163] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[288.384546] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[288.384550] (kms_setmode:5457) ioctl_wrappers-DEBUG: Test requirement passed:...
[288.387003] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.392198] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.392200] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.405609] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.410475] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[300.430383] (kms_setmode:5477) CRITICAL: Test assertion failure function test...
[300.430405] (kms_setmode:5477) CRITICAL: Failed assertion: status
[300.430427] (kms_setmode:5477) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[295.642906] (kms_setmode:5477) INFO:   Test id#1 CRTC count 1
[295.642916] (kms_setmode:5477) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[295.642925] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[295.643268] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[295.643271] (kms_setmode:5477) ioctl_wrappers-DEBUG: Test requirement passed:...
[295.645963] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.651640] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.651643] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.665793] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.671007] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccc...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[302.215870] (kms_setmode:5470) CRITICAL: Test assertion failure function test...
[302.215926] (kms_setmode:5470) CRITICAL: Failed assertion: status
[302.216385] (kms_setmode:5470) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[297.420923] (kms_setmode:5470) INFO:   Test id#1 CRTC count 1
[297.420949] (kms_setmode:5470) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[297.420958] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[297.421268] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[297.421270] (kms_setmode:5470) ioctl_wrappers-DEBUG: Test requirement passed:...
[297.423540] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.428479] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.428481] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.441152] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.446175] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f8...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[292.791464] (kms_setmode:5436) CRITICAL: Test assertion failure function test...
[292.791491] (kms_setmode:5436) CRITICAL: Failed assertion: status
[292.791518] (kms_setmode:5436) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[287.963504] (kms_setmode:5436) INFO:   Test id#1 CRTC count 1
[287.963512] (kms_setmode:5436) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[287.963521] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[287.963979] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[287.963981] (kms_setmode:5436) ioctl_wrappers-DEBUG: Test requirement passed:...
[287.966903] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.971979] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.971981] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.988462] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.993380] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e35...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[299.416436] (kms_setmode:5516) CRITICAL: Test assertion failure function test...
[299.416464] (kms_setmode:5516) CRITICAL: Failed assertion: status
[299.416491] (kms_setmode:5516) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[294.594679] (kms_setmode:5516) INFO:   Test id#1 CRTC count 1
[294.594687] (kms_setmode:5516) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[294.594695] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[294.595124] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[294.595127] (kms_setmode:5516) ioctl_wrappers-DEBUG: Test requirement passed:...
[294.597238] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.602465] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.602467] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.617706] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.622679] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[295.104061] (kms_setmode:5755) CRITICAL: Test assertion failure function test...
[295.104100] (kms_setmode:5755) CRITICAL: Failed assertion: status
[295.104136] (kms_setmode:5755) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[290.357277] (kms_setmode:5755) INFO:   Test id#1 CRTC count 1
[290.357293] (kms_setmode:5755) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[290.357307] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[290.357901] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[290.357909] (kms_setmode:5755) ioctl_wrappers-DEBUG: Test requirement passed:...
[290.361270] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[290.367142] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fee...
[290.367145] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fee...
[290.383978] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[290.389900] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128fef...
...

Starting dynamic subtest: pipe-B-eDP-1
[299.802619] (kms_setmode:5403) CRITICAL: Test assertion failure function test...
[299.802649] (kms_setmode:5403) CRITICAL: Failed assertion: status
[299.802669] (kms_setmode:5403) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[294.961695] (kms_setmode:5403) INFO:   Test id#2 CRTC count 1
[294.961710] (kms_setmode:5403) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[294.961728] (kms_setmode:5403) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[294.962284] (kms_setmode:5403) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[294.962291] (kms_setmode:5403) ioctl_wrappers-DEBUG: Test requirement passed:...
[294.973567] (kms_setmode:5403) intel_allocator_simple-DEBUG: Using simple all...
[294.979306] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c8318c...
[294.979308] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c8318c...
[294.997322] (kms_setmode:5403) intel_allocator_simple-DEBUG: Using simple all...
[295.003270] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c83192...
[295.003272] (kms_setmode:5403) intel_allocator_simple-DEBUG: <ial: 0x62c83192...
...

Starting dynamic subtest: pipe-B-eDP-1
[296.382719] (kms_setmode:5453) CRITICAL: Test assertion failure function test...
[296.382742] (kms_setmode:5453) CRITICAL: Failed assertion: status
[296.382760] (kms_setmode:5453) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[291.645699] (kms_setmode:5453) INFO:   Test id#2 CRTC count 1
[291.645716] (kms_setmode:5453) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[291.645731] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[291.646267] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[291.646274] (kms_setmode:5453) ioctl_wrappers-DEBUG: Test requirement passed:...
[291.660203] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[291.665150] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6926...
[291.665152] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6926...
[291.679512] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[291.684189] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6926...
[291.684191] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6926...
...

Starting dynamic subtest: pipe-B-eDP-1
[305.595007] (kms_setmode:5477) CRITICAL: Test assertion failure function test...
[305.595033] (kms_setmode:5477) CRITICAL: Failed assertion: status
[305.595058] (kms_setmode:5477) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[300.830812] (kms_setmode:5477) INFO:   Test id#2 CRTC count 1
[300.830821] (kms_setmode:5477) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[300.830829] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[300.831126] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[300.831129] (kms_setmode:5477) ioctl_wrappers-DEBUG: Test requirement passed:...
[300.835743] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[300.841363] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccf...
[300.841365] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccf...
[300.852580] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[300.857893] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccf...
[300.857895] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccf...
...

Starting dynamic subtest: pipe-B-eDP-1
[307.477292] (kms_setmode:5470) CRITICAL: Test assertion failure function test...
[307.477318] (kms_setmode:5470) CRITICAL: Failed assertion: status
[307.477344] (kms_setmode:5470) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[302.661676] (kms_setmode:5470) INFO:   Test id#2 CRTC count 1
[302.661688] (kms_setmode:5470) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[302.661700] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[302.662150] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[302.662153] (kms_setmode:5470) ioctl_wrappers-DEBUG: Test requirement passed:...
[302.666206] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[302.671391] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207fb...
[302.671392] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207fb...
[302.684268] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[302.689465] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207fb...
[302.689467] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207fb...
...

Starting dynamic subtest: pipe-B-eDP-1
[297.951224] (kms_setmode:5436) CRITICAL: Test assertion failure function test...
[297.951244] (kms_setmode:5436) CRITICAL: Failed assertion: status
[297.951263] (kms_setmode:5436) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[293.195565] (kms_setmode:5436) INFO:   Test id#2 CRTC count 1
[293.195585] (kms_setmode:5436) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[293.195605] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[293.196292] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[293.196298] (kms_setmode:5436) ioctl_wrappers-DEBUG: Test requirement passed:...
[293.204020] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[293.210516] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e38...
[293.210520] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e38...
[293.224384] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[293.229230] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e3a...
[293.229233] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e3a...
...

Starting dynamic subtest: pipe-B-eDP-1
[304.678457] (kms_setmode:5516) CRITICAL: Test assertion failure function test...
[304.678515] (kms_setmode:5516) CRITICAL: Failed assertion: status
[304.678566] (kms_setmode:5516) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[299.856122] (kms_setmode:5516) INFO:   Test id#2 CRTC count 1
[299.856131] (kms_setmode:5516) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[299.856154] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[299.856475] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[299.856478] (kms_setmode:5516) ioctl_wrappers-DEBUG: Test requirement passed:...
[299.860608] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[299.866028] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9f...
[299.866032] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9f...
[299.877500] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[299.882570] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[299.882572] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
...

Starting dynamic subtest: pipe-B-eDP-1
[300.283647] (kms_setmode:5755) CRITICAL: Test assertion failure function test...
[300.283671] (kms_setmode:5755) CRITICAL: Failed assertion: status
[300.283694] (kms_setmode:5755) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[295.517640] (kms_setmode:5755) INFO:   Test id#2 CRTC count 1
[295.517649] (kms_setmode:5755) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[295.517658] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[295.517993] (kms_setmode:5755) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[295.517995] (kms_setmode:5755) ioctl_wrappers-DEBUG: Test requirement passed:...
[295.522337] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[295.527460] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128ff5...
[295.527463] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128ff5...
[295.543606] (kms_setmode:5755) intel_allocator_simple-DEBUG: Using simple all...
[295.548884] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128ff7...
[295.548888] (kms_setmode:5755) intel_allocator_simple-DEBUG: <ial: 0x65128ff7...
...

Starting dynamic subtest: pipe-B-eDP-1
[297.817694] (kms_setmode:5510) CRITICAL: Test assertion failure function test...
[297.817714] (kms_setmode:5510) CRITICAL: Failed assertion: status
[297.817735] (kms_setmode:5510) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-B-eDP-1 failed.
**** DEBUG ****
[293.068263] (kms_setmode:5510) INFO:   Test id#2 CRTC count 1
[293.068270] (kms_setmode:5510) INFO:     CRTC[270] [Pipe B] Mode: 2880x1800@6...
[293.068283] (kms_setmode:5510) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[293.068641] (kms_setmode:5510) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[293.068644] (kms_setmode:5510) ioctl_wrappers-DEBUG: Test requirement passed:...
[293.072810] (kms_setmode:5510) intel_allocator_simple-DEBUG: Using simple all...
[293.078484] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
[293.078487] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
[293.091352] (kms_setmode:5510) intel_allocator_simple-DEBUG: Using simple all...
[293.096617] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
[293.096619] (kms_setmode:5510) intel_allocator_simple-DEBUG: <ial: 0x58601dd8...
...

Starting subtest: flip-basic-fastset
Starting dynamic subtest: pipe-A-eDP-1
[144.492638] (kms_vrr:3008) CRITICAL: Test assertion failure function test_bas...
[144.492647] (kms_vrr:3008) CRITICAL: Failed assertion: result > 75
[144.492658] (kms_vrr:3008) CRITICAL: Refresh rate (80 Hz) 12500000ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Ignoring property "COLOR...
[144.376965] (kms_vrr:3008) igt_kms-DEBUG: plane A.0: Ignoring property "COLOR...
[144.379623] (kms_vrr:3008) igt_kms-DEBUG: display: }
[144.380118] (kms_vrr:3008) DEBUG: event_ns - last_event_ns: 12345000 (81.00 Hz)
[144.389459] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: plane_set_fb(434)
[144.389460] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: plane_set_size (2880x...
[144.389460] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: fb_set_position(0,0)
[144.389461] (kms_vrr:3008) igt_kms-DEBUG: display: A.0: fb_set_size(2880x1800)
[144.389462] (kms_vrr:3008) igt_kms-DEBUG: display: commit {
...

Starting subtest: seamless-rr-switch-virtual
Starting dynamic subtest: pipe-A-eDP-1
[476.223937] (kms_vrr:8803) CRITICAL: Test assertion failure function test_sea...
[476.223945] (kms_vrr:8803) CRITICAL: Failed assertion: result > 75
[476.223952] (kms_vrr:8803) CRITICAL: Refresh rate (120 Hz) 8333333ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
commit {
[476.162073] (kms_vrr:8803) igt_kms-DEBUG: display:     populating plane data:...
[476.162074] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_X"...
[476.162075] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y"...
[476.162075] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_W"...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "SRC_H"...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W...
[476.162076] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H...
[476.162077] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "FB_ID"...
[476.162077] (kms_vrr:8803) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
...

Starting subtest: seamless-rr-switch-virtual
Starting dynamic subtest: pipe-A-eDP-1
[215.891468] (kms_vrr:6242) CRITICAL: Test assertion failure function test_sea...
[215.891477] (kms_vrr:6242) CRITICAL: Failed assertion: result > 75
[215.891487] (kms_vrr:6242) CRITICAL: Refresh rate (120 Hz) 8333333ns: Target ...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
commit {
[215.839685] (kms_vrr:6242) igt_kms-DEBUG: display:     populating plane data:...
[215.839685] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_X"...
[215.839686] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_Y"...
[215.839686] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_W"...
[215.839687] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "SRC_H"...
[215.839687] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_W...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_H...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "FB_ID"...
[215.839688] (kms_vrr:6242) igt_kms-DEBUG: plane A.0: Setting property "CRTC_I...
...

Starting subtest: dpms-vs-vblank-race
Starting dynamic subtest: A-eDP1
[168.731963] (kms_flip:3741) CRITICAL: Test assertion failure function calibra...
[168.732021] (kms_flip:3741) CRITICAL: Failed assertion: 3 * stddev / mean < 0...
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[167.241549] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[167.241561] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.241574] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.241997] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[167.242000] (kms_flip:3741) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[167.242039] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[167.242046] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.242059] (kms_flip:3741) drmtest-DEBUG: Test requirement passed: is_intel_...
[167.242443] (kms_flip:3741) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[167.242445] (kms_flip:3741) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[167.246045] (kms_flip:3741) intel_allocator_simple-DEBUG: Using simple alloca...
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
[379.803667] (kms_flip:8588) CRITICAL: Test assertion failure function run_tes...
[379.803781] (kms_flip:8588) CRITICAL: Failed assertion: end - start < 500
[379.803858] (kms_flip:8588) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[377.785216] (kms_flip:8588) DEBUG: Vblank took 52us
[377.801800] (kms_flip:8588) DEBUG: name = flip
last_ts = 378.705292
last_received_ts = 378.704620
last_seq = 2055
current_ts = 378.721954
current_received_ts = 378.721252
current_seq = 2056
count = 289
seq_step = 1
...

Starting dynamic subtest: B-eDP1
[396.606513] (kms_flip:8596) CRITICAL: Test assertion failure function run_tes...
[396.606626] (kms_flip:8596) CRITICAL: Failed assertion: end - start < 500
[396.606690] (kms_flip:8596) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[394.589530] (kms_flip:8596) DEBUG: Vblank took 53us
[394.606088] (kms_flip:8596) DEBUG: name = flip
last_ts = 395.265289
last_received_ts = 395.264618
last_seq = 881
current_ts = 395.281952
current_received_ts = 395.281219
current_seq = 882
count = 173
seq_step = 1
[394.606160] (kms_flip:8596) DEBUG: Vblank took 68us
...

Starting dynamic subtest: B-eDP1
[386.316318] (kms_flip:8583) CRITICAL: Test assertion failure function run_tes...
[386.316403] (kms_flip:8583) CRITICAL: Failed assertion: end - start < 500
[386.316454] (kms_flip:8583) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
[384.299296] (kms_flip:8583) DEBUG: Vblank took 102us
[384.315851] (kms_flip:8583) DEBUG: name = flip
last_ts = 385.820282
last_received_ts = 385.819641
last_seq = 733
current_ts = 385.836945
current_received_ts = 385.836273
current_seq = 734
count = 25
seq_step = 1
[384.315959] (kms_flip:8583) DEBUG: Vblank took 83us
...

Starting dynamic subtest: C-eDP1
[495.492719] (kms_flip:6789) CRITICAL: Test assertion failure function run_tes...
[495.492766] (kms_flip:6789) CRITICAL: Failed assertion: end - start < 500
[495.492794] (kms_flip:6789) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[493.475440] (kms_flip:6789) DEBUG: Vblank took 96us
[493.491949] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.013245
last_received_ts = 495.012451
last_seq = 2180
current_ts = 495.029938
current_received_ts = 495.029083
current_seq = 2181
count = 221
seq_step = 1
[493.492050] (kms_flip:6789) DEBUG: Vblank took 100us
...