[64.931920] Initializing watchdogs
[64.931984]   /dev/watchdog0
[64.951226] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[64.961443] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[64.965571] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.967716] [001/134] (960s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[65.155596] Starting subtest: 2x-long-cursor-vs-flip-atomic
[65.158306] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.002s)
[65.311999] [002/134] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[65.497553] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[65.499248] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[65.691299] [003/134] (959s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[65.872516] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[65.874175] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[66.056955] [004/134] (959s left) kms_big_fb (linear-64bpp-rotate-180)
[66.245429] Starting subtest: linear-64bpp-rotate-180
[68.467460] Subtest linear-64bpp-rotate-180: SUCCESS (2.221s)
[68.712602] [005/134] (957s left) gem_render_copy (y-tiled-to-vebox-linear)
[68.784482] Starting subtest: y-tiled-to-vebox-linear
[68.786629] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[69.051293] [006/134] (956s left) kms_atomic (atomic-plane-damage)
[69.229931] Starting subtest: atomic-plane-damage
[69.616989] Starting dynamic subtest: pipe-A-DP-3
[70.682486] Dynamic subtest pipe-A-DP-3: SUCCESS (1.064s)
[70.683570] Subtest atomic-plane-damage: SUCCESS (1.453s)
[71.021532] [007/134] (954s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[71.528357] Starting subtest: cursor-rapid-movement-max-size
[71.531033] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[71.720214] [008/134] (954s left) syncobj_timeline (device-submit-unordered)
[71.788755] Starting subtest: device-submit-unordered
[71.789611] Subtest device-submit-unordered: SUCCESS (0.000s)
[71.970915] [009/134] (954s left) kms_async_flips (async-flip-dpms)
[72.151866] Starting subtest: async-flip-dpms
[72.381599] Starting dynamic subtest: pipe-A-DP-3
[75.175950] Dynamic subtest pipe-A-DP-3: SUCCESS (2.794s)
[75.484308] Starting dynamic subtest: pipe-B-DP-3
[77.578684] Dynamic subtest pipe-B-DP-3: SUCCESS (2.102s)
[77.764661] Starting dynamic subtest: pipe-C-DP-3
[79.851124] Dynamic subtest pipe-C-DP-3: SUCCESS (2.092s)
[80.099637] Starting dynamic subtest: pipe-D-DP-3
[82.185760] Dynamic subtest pipe-D-DP-3: SUCCESS (2.096s)
[82.186539] Subtest async-flip-dpms: SUCCESS (10.034s)
[82.641327] [010/134] (943s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[82.818981] Starting subtest: coverage-vs-premult-vs-constant
[83.065789] Starting dynamic subtest: pipe-A-DP-3
[83.524498] Dynamic subtest pipe-A-DP-3: SUCCESS (0.458s)
[83.534130] Starting dynamic subtest: pipe-D-DP-3
[84.036227] Dynamic subtest pipe-D-DP-3: SUCCESS (0.511s)
[84.037114] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.218s)
[84.344338] [011/134] (941s left) fbdev (nullptr)
[84.400268] Starting subtest: nullptr
[84.401184] Subtest nullptr: SUCCESS (0.000s)
[84.559542] [012/134] (941s left) kms_color (ctm-0-25)
[84.743949] Starting subtest: ctm-0-25
[84.744842] Starting dynamic subtest: pipe-A-DP-3
[86.476360] Dynamic subtest pipe-A-DP-3: SUCCESS (1.732s)
[86.484516] Starting dynamic subtest: pipe-B-DP-3
[88.126251] Dynamic subtest pipe-B-DP-3: SUCCESS (1.649s)
[88.136173] Starting dynamic subtest: pipe-C-DP-3
[89.778433] Dynamic subtest pipe-C-DP-3: SUCCESS (1.653s)
[89.787748] Starting dynamic subtest: pipe-D-DP-3
[91.407687] Dynamic subtest pipe-D-DP-3: SUCCESS (1.629s)
[91.408637] Subtest ctm-0-25: SUCCESS (6.664s)
[91.703084] [013/134] (934s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[91.930793] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[94.228514] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (2.299s)
[94.468539] [014/134] (931s left) vgem_basic (mmap)
[94.573272] Starting subtest: mmap
[94.578917] Subtest mmap: SUCCESS (0.006s)
[94.708645] [FACT vgem_basic (mmap)] new: kernel.kmod_is_loaded.vgem: true
[94.710845] [015/134] (931s left) gem_mmap_offset (mmap-unmap)
[94.793143] Starting subtest: mmap-unmap
[94.794143] Starting dynamic subtest: lmem0
[95.705211] Dynamic subtest lmem0: SUCCESS (0.913s)
[95.714858] Starting dynamic subtest: smem0
[97.053958] Dynamic subtest smem0: SUCCESS (1.347s)
[97.054954] Subtest mmap-unmap: SUCCESS (2.262s)
[97.367329] [016/134] (929s left) kms_properties (invalid-properties-legacy)
[97.550403] Starting subtest: invalid-properties-legacy
[97.711599] Subtest invalid-properties-legacy: SUCCESS (0.161s)
[97.843217] [017/134] (928s left) dumb_buffer (invalid-bpp)
[97.921934] Starting subtest: invalid-bpp
[97.922862] Subtest invalid-bpp: SUCCESS (0.000s)
[98.115481] [018/134] (928s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[98.302116] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[98.302976] Starting dynamic subtest: pipe-A-valid-mode
[98.384312] Dynamic subtest pipe-A-valid-mode: SKIP (0.081s)
[98.386220] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.082s)
[98.633434] [019/134] (928s left) kms_psr (fbc-pr-sprite-plane-move)
[98.820194] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[98.878184] [020/134] (927s left) gem_fenced_exec_thrash (no-spare-fences)
[98.972193] Subtest no-spare-fences: SKIP (0.000s)
[99.028462] [021/134] (927s left) perf (create-destroy-userspace-config)
[99.435706] Starting subtest: create-destroy-userspace-config
[99.463135] Subtest create-destroy-userspace-config: SUCCESS (0.026s)
[99.514876] [022/134] (927s left) kms_chamelium_frames (hdmi-crc-fast)
[99.799496] Subtest hdmi-crc-fast: SKIP (0.000s)
[99.950611] [023/134] (927s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[100.139667] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[100.141279] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[100.323711] [024/134] (926s left) i915_selftest (live)
[101.480645] Starting subtest: live
[101.481714] Starting dynamic subtest: sanitycheck
[103.049193] Dynamic subtest sanitycheck: SUCCESS (1.569s)
[103.057187] Starting dynamic subtest: uncore
[104.622239] Dynamic subtest uncore: SUCCESS (1.572s)
[104.631070] Starting dynamic subtest: workarounds
[120.928257] Dynamic subtest workarounds: FAIL (16.108s)
[120.934601] Starting dynamic subtest: gt_engines
[123.065043] Dynamic subtest gt_engines: SUCCESS (2.333s)
[123.073144] Starting dynamic subtest: gt_timelines
[142.633243] Dynamic subtest gt_timelines: SUCCESS (19.568s)
[142.641248] Starting dynamic subtest: gt_contexts
[144.220095] Dynamic subtest gt_contexts: SUCCESS (1.586s)
[144.228189] Starting dynamic subtest: gt_lrc
[150.556199] Dynamic subtest gt_lrc: SUCCESS (6.336s)
[150.563936] Starting dynamic subtest: gt_mocs
[153.504941] Dynamic subtest gt_mocs: SUCCESS (2.948s)
[153.513069] Starting dynamic subtest: gt_pm
[157.684990] Dynamic subtest gt_pm: SUCCESS (4.179s)
[157.692778] Starting dynamic subtest: gt_heartbeat
[159.294988] Dynamic subtest gt_heartbeat: SUCCESS (1.605s)
[159.303174] Starting dynamic subtest: gt_tlb
[171.762325] Dynamic subtest gt_tlb: SUCCESS (12.470s)
[171.771295] Starting dynamic subtest: requests
[185.275236] Dynamic subtest requests: SUCCESS (13.512s)
[185.283602] Starting dynamic subtest: migrate
[190.962103] Dynamic subtest migrate: SUCCESS (5.686s)
[190.970359] Starting dynamic subtest: active
[192.566071] Dynamic subtest active: SUCCESS (1.603s)
[192.576455] Starting dynamic subtest: objects
[194.905066] Dynamic subtest objects: SUCCESS (2.338s)
[194.913059] Starting dynamic subtest: mman
[201.550050] Dynamic subtest mman: SUCCESS (6.644s)
[201.559406] Starting dynamic subtest: dmabuf
[203.187930] Dynamic subtest dmabuf: SUCCESS (1.637s)
[203.195991] Starting dynamic subtest: vma
[204.719795] Dynamic subtest vma: SUCCESS (1.530s)
[204.750573] Starting dynamic subtest: coherency
[209.036110] Dynamic subtest coherency: SUCCESS (4.314s)
[209.044123] Starting dynamic subtest: gtt
[219.078326] Dynamic subtest gtt: SUCCESS (10.041s)
[219.086913] Starting dynamic subtest: gem
[220.841126] Dynamic subtest gem: SUCCESS (1.762s)
[220.849985] Starting dynamic subtest: evict
[223.841992] Dynamic subtest evict: SUCCESS (3.000s)
[223.849841] Starting dynamic subtest: hugepages
[241.977984] Dynamic subtest hugepages: SUCCESS (18.135s)
[241.985487] Starting dynamic subtest: gem_contexts
[275.365263] Dynamic subtest gem_contexts: SUCCESS (33.386s)
[275.374300] Starting dynamic subtest: client
[276.986206] Dynamic subtest client: SUCCESS (1.620s)
[276.994254] Starting dynamic subtest: gem_migrate
[280.583018] Dynamic subtest gem_migrate: SUCCESS (3.596s)
[280.591071] Starting dynamic subtest: reset
[282.240069] Dynamic subtest reset: SUCCESS (1.656s)
[282.250732] Starting dynamic subtest: memory_region
[285.401967] Dynamic subtest memory_region: SUCCESS (3.161s)
[285.409447] Starting dynamic subtest: hangcheck
[315.992356] Dynamic subtest hangcheck: SUCCESS (30.589s)
[316.001173] Starting dynamic subtest: execlists
[317.566026] Dynamic subtest execlists: SUCCESS (1.572s)
[317.576670] Starting dynamic subtest: ring_submission
[319.111017] Dynamic subtest ring_submission: SUCCESS (1.544s)
[319.119236] Starting dynamic subtest: perf
[320.784954] Dynamic subtest perf: SUCCESS (1.673s)
[320.793407] Starting dynamic subtest: slpc
[327.927973] Dynamic subtest slpc: SUCCESS (7.142s)
[327.935989] Starting dynamic subtest: guc
[330.867043] Dynamic subtest guc: SUCCESS (2.938s)
[330.875217] Starting dynamic subtest: guc_multi_lrc
[332.440121] Dynamic subtest guc_multi_lrc: SUCCESS (1.572s)
[332.449144] Starting dynamic subtest: guc_hang
[334.935172] Dynamic subtest guc_hang: SUCCESS (2.494s)
[334.943854] Starting dynamic subtest: late_gt_pm
[336.604010] Dynamic subtest late_gt_pm: SUCCESS (1.667s)
[336.604985] Subtest live: FAIL (235.125s)
[336.635736] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[336.636799] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[336.639126] [025/134] (690s left) kms_pm_rpm (modeset-non-lpsp-stress)
[340.095986] Starting subtest: modeset-non-lpsp-stress
[345.904087] Subtest modeset-non-lpsp-stress: SUCCESS (5.806s)
[346.240328] [FACT kms_pm_rpm (modeset-non-lpsp-stress)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[346.242546] [FACT kms_pm_rpm (modeset-non-lpsp-stress)] new: kernel.kmod_is_loaded.i915: true
[346.245327] [026/134] (680s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[346.328787] Starting subtest: invalid-multi-wait-all-unsubmitted
[346.329634] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[346.525590] [027/134] (680s left) gem_mmap_offset (perf)
[346.601460] Starting subtest: perf
[346.602415] Starting dynamic subtest: lmem0
[346.852260] Dynamic subtest lmem0: SUCCESS (0.250s)
[346.862604] Starting dynamic subtest: smem0
[346.867580] Dynamic subtest smem0: SUCCESS (0.003s)
[346.868619] Subtest perf: SUCCESS (0.255s)
[347.035035] [028/134] (680s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[347.228484] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[347.230276] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[347.412272] [029/134] (679s left) gem_exec_balancer (parallel-balancer)
[347.803875] Starting subtest: parallel-balancer
[349.268288] Subtest parallel-balancer: SUCCESS (1.465s)
[349.445647] [030/134] (678s left) kms_chamelium_hpd (hdmi-hpd)
[349.707502] Subtest hdmi-hpd: SKIP (0.000s)
[349.854184] [031/134] (677s left) gem_exec_whisper (basic-fds-priority)
[349.943769] Starting subtest: basic-fds-priority
[372.882799] Subtest basic-fds-priority: SUCCESS (22.937s)
[373.158787] [032/134] (654s left) kms_lease (cursor-implicit-plane)
[373.345915] Starting subtest: cursor-implicit-plane
[373.348181] Starting dynamic subtest: pipe-A-DP-3
[373.708431] Dynamic subtest pipe-A-DP-3: SUCCESS (0.361s)
[373.720926] Starting dynamic subtest: pipe-B-DP-3
[373.979653] Dynamic subtest pipe-B-DP-3: SUCCESS (0.269s)
[373.992617] Starting dynamic subtest: pipe-C-DP-3
[374.269544] Dynamic subtest pipe-C-DP-3: SUCCESS (0.288s)
[374.282644] Starting dynamic subtest: pipe-D-DP-3
[374.549006] Dynamic subtest pipe-D-DP-3: SUCCESS (0.277s)
[374.549925] Subtest cursor-implicit-plane: SUCCESS (1.203s)
[374.871629] [033/134] (652s left) vgem_basic (bad-handle)
[374.928297] Starting subtest: bad-handle
[374.929199] Subtest bad-handle: SUCCESS (0.000s)
[375.084975] [034/134] (652s left) gem_ctx_persistence (heartbeat-hang)
[375.172675] Starting subtest: heartbeat-hang
[375.174918] Subtest heartbeat-hang: SKIP (0.000s)
[375.376477] [035/134] (652s left) kms_lease (setcrtc-implicit-plane)
[375.563429] Starting subtest: setcrtc-implicit-plane
[375.564303] Starting dynamic subtest: pipe-A-DP-3
[375.986148] Dynamic subtest pipe-A-DP-3: SUCCESS (0.423s)
[376.000132] Starting dynamic subtest: pipe-B-DP-3
[376.308134] Dynamic subtest pipe-B-DP-3: SUCCESS (0.319s)
[376.317785] Starting dynamic subtest: pipe-C-DP-3
[376.611011] Dynamic subtest pipe-C-DP-3: SUCCESS (0.302s)
[376.622311] Starting dynamic subtest: pipe-D-DP-3
[376.919109] Dynamic subtest pipe-D-DP-3: SUCCESS (0.306s)
[376.919987] Subtest setcrtc-implicit-plane: SUCCESS (1.358s)
[377.219231] [036/134] (650s left) gem_exec_schedule (submit-early-slice)
[377.311481] Starting subtest: submit-early-slice
[377.312428] Starting dynamic subtest: rcs0
[377.401531] Dynamic subtest rcs0: SUCCESS (0.089s)
[377.412905] Starting dynamic subtest: bcs0
[377.459736] Dynamic subtest bcs0: SUCCESS (0.057s)
[377.470736] Starting dynamic subtest: vcs0
[377.519641] Dynamic subtest vcs0: SUCCESS (0.059s)
[377.530732] Starting dynamic subtest: vcs1
[377.575271] Dynamic subtest vcs1: SUCCESS (0.055s)
[377.586137] Starting dynamic subtest: vecs0
[377.638071] Dynamic subtest vecs0: SUCCESS (0.062s)
[377.649129] Starting dynamic subtest: vecs1
[377.695242] Dynamic subtest vecs1: SUCCESS (0.056s)
[377.706113] Starting dynamic subtest: ccs0
[377.756429] Dynamic subtest ccs0: SUCCESS (0.060s)
[377.757266] Subtest submit-early-slice: SUCCESS (0.446s)
[378.280433] [037/134] (649s left) gem_reg_read (timestamp-moving)
[378.359210] Starting subtest: timestamp-moving
[379.361378] Subtest timestamp-moving: SUCCESS (1.001s)
[379.499327] [038/134] (648s left) kms_dp_linktrain_fallback (dsc-fallback)
[379.692284] Starting subtest: dsc-fallback
[379.694993] Subtest dsc-fallback: SKIP (0.000s)
[379.844090] [039/134] (648s left) gem_tiled_blits (basic)
[379.924324] Subtest basic: SKIP (0.000s)
[380.011901] [040/134] (648s left) syncobj_timeline (wait-delayed-signal)
[380.099412] Starting subtest: wait-delayed-signal
[380.202697] Subtest wait-delayed-signal: SUCCESS (0.102s)
[380.351219] [041/134] (647s left) gem_lmem_swapping (massive)
[383.008023] Starting subtest: massive
[383.010884] Starting dynamic subtest: lmem0
[396.581599] Dynamic subtest lmem0: SUCCESS (13.574s)
[396.582501] Subtest massive: SUCCESS (13.576s)
[397.860582] [FACT gem_lmem_swapping (massive)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[397.861516] [FACT gem_lmem_swapping (massive)] deleted: kernel.kmod_is_loaded.i915: true
[397.863402] [042/134] (630s left) kms_big_fb (4-tiled-addfb-size-overflow)
[399.591279] Starting subtest: 4-tiled-addfb-size-overflow
[399.593963] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.001s)
[399.801657] [FACT kms_big_fb (4-tiled-addfb-size-overflow)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[399.803957] [FACT kms_big_fb (4-tiled-addfb-size-overflow)] new: kernel.kmod_is_loaded.i915: true
[399.807318] [043/134] (628s left) kms_hdr (static-swap)
[399.998739] Starting subtest: static-swap
[400.558267] Starting dynamic subtest: pipe-A-DP-3
[401.686466] Dynamic subtest pipe-A-DP-3: SUCCESS (1.127s)
[401.692203] Subtest static-swap: SUCCESS (1.689s)
[402.082842] [044/134] (626s left) kms_flip (plain-flip-ts-check)
[402.268081] Starting subtest: plain-flip-ts-check
[402.272654] Starting dynamic subtest: A-DP3
[410.401351] Dynamic subtest A-DP3: SUCCESS (8.128s)
[410.418403] Starting dynamic subtest: B-DP3
[418.458157] Dynamic subtest B-DP3: SUCCESS (8.055s)
[418.468988] Starting dynamic subtest: C-DP3
[426.501060] Dynamic subtest C-DP3: SUCCESS (8.041s)
[426.513171] Starting dynamic subtest: D-DP3
[434.550952] Dynamic subtest D-DP3: SUCCESS (8.048s)
[434.553279] Subtest plain-flip-ts-check: SUCCESS (32.285s)
[434.913886] [045/134] (593s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[435.097993] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[435.098937] Starting dynamic subtest: pipe-A
[435.480475] Dynamic subtest pipe-A: SUCCESS (0.382s)
[435.496511] Starting dynamic subtest: pipe-B
[435.960077] Dynamic subtest pipe-B: SUCCESS (0.478s)
[435.985272] Starting dynamic subtest: pipe-C
[436.440072] Dynamic subtest pipe-C: SUCCESS (0.477s)
[436.459624] Starting dynamic subtest: pipe-D
[436.919420] Dynamic subtest pipe-D: SUCCESS (0.477s)
[436.920615] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (1.822s)
[437.286667] [046/134] (591s left) prime_vgem (busy)
[437.382623] Starting subtest: busy
[437.383505] Starting dynamic subtest: rcs0
[437.444456] Dynamic subtest rcs0: SUCCESS (0.061s)
[437.455654] Starting dynamic subtest: bcs0
[437.493606] Dynamic subtest bcs0: SUCCESS (0.048s)
[437.505095] Starting dynamic subtest: vcs0
[437.538565] Dynamic subtest vcs0: SUCCESS (0.044s)
[437.565186] Starting dynamic subtest: vcs1
[437.583419] Dynamic subtest vcs1: SUCCESS (0.042s)
[437.604066] Starting dynamic subtest: vecs0
[437.628262] Dynamic subtest vecs0: SUCCESS (0.043s)
[437.648777] Starting dynamic subtest: vecs1
[437.672917] Dynamic subtest vecs1: SUCCESS (0.042s)
[437.693550] Starting dynamic subtest: ccs0
[437.717915] Dynamic subtest ccs0: SUCCESS (0.043s)
[437.721444] Subtest busy: SUCCESS (0.336s)
[437.885225] [047/134] (590s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[437.970397] Starting subtest: mixed-tiled-to-y-tiled-ccs
[437.972293] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[438.267916] [048/134] (590s left) gem_ctx_isolation (clean)
[438.357403] Starting subtest: clean
[438.358361] Starting dynamic subtest: rcs0
[438.459213] Dynamic subtest rcs0: SUCCESS (0.101s)
[438.469938] Starting dynamic subtest: bcs0
[438.587134] Dynamic subtest bcs0: SUCCESS (0.127s)
[438.597742] Starting dynamic subtest: vcs0
[438.713422] Dynamic subtest vcs0: SUCCESS (0.125s)
[438.723785] Starting dynamic subtest: vcs1
[438.855635] Dynamic subtest vcs1: SUCCESS (0.141s)
[438.866389] Starting dynamic subtest: vecs0
[438.949406] Dynamic subtest vecs0: SUCCESS (0.093s)
[438.959927] Starting dynamic subtest: vecs1
[439.095384] Dynamic subtest vecs1: SUCCESS (0.145s)
[439.105907] Starting dynamic subtest: ccs0
[439.204471] Dynamic subtest ccs0: SUCCESS (0.108s)
[439.205387] Subtest clean: SUCCESS (0.848s)
[439.516325] [049/134] (589s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-render)
[439.706940] Starting subtest: psr-abgr161616f-draw-render
[439.708626] Subtest psr-abgr161616f-draw-render: SKIP (0.000s)
[439.907911] [050/134] (589s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[440.103663] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[441.392127] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.288s)
[441.656227] [051/134] (587s left) gem_spin_batch (legacy)
[441.746370] Starting subtest: legacy
[441.747262] Starting dynamic subtest: default
[445.010925] Dynamic subtest default: SUCCESS (3.262s)
[445.024769] Starting dynamic subtest: render
[448.248590] Dynamic subtest render: SUCCESS (3.232s)
[448.262490] Starting dynamic subtest: bsd
[451.487903] Dynamic subtest bsd: SUCCESS (3.233s)
[451.501949] Starting dynamic subtest: bsd1
[454.725118] Dynamic subtest bsd1: SUCCESS (3.232s)
[454.736658] Starting dynamic subtest: bsd2
[457.961712] Dynamic subtest bsd2: SUCCESS (3.231s)
[457.975545] Starting dynamic subtest: blt
[461.199874] Dynamic subtest blt: SUCCESS (3.232s)
[461.218512] Starting dynamic subtest: vebox
[464.443694] Dynamic subtest vebox: SUCCESS (3.238s)
[464.445587] Subtest legacy: SUCCESS (22.699s)
[464.739362] [052/134] (564s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[464.930456] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[464.983919] [053/134] (564s left) perf_pmu (init-busy)
[465.056286] Starting subtest: init-busy
[465.057168] Starting dynamic subtest: rcs0
[465.058032] Dynamic subtest rcs0: SUCCESS (0.002s)
[465.067375] Starting dynamic subtest: bcs0
[465.068234] Dynamic subtest bcs0: SUCCESS (0.001s)
[465.077299] Starting dynamic subtest: vcs0
[465.078186] Dynamic subtest vcs0: SUCCESS (0.001s)
[465.087137] Starting dynamic subtest: vcs1
[465.088076] Dynamic subtest vcs1: SUCCESS (0.001s)
[465.097185] Starting dynamic subtest: vecs0
[465.098137] Dynamic subtest vecs0: SUCCESS (0.001s)
[465.107208] Starting dynamic subtest: vecs1
[465.108440] Dynamic subtest vecs1: SUCCESS (0.001s)
[465.117347] Starting dynamic subtest: ccs0
[465.118606] Dynamic subtest ccs0: SUCCESS (0.001s)
[465.119755] Subtest init-busy: SUCCESS (0.013s)
[465.283809] [054/134] (563s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[465.473129] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[465.474861] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[465.656905] [055/134] (563s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[465.847926] Starting subtest: primary-y-tiled-reflect-x-90
[465.849606] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[466.020510] [056/134] (563s left) prime_mmap (test_correct)
[466.096950] Starting subtest: test_correct
[466.097929] Starting dynamic subtest: test_correct-smem
[466.143892] Dynamic subtest test_correct-smem: SUCCESS (0.046s)
[466.154367] Starting dynamic subtest: test_correct-lmem0
[466.336214] Dynamic subtest test_correct-lmem0: SUCCESS (0.191s)
[466.337244] Subtest test_correct: SUCCESS (0.240s)
[466.505120] [057/134] (562s left) kms_cursor_crc (cursor-sliding-max-size)
[467.011112] Starting subtest: cursor-sliding-max-size
[467.013898] Subtest cursor-sliding-max-size: SKIP (0.000s)
[467.253826] [058/134] (562s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[467.443432] Starting subtest: cursorB-vs-flipA-legacy
[467.445166] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[467.612943] [059/134] (561s left) gem_mmap_gtt (basic-wc)
[467.693167] Subtest basic-wc: SKIP (0.000s)
[467.767300] [060/134] (561s left) gem_softpin (noreloc)
[467.853491] Starting subtest: noreloc
[473.386982] Subtest noreloc: SUCCESS (5.533s)
[473.602113] [061/134] (556s left) sw_sync (alloc_timeline)
[473.648757] Starting subtest: alloc_timeline
[473.649565] Subtest alloc_timeline: SUCCESS (0.000s)
[473.805659] [062/134] (555s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[473.996014] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[473.997727] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[474.187283] [063/134] (555s left) kms_pm_backlight (bad-brightness)
[474.374105] Subtest bad-brightness: SKIP (0.000s)
[474.435795] [064/134] (555s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[475.090279] Starting subtest: syncobj-timeline-invalid-wait
[475.099130] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.007s)
[475.506393] [065/134] (554s left) syncobj_timeline (signal-point-0)
[475.580350] Starting subtest: signal-point-0
[475.581426] Subtest signal-point-0: SUCCESS (0.000s)
[475.789815] [066/134] (554s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[475.993964] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[475.995778] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[476.190365] [067/134] (553s left) kms_content_protection (atomic)
[476.438196] Starting subtest: atomic
[476.476119] Starting dynamic subtest: pipe-A-DP-3
[598.265625] Dynamic subtest pipe-A-DP-3: FAIL (121.613s)
[598.267388] Subtest atomic: FAIL (121.749s)
[598.519157] [068/134] (431s left) i915_pm_rps (reset)
[598.608208] Starting subtest: reset
[603.443414] Subtest reset: SUCCESS (4.834s)
[603.620698] [069/134] (426s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[603.811179] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[603.813094] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[604.017084] [070/134] (426s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[604.215181] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[604.216929] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[604.417068] [071/134] (426s left) gem_close (many-handles-one-vma)
[604.499413] Starting subtest: many-handles-one-vma
[604.531449] Subtest many-handles-one-vma: SUCCESS (0.032s)
[604.707927] [072/134] (425s left) kms_psr (fbc-psr-sprite-render)
[604.893246] Subtest fbc-psr-sprite-render: SKIP (0.000s)
[604.945730] [073/134] (425s left) kms_vblank (wait-idle-hang)
[605.131273] Starting subtest: wait-idle-hang
[605.132144] Starting dynamic subtest: pipe-A-DP-3
[631.561678] Dynamic subtest pipe-A-DP-3: SUCCESS (26.429s)
[631.574841] Starting dynamic subtest: pipe-D-DP-3
[656.571989] Dynamic subtest pipe-D-DP-3: SUCCESS (25.005s)
[656.572954] Subtest wait-idle-hang: SUCCESS (51.441s)
[656.899942] [074/134] (373s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[657.094062] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[657.095846] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[657.288254] [075/134] (373s left) gem_userptr_blits (create-destroy-unsync)
[657.379761] Subtest create-destroy-unsync: SKIP (0.000s)
[657.623879] [076/134] (373s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[657.698527] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[657.806060] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[657.987922] [077/134] (372s left) kms_psr (psr2-cursor-blt)
[658.179186] Subtest psr2-cursor-blt: SKIP (0.000s)
[658.231558] [078/134] (372s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[658.421405] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[658.422278] Starting dynamic subtest: pipe-A-valid-mode
[658.499047] Dynamic subtest pipe-A-valid-mode: SKIP (0.075s)
[658.500830] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.077s)
[658.753034] [079/134] (372s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[658.939420] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[658.940285] Starting dynamic subtest: pipe-A-DP-3
[658.948471] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[658.958434] Starting dynamic subtest: pipe-B-DP-3
[658.966596] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[658.976477] Starting dynamic subtest: pipe-C-DP-3
[658.984578] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[658.994599] Starting dynamic subtest: pipe-D-DP-3
[659.004887] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[659.007067] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[659.429915] [080/134] (371s left) kms_cursor_crc (cursor-onscreen-512x170)
[659.908889] Starting subtest: cursor-onscreen-512x170
[659.910560] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[660.195488] [081/134] (371s left) syncobj_wait (invalid-reset-illegal-handle)
[660.278422] Starting subtest: invalid-reset-illegal-handle
[660.279309] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[660.474918] [082/134] (370s left) gem_sync (basic-store-all)
[660.566568] Starting subtest: basic-store-all
[665.184284] Subtest basic-store-all: SUCCESS (4.617s)
[665.418245] [083/134] (365s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[665.613351] Starting subtest: y-tiled-64bpp-rotate-0
[665.615170] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[665.813702] [084/134] (365s left) gem_mmap_gtt (basic)
[665.890164] Subtest basic: SKIP (0.000s)
[665.957435] [085/134] (365s left) kms_psr (psr-dpms)
[666.142218] Subtest psr-dpms: SKIP (0.000s)
[666.207546] [086/134] (365s left) gem_lmem_swapping (verify-ccs)
[668.886764] Starting subtest: verify-ccs
[668.887527] Starting dynamic subtest: lmem0
[714.174942] Dynamic subtest lmem0: SUCCESS (45.287s)
[714.175960] Subtest verify-ccs: SUCCESS (45.288s)
[716.124384] [FACT gem_lmem_swapping (verify-ccs)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[716.125366] [FACT gem_lmem_swapping (verify-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[716.127369] [087/134] (315s left) gem_pread (snoop)
[717.729394] Subtest snoop: SKIP (0.000s)
[717.796318] [FACT gem_pread (snoop)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[717.799036] [FACT gem_pread (snoop)] new: kernel.kmod_is_loaded.i915: true
[717.801710] [088/134] (313s left) kms_rotation_crc (exhaust-fences)
[717.990289] Starting subtest: exhaust-fences
[717.992053] Subtest exhaust-fences: SKIP (0.000s)
[718.158634] [089/134] (313s left) gem_softpin (softpin)
[718.249451] Starting subtest: softpin
[750.552149] Subtest softpin: SUCCESS (32.301s)
[750.731165] [090/134] (281s left) i915_drm_fdinfo (virtual-busy-hang-all)
[750.800781] Starting subtest: virtual-busy-hang-all
[750.802539] Subtest virtual-busy-hang-all: SKIP (0.000s)
[750.957395] [091/134] (280s left) gem_render_copy (y-tiled-ccs-to-linear)
[751.038561] Starting subtest: y-tiled-ccs-to-linear
[751.040592] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[751.341588] [092/134] (280s left) gem_watchdog (default-physical)
[751.436194] Starting subtest: default-physical
[752.489371] Subtest default-physical: SUCCESS (1.052s)
[752.631317] [093/134] (279s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[752.706384] Starting subtest: multi-wait-available-unsubmitted-submitted
[752.707295] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[752.909460] [094/134] (279s left) gem_mmap_gtt (basic-short)
[752.985241] Subtest basic-short: SKIP (0.000s)
[753.056947] [095/134] (279s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[753.247488] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[753.249139] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[753.443408] [096/134] (278s left) gem_flink_basic (bad-open)
[753.518262] Starting subtest: bad-open
[753.519176] Subtest bad-open: SUCCESS (0.000s)
[753.688713] [097/134] (278s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[753.872422] Starting subtest: cursor-vs-flip-toggle
[786.638205] Subtest cursor-vs-flip-toggle: SUCCESS (32.764s)
[786.936048] [098/134] (245s left) gem_flink_basic (double-flink)
[787.009309] Starting subtest: double-flink
[787.010222] Subtest double-flink: SUCCESS (0.000s)
[787.221863] [099/134] (245s left) gen9_exec_parse (batch-zero-length)
[787.304264] Subtest batch-zero-length: SKIP (0.000s)
[787.389124] [100/134] (245s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[787.581285] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[787.582982] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[787.773887] [101/134] (244s left) api_intel_allocator (simple-allocator)
[787.848351] Starting subtest: simple-allocator
[787.849260] Starting dynamic subtest: basic
[787.876713] Dynamic subtest basic: SUCCESS (0.028s)
[787.887433] Starting dynamic subtest: parallel-one
[788.059673] Dynamic subtest parallel-one: SUCCESS (0.182s)
[788.068725] Starting dynamic subtest: print
[788.069564] Dynamic subtest print: SUCCESS (0.000s)
[788.078840] Starting dynamic subtest: reuse
[788.079617] Dynamic subtest reuse: SUCCESS (0.000s)
[788.089169] Starting dynamic subtest: reserve
[788.090089] Dynamic subtest reserve: SUCCESS (0.000s)
[788.098792] Starting dynamic subtest: fork-reopen-allocator
[788.099920] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[788.100940] Subtest simple-allocator: SUCCESS (0.229s)
[788.310896] [102/134] (244s left) gem_exec_balancer (parallel-keep-in-fence)
[788.645928] Starting subtest: parallel-keep-in-fence
[790.880482] Subtest parallel-keep-in-fence: SUCCESS (2.235s)
[791.036896] [103/134] (241s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[791.223460] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[791.224365] Starting dynamic subtest: pipe-A-DP-3
[791.232663] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[791.241992] Starting dynamic subtest: pipe-B-DP-3
[791.250377] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[791.259493] Starting dynamic subtest: pipe-C-DP-3
[791.267738] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[791.276761] Starting dynamic subtest: pipe-D-DP-3
[791.286460] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[791.288454] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.009s)
[791.704088] [104/134] (241s left) gem_ctx_create (basic)
[791.792557] Starting subtest: basic
[791.793597] Subtest basic: SUCCESS (0.000s)
[792.061265] [105/134] (240s left) syncobj_wait (invalid-reset-zero-handles)
[792.135360] Starting subtest: invalid-reset-zero-handles
[792.136269] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[792.275896] [106/134] (240s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[792.464172] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[792.465910] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[792.656627] [107/134] (240s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[792.845070] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[792.846890] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[793.056371] [108/134] (240s left) gem_ctx_shared (detached-shared-gtt)
[793.145350] Starting subtest: detached-shared-gtt
[795.295456] Subtest detached-shared-gtt: SUCCESS (2.151s)
[797.930195] [109/134] (235s left) kms_cdclk (mode-transition)
[798.112438] Starting subtest: mode-transition
[798.113380] Starting dynamic subtest: pipe-A-DP-3
[798.203059] Dynamic subtest pipe-A-DP-3: SKIP (0.090s)
[798.218894] Starting dynamic subtest: pipe-B-DP-3
[798.220712] Dynamic subtest pipe-B-DP-3: SKIP (0.003s)
[798.231548] Starting dynamic subtest: pipe-C-DP-3
[798.233335] Dynamic subtest pipe-C-DP-3: SKIP (0.003s)
[798.242531] Starting dynamic subtest: pipe-D-DP-3
[798.244296] Dynamic subtest pipe-D-DP-3: SKIP (0.003s)
[798.246156] Subtest mode-transition: SKIP (0.107s)
[798.469614] [110/134] (234s left) kms_prop_blob (invalid-set-prop)
[798.546224] Starting subtest: invalid-set-prop
[798.547167] Subtest invalid-set-prop: SUCCESS (0.000s)
[798.732600] [111/134] (234s left) gem_exec_params (rs-invalid)
[798.814800] Starting subtest: rs-invalid
[798.818530] Subtest rs-invalid: SUCCESS (0.002s)
[799.019953] [112/134] (234s left) gem_create (create-valid-nonaligned)
[799.101395] Starting subtest: create-valid-nonaligned
[799.102293] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[799.353812] [113/134] (234s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[799.543187] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[799.544965] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[799.736837] [114/134] (233s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[799.921271] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[799.922197] Starting dynamic subtest: pipe-A-DP-3
[799.930618] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[799.940045] Starting dynamic subtest: pipe-B-DP-3
[799.948241] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[799.957376] Starting dynamic subtest: pipe-C-DP-3
[799.965462] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[799.974491] Starting dynamic subtest: pipe-D-DP-3
[799.984346] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[799.986339] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.007s)
[800.416448] [115/134] (233s left) kms_feature_discovery (dp-mst)
[800.602554] Starting subtest: dp-mst
[800.604213] Subtest dp-mst: SKIP (0.000s)
[800.767943] [116/134] (232s left) kms_frontbuffer_tracking (fbc-abgr161616f-draw-mmap-cpu)
[800.957393] Starting subtest: fbc-abgr161616f-draw-mmap-cpu
[800.959117] Subtest fbc-abgr161616f-draw-mmap-cpu: SKIP (0.000s)
[801.150817] [117/134] (232s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[801.345586] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[801.347292] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[801.546642] [118/134] (232s left) kms_psr (fbc-psr-basic)
[801.733392] Subtest fbc-psr-basic: SKIP (0.000s)
[801.791791] [119/134] (232s left) kms_universal_plane (universal-plane-functional)
[801.978433] Starting subtest: universal-plane-functional
[801.979329] Starting dynamic subtest: pipe-A-DP-3
[804.310786] Dynamic subtest pipe-A-DP-3: SUCCESS (2.330s)
[804.330628] Starting dynamic subtest: pipe-B-DP-3
[806.515011] Dynamic subtest pipe-B-DP-3: SUCCESS (2.199s)
[806.526527] Starting dynamic subtest: pipe-C-DP-3
[808.704402] Dynamic subtest pipe-C-DP-3: SUCCESS (2.188s)
[808.716600] Starting dynamic subtest: pipe-D-DP-3
[810.909206] Dynamic subtest pipe-D-DP-3: SUCCESS (2.202s)
[810.910363] Subtest universal-plane-functional: SUCCESS (8.933s)
[811.243927] [120/134] (222s left) kms_plane (planar-pixel-format-settings)
[811.429945] Starting subtest: planar-pixel-format-settings
[811.552464] Subtest planar-pixel-format-settings: SUCCESS (0.122s)
[811.795067] [121/134] (222s left) kms_flip (2x-flip-vs-panning)
[811.979423] Starting subtest: 2x-flip-vs-panning
[811.994697] Subtest 2x-flip-vs-panning: SKIP (0.014s)
[812.162902] [122/134] (222s left) gem_exec_schedule (implicit-write-read)
[812.251078] Starting subtest: implicit-write-read
[812.251974] Starting dynamic subtest: rcs0
[812.554775] Dynamic subtest rcs0: SUCCESS (0.303s)
[812.564520] Starting dynamic subtest: bcs0
[812.766744] Dynamic subtest bcs0: SUCCESS (0.211s)
[812.776508] Starting dynamic subtest: vcs0
[812.985418] Dynamic subtest vcs0: SUCCESS (0.218s)
[812.995158] Starting dynamic subtest: vcs1
[813.208654] Dynamic subtest vcs1: SUCCESS (0.222s)
[813.218362] Starting dynamic subtest: vecs0
[813.426398] Dynamic subtest vecs0: SUCCESS (0.217s)
[813.436284] Starting dynamic subtest: vecs1
[813.645629] Dynamic subtest vecs1: SUCCESS (0.218s)
[813.655394] Starting dynamic subtest: ccs0
[813.865423] Dynamic subtest ccs0: SUCCESS (0.219s)
[813.866325] Subtest implicit-write-read: SUCCESS (1.615s)
[814.305516] [123/134] (219s left) gem_render_copy (x-tiled)
[814.392470] Starting subtest: x-tiled
[814.393326] Starting dynamic subtest: smem
[814.500445] Dynamic subtest smem: SUCCESS (0.107s)
[814.510413] Starting dynamic subtest: lmem0
[815.920533] Dynamic subtest lmem0: SUCCESS (1.419s)
[815.921597] Subtest x-tiled: SUCCESS (1.528s)
[816.273713] [124/134] (218s left) kms_vblank (wait-forked-busy)
[816.458395] Starting subtest: wait-forked-busy
[816.466307] Starting dynamic subtest: pipe-A-DP-3
[818.956231] Dynamic subtest pipe-A-DP-3: SUCCESS (2.496s)
[818.980218] Starting dynamic subtest: pipe-D-DP-3
[821.395432] Dynamic subtest pipe-D-DP-3: SUCCESS (2.432s)
[821.396401] Subtest wait-forked-busy: SUCCESS (4.938s)
[821.680688] [125/134] (212s left) gem_mmap (big-bo)
[821.758168] Subtest big-bo: SKIP (0.000s)
[821.842127] [126/134] (212s left) gem_reg_read (bad-register)
[821.919617] Starting subtest: bad-register
[821.920569] Subtest bad-register: SUCCESS (0.000s)
[822.112938] [127/134] (212s left) kms_flip (nonexisting-fb)
[822.297454] Starting subtest: nonexisting-fb
[822.302160] Starting dynamic subtest: A-DP3
[822.641211] Dynamic subtest A-DP3: SUCCESS (0.338s)
[822.684280] Starting dynamic subtest: B-DP3
[822.939400] Dynamic subtest B-DP3: SUCCESS (0.293s)
[822.969961] Starting dynamic subtest: C-DP3
[823.219715] Dynamic subtest C-DP3: SUCCESS (0.277s)
[823.245959] Starting dynamic subtest: D-DP3
[823.508488] Dynamic subtest D-DP3: SUCCESS (0.286s)
[823.512846] Subtest nonexisting-fb: SUCCESS (1.215s)
[823.922788] [128/134] (210s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs)
[824.108328] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs
[824.109220] Starting dynamic subtest: pipe-A-DP-3
[824.117530] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[824.127249] Starting dynamic subtest: pipe-B-DP-3
[824.135605] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[824.145373] Starting dynamic subtest: pipe-C-DP-3
[824.153525] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[824.162965] Starting dynamic subtest: pipe-D-DP-3
[824.173150] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[824.175476] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.007s)
[824.599137] [129/134] (210s left) i915_query (query-topology-coherent-slice-mask)
[824.674648] Starting subtest: query-topology-coherent-slice-mask
[824.676554] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[824.879917] [130/134] (209s left) gem_close_race (multigpu-basic-process)
[824.991574] Starting subtest: multigpu-basic-process
[824.993487] Subtest multigpu-basic-process: SKIP (0.000s)
[825.282848] [131/134] (209s left) i915_drm_fdinfo (memory-info-idle)
[825.351938] Starting subtest: memory-info-idle
[825.352890] Starting dynamic subtest: lmem0
[825.434160] Dynamic subtest lmem0: SUCCESS (0.082s)
[825.446546] Starting dynamic subtest: smem0
[825.480366] Dynamic subtest smem0: SUCCESS (0.045s)
[825.481381] Subtest memory-info-idle: SUCCESS (0.129s)
[825.667698] [132/134] (209s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[825.856979] Subtest psr2-cursor-plane-update-sf: SKIP (0.000s)
[825.909556] [133/134] (209s left) api_intel_bb (object-reloc-purge-cache)
[825.983521] Starting subtest: object-reloc-purge-cache
[825.987241] Subtest object-reloc-purge-cache: SKIP (0.003s)
[826.229516] [134/134] (208s left) gem_workarounds (reset)
[826.319249] Starting subtest: reset
[826.600976] Subtest reset: SUCCESS (0.281s)
[826.830116] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: kms_atomic
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_async_flips
results: parsing output: 9/ for test: kms_plane_alpha_blend
results: parsing output: 10/ for test: fbdev
results: parsing output: 11/ for test: kms_color
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: vgem_basic
results: parsing output: 14/ for test: gem_mmap_offset
results: parsing output: 15/ for test: kms_properties
results: parsing output: 16/ for test: dumb_buffer
results: parsing output: 17/ for test: kms_flip_scaled_crc
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: gem_fenced_exec_thrash
results: parsing output: 20/ for test: perf
results: parsing output: 21/ for test: kms_chamelium_frames
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: i915_selftest
results: parsing output: 24/ for test: kms_pm_rpm
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gem_mmap_offset
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: gem_exec_balancer
results: parsing output: 29/ for test: kms_chamelium_hpd
results: parsing output: 30/ for test: gem_exec_whisper
results: parsing output: 31/ for test: kms_lease
results: parsing output: 32/ for test: vgem_basic
results: parsing output: 33/ for test: gem_ctx_persistence
results: parsing output: 34/ for test: kms_lease
results: parsing output: 35/ for test: gem_exec_schedule
results: parsing output: 36/ for test: gem_reg_read
results: parsing output: 37/ for test: kms_dp_linktrain_fallback
results: parsing output: 38/ for test: gem_tiled_blits
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: gem_lmem_swapping
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_hdr
results: parsing output: 43/ for test: kms_flip
results: parsing output: 44/ for test: kms_plane_scaling
results: parsing output: 45/ for test: prime_vgem
results: parsing output: 46/ for test: gem_render_copy
results: parsing output: 47/ for test: gem_ctx_isolation
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: gem_spin_batch
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: prime_mmap
results: parsing output: 56/ for test: kms_cursor_crc
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: gem_mmap_gtt
results: parsing output: 59/ for test: gem_softpin
results: parsing output: 60/ for test: sw_sync
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_pm_backlight
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_content_protection
results: parsing output: 67/ for test: i915_pm_rps
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_close
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_vblank
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_userptr_blits
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: kms_flip_scaled_crc
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_cursor_crc
results: parsing output: 80/ for test: syncobj_wait
results: parsing output: 81/ for test: gem_sync
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: gem_mmap_gtt
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: gem_lmem_swapping
results: parsing output: 86/ for test: gem_pread
results: parsing output: 87/ for test: kms_rotation_crc
results: parsing output: 88/ for test: gem_softpin
results: parsing output: 89/ for test: i915_drm_fdinfo
results: parsing output: 90/ for test: gem_render_copy
results: parsing output: 91/ for test: gem_watchdog
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: gem_mmap_gtt
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_flink_basic
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: gem_flink_basic
results: parsing output: 98/ for test: gen9_exec_parse
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: api_intel_allocator
results: parsing output: 101/ for test: gem_exec_balancer
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: gem_ctx_create
results: parsing output: 104/ for test: syncobj_wait
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_ctx_shared
results: parsing output: 108/ for test: kms_cdclk
results: parsing output: 109/ for test: kms_prop_blob
results: parsing output: 110/ for test: gem_exec_params
results: parsing output: 111/ for test: gem_create
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: kms_feature_discovery
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_universal_plane
results: parsing output: 119/ for test: kms_plane
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: gem_render_copy
results: parsing output: 123/ for test: kms_vblank
results: parsing output: 124/ for test: gem_mmap
results: parsing output: 125/ for test: gem_reg_read
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: i915_query
results: parsing output: 129/ for test: gem_close_race
results: parsing output: 130/ for test: i915_drm_fdinfo
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: api_intel_bb
results: parsing output: 133/ for test: gem_workarounds
Done.