[56.508851] Initializing watchdogs
[56.508895]   /dev/watchdog0
[56.522102] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.526277] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.528384] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.531543] [001/133] (960s left) kms_flip (wf_vblank-ts-check)
[56.644673] Starting subtest: wf_vblank-ts-check
[56.646606] Starting dynamic subtest: A-eDP1
[65.566679] Dynamic subtest A-eDP1: SUCCESS (8.950s)
[65.576303] Starting dynamic subtest: B-eDP1
[74.496117] Dynamic subtest B-eDP1: SUCCESS (8.937s)
[74.506605] Starting dynamic subtest: C-eDP1
[83.410784] Dynamic subtest C-eDP1: SUCCESS (8.915s)
[83.417686] Starting dynamic subtest: D-eDP1
[92.334623] Dynamic subtest D-eDP1: SUCCESS (8.923s)
[92.339576] Subtest wf_vblank-ts-check: SUCCESS (35.734s)
[93.567797] [002/133] (923s left) kms_cursor_crc (cursor-onscreen-256x256)
[93.801808] Starting subtest: cursor-onscreen-256x256
[93.814667] Starting dynamic subtest: pipe-A-eDP-1
[95.957633] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.138s)
[95.980912] Starting dynamic subtest: pipe-D-eDP-1
[98.747943] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.770s)
[98.750321] Subtest cursor-onscreen-256x256: SUCCESS (4.943s)
[99.550356] [003/133] (917s left) gem_mmap_gtt (big-copy-odd)
[99.621291] Subtest big-copy-odd: SKIP (0.000s)
[99.679888] [004/133] (917s left) i915_pm_rpm (sysfs-read)
[101.198388] Starting subtest: sysfs-read
[102.358781] Subtest sysfs-read: SUCCESS (1.157s)
[102.737815] [005/133] (914s left) kms_universal_plane (disable-primary-vs-flip)
[102.829488] Starting subtest: disable-primary-vs-flip
[102.831944] Starting dynamic subtest: pipe-A-eDP-1
[104.411564] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.582s)
[104.420347] Starting dynamic subtest: pipe-B-eDP-1
[106.643876] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.233s)
[106.651367] Starting dynamic subtest: pipe-C-eDP-1
[108.816828] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.172s)
[108.824747] Starting dynamic subtest: pipe-D-eDP-1
[110.998561] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.179s)
[111.000635] Subtest disable-primary-vs-flip: SUCCESS (8.170s)
[111.796645] [006/133] (905s left) perf (gen12-group-exclusive-stream-ctx-handle)
[111.935706] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[111.964740] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.028s)
[112.010563] [007/133] (905s left) kms_flip_event_leak (basic)
[113.891368] Starting subtest: basic
[113.893301] Starting dynamic subtest: pipe-A-eDP-1
[114.320754] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.429s)
[114.331506] Starting dynamic subtest: pipe-B-eDP-1
[115.424289] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.102s)
[115.432831] Starting dynamic subtest: pipe-C-eDP-1
[116.548219] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.122s)
[116.556956] Starting dynamic subtest: pipe-D-eDP-1
[117.668166] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.120s)
[117.670544] Subtest basic: SUCCESS (3.778s)
[118.539443] [008/133] (898s left) gem_exec_fence (syncobj-wait)
[118.988565] Starting subtest: syncobj-wait
[120.915378] Subtest syncobj-wait: SUCCESS (1.926s)
[121.263604] [FACT gem_exec_fence (syncobj-wait)] new: kernel.kmod_is_loaded.vgem: true
[121.268979] [009/133] (896s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[121.361940] Starting subtest: primary-y-tiled-reflect-x-90
[121.366528] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[121.430981] [010/133] (895s left) kms_psr (fbc-psr-no-drrs)
[121.524848] Starting subtest: fbc-psr-no-drrs
[121.527153] Starting dynamic subtest: eDP-1
[121.531291] Dynamic subtest eDP-1: SKIP (0.000s)
[121.535490] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[121.595679] [011/133] (895s left) kms_chamelium_edid (hdmi-mode-timings)
[122.001036] Subtest hdmi-mode-timings: SKIP (0.000s)
[122.840435] [012/133] (894s left) gem_exec_flush (basic-wb-ro-before-default)
[122.909198] Starting subtest: basic-wb-ro-before-default
[128.364346] Subtest basic-wb-ro-before-default: SUCCESS (5.457s)
[128.549256] [013/133] (888s left) gem_ccs (block-multicopy-inplace)
[128.622138] Starting subtest: block-multicopy-inplace
[128.626920] Subtest block-multicopy-inplace: SKIP (0.000s)
[128.727273] [014/133] (888s left) kms_invalid_mode (clock-too-high)
[128.823541] Starting subtest: clock-too-high
[128.825891] Starting dynamic subtest: pipe-A-eDP-1
[128.830128] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[128.838612] Starting dynamic subtest: pipe-B-eDP-1
[128.842603] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[128.850402] Starting dynamic subtest: pipe-C-eDP-1
[128.854621] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[128.862693] Starting dynamic subtest: pipe-D-eDP-1
[128.866794] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[128.871024] Subtest clock-too-high: SKIP (0.003s)
[128.905844] [015/133] (888s left) perf (invalid-remove-userspace-config)
[128.997821] Starting subtest: invalid-remove-userspace-config
[129.008600] Subtest invalid-remove-userspace-config: SUCCESS (0.011s)
[129.048597] [016/133] (888s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[129.160311] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[129.164611] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[129.255744] [017/133] (888s left) gem_pxp (hw-rejects-pxp-buffer)
[129.381580] Starting subtest: hw-rejects-pxp-buffer
[129.386057] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[129.497261] [018/133] (888s left) vgem_basic (dmabuf-fence)
[129.538034] Starting subtest: dmabuf-fence
[129.539571] Subtest dmabuf-fence: SUCCESS (0.001s)
[129.594883] [019/133] (888s left) core_getclient (basic)
[129.648503] Starting subtest: basic
[129.650422] Subtest basic: SUCCESS (0.000s)
[129.770776] [020/133] (887s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[129.868023] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[129.871598] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[129.969084] [021/133] (887s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[130.066929] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[130.068729] Starting dynamic subtest: pipe-A-default-mode
[132.828412] Dynamic subtest pipe-A-default-mode: SUCCESS (2.760s)
[132.830651] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.761s)
[133.660104] [022/133] (884s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[133.714054] Starting subtest: invalid-signal-one-illegal-handle
[133.716496] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[133.811525] [023/133] (884s left) kms_getfb (getfb-handle-not-fb)
[133.903038] Starting subtest: getfb-handle-not-fb
[133.905369] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[134.015915] [024/133] (883s left) kms_color (invalid-ctm-matrix-sizes)
[134.099462] Starting subtest: invalid-ctm-matrix-sizes
[134.101819] Starting dynamic subtest: pipe-A
[134.104112] Dynamic subtest pipe-A: SUCCESS (0.002s)
[134.113057] Starting dynamic subtest: pipe-B
[134.115244] Dynamic subtest pipe-B: SUCCESS (0.003s)
[134.122429] Starting dynamic subtest: pipe-C
[134.124678] Dynamic subtest pipe-C: SUCCESS (0.003s)
[134.131400] Starting dynamic subtest: pipe-D
[134.133298] Dynamic subtest pipe-D: SUCCESS (0.003s)
[134.135578] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.013s)
[134.179567] [025/133] (883s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[134.268975] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[134.271405] Starting dynamic subtest: pipe-A-eDP-1
[136.345494] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.078s)
[136.353631] Starting dynamic subtest: pipe-B-eDP-1
[138.589480] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.243s)
[138.597682] Starting dynamic subtest: pipe-C-eDP-1
[140.793960] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.204s)
[140.802709] Starting dynamic subtest: pipe-D-eDP-1
[142.855110] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.058s)
[142.857405] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.587s)
[144.017960] [026/133] (873s left) i915_drm_fdinfo (memory-info-resident)
[144.068795] Starting subtest: memory-info-resident
[144.071014] Starting dynamic subtest: smem0
[146.669524] Dynamic subtest smem0: SUCCESS (2.601s)
[146.671935] Subtest memory-info-resident: SUCCESS (2.602s)
[146.742748] [027/133] (871s left) gem_pipe_control_store_loop (reused-buffer)
[146.802967] Starting subtest: reused-buffer
[148.959698] Subtest reused-buffer: SUCCESS (2.154s)
[149.097621] [028/133] (868s left) device_reset (unbind-reset-rebind)
[149.145591] Starting subtest: unbind-reset-rebind
[153.699621] Subtest unbind-reset-rebind: SUCCESS (4.553s)
[153.749109] [029/133] (864s left) kms_content_protection (lic-type-1)
[153.888831] Starting subtest: lic-type-1
[153.893595] Subtest lic-type-1: SKIP (0.000s)
[154.009386] [030/133] (864s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[154.121057] Starting subtest: 4-tiled-8bpp-rotate-90
[154.159127] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.035s)
[154.279955] [031/133] (863s left) kms_rotation_crc (primary-rotation-270)
[154.372205] Starting subtest: primary-rotation-270
[154.376542] Subtest primary-rotation-270: SKIP (0.001s)
[154.448558] [032/133] (863s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[155.992997] Starting subtest: modeset-non-lpsp-stress-no-wait
[156.031487] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.040s)
[156.697361] [033/133] (861s left) kms_async_flips (async-flip-hang)
[156.798766] Starting subtest: async-flip-hang
[157.178202] Starting dynamic subtest: pipe-A-eDP-1
[172.403425] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.223s)
[172.774764] Starting dynamic subtest: pipe-B-eDP-1
[188.401033] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.629s)
[188.774474] Starting dynamic subtest: pipe-C-eDP-1
[204.399307] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.629s)
[204.775082] Starting dynamic subtest: pipe-D-eDP-1
[220.396188] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.624s)
[220.398765] Subtest async-flip-hang: SUCCESS (63.594s)
[221.634412] [034/133] (796s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[221.748440] Starting subtest: cursorB-vs-flipB-legacy
[221.752936] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[221.892646] [035/133] (796s left) kms_lease (master-vs-lease)
[221.980559] Starting subtest: master-vs-lease
[221.982668] Subtest master-vs-lease: SUCCESS (0.002s)
[222.082418] [036/133] (796s left) kms_plane_multiple (tiling-y)
[222.183885] Starting subtest: tiling-y
[222.188202] Subtest tiling-y: SKIP (0.000s)
[222.255666] [037/133] (796s left) i915_hangman (engine-engine-hang)
[222.321095] Starting subtest: engine-engine-hang
[222.323354] Starting dynamic subtest: rcs0
[223.861041] Dynamic subtest rcs0: SUCCESS (1.538s)
[223.872258] Starting dynamic subtest: bcs0
[225.910519] Dynamic subtest bcs0: SUCCESS (2.047s)
[225.921605] Starting dynamic subtest: vcs0
[227.892419] Dynamic subtest vcs0: SUCCESS (1.979s)
[227.903604] Starting dynamic subtest: vcs1
[229.876052] Dynamic subtest vcs1: SUCCESS (1.981s)
[229.887942] Starting dynamic subtest: vecs0
[231.859587] Dynamic subtest vecs0: SUCCESS (1.981s)
[231.871409] Starting dynamic subtest: ccs0
[233.844700] Dynamic subtest ccs0: SUCCESS (1.982s)
[233.847730] Subtest engine-engine-hang: SUCCESS (11.524s)
[233.927855] [038/133] (784s left) gem_media_fill (media-fill)
[233.987635] Subtest media-fill: SKIP (0.000s)
[234.109993] [039/133] (784s left) kms_flip (2x-busy-flip)
[234.211143] Starting subtest: 2x-busy-flip
[234.221721] Subtest 2x-busy-flip: SKIP (0.010s)
[234.306254] [040/133] (784s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[234.400984] Starting subtest: yf-tiled-16bpp-rotate-90
[234.405383] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[234.499375] [041/133] (784s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[234.873088] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[235.651656] [042/133] (783s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[236.197207] Starting subtest: syncobj-timeline-invalid-wait
[236.200051] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[237.990533] [043/133] (780s left) gem_workarounds (basic-read)
[238.057701] Starting subtest: basic-read
[238.068407] Subtest basic-read: SUCCESS (0.012s)
[238.180649] [044/133] (780s left) syncobj_eventfd (invalid-illegal-eventfd)
[238.242053] Starting subtest: invalid-illegal-eventfd
[238.244138] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[238.334500] [045/133] (780s left) kms_plane_alpha_blend (alpha-7efc)
[238.429881] Starting subtest: alpha-7efc
[238.717655] Starting dynamic subtest: pipe-A-eDP-1
[239.316360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.598s)
[239.342165] Starting dynamic subtest: pipe-D-eDP-1
[240.609410] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.273s)
[240.611673] Subtest alpha-7efc: SUCCESS (2.182s)
[241.471911] [046/133] (777s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[241.569283] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[248.312404] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.747s)
[248.457970] [047/133] (770s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[248.547262] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[248.549576] Starting dynamic subtest: pipe-A-eDP-1
[248.605584] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[248.613062] Starting dynamic subtest: pipe-B-eDP-1
[248.669295] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[248.678698] Starting dynamic subtest: pipe-C-eDP-1
[248.734741] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[248.743580] Starting dynamic subtest: pipe-D-eDP-1
[248.797771] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[248.801626] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[250.849209] [048/133] (768s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[250.949071] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[250.951339] Starting dynamic subtest: A-eDP1
[254.571080] Dynamic subtest A-eDP1: SUCCESS (3.618s)
[254.580359] Starting dynamic subtest: B-eDP1
[257.762443] Dynamic subtest B-eDP1: SUCCESS (3.191s)
[257.770936] Starting dynamic subtest: C-eDP1
[260.875154] Dynamic subtest C-eDP1: SUCCESS (3.113s)
[260.884088] Starting dynamic subtest: D-eDP1
[263.979225] Dynamic subtest D-eDP1: SUCCESS (3.103s)
[263.981805] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.033s)
[264.787765] [049/133] (754s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[264.891934] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[264.896181] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[265.017061] [050/133] (754s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[265.112382] Starting subtest: fb-8bpc-vs-panel-8bpc
[265.114652] Starting dynamic subtest: pipe-A-eDP-1
[266.491960] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.380s)
[266.494443] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.381s)
[267.362333] [051/133] (751s left) gem_pxp (fail-invalid-protected-context)
[267.481337] Starting subtest: fail-invalid-protected-context
[267.483649] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[267.584725] [052/133] (751s left) kms_force_connector_basic (prune-stale-modes)
[267.664419] Starting subtest: prune-stale-modes
[268.112338] Subtest prune-stale-modes: SUCCESS (0.451s)
[268.271465] [053/133] (750s left) gem_render_copy_redux (interruptible)
[268.326287] Starting subtest: interruptible
[268.582228] Subtest interruptible: SUCCESS (0.256s)
[268.720353] [054/133] (750s left) perf_pmu (faulting-read)
[268.762445] Starting subtest: faulting-read
[268.764464] Starting dynamic subtest: wb
[268.766505] Dynamic subtest wb: SUCCESS (0.001s)
[268.775404] Starting dynamic subtest: wc
[268.777042] Dynamic subtest wc: SUCCESS (0.000s)
[268.784346] Starting dynamic subtest: uc
[268.785975] Dynamic subtest uc: SUCCESS (0.000s)
[268.787571] Subtest faulting-read: SUCCESS (0.003s)
[268.885500] [055/133] (750s left) gem_ctx_isolation (clean)
[268.944814] Starting subtest: clean
[268.946837] Starting dynamic subtest: rcs0
[268.992756] Dynamic subtest rcs0: SUCCESS (0.047s)
[269.003697] Starting dynamic subtest: bcs0
[269.059845] Dynamic subtest bcs0: SUCCESS (0.066s)
[269.070135] Starting dynamic subtest: vcs0
[269.131561] Dynamic subtest vcs0: SUCCESS (0.071s)
[269.142927] Starting dynamic subtest: vcs1
[270.943743] Dynamic subtest vcs1: SUCCESS (1.810s)
[270.957988] Starting dynamic subtest: vecs0
[272.856992] Dynamic subtest vecs0: SUCCESS (1.911s)
[272.865783] Starting dynamic subtest: ccs0
[274.905980] Dynamic subtest ccs0: SUCCESS (2.048s)
[274.908443] Subtest clean: SUCCESS (5.961s)
[275.134224] [056/133] (744s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[275.259597] Starting subtest: flip-vs-cursor-busy-crc-atomic
[276.048882] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.791s)
[276.881297] [057/133] (742s left) gem_mmap_gtt (isolation)
[276.938450] Subtest isolation: SKIP (0.000s)
[276.994420] [058/133] (742s left) prime_busy (hang)
[277.043110] Starting subtest: hang
[277.045478] Starting dynamic subtest: rcs0
[303.404967] Dynamic subtest rcs0: SUCCESS (26.356s)
[303.412879] Starting dynamic subtest: bcs0
[310.561607] Dynamic subtest bcs0: SUCCESS (7.159s)
[310.576873] Starting dynamic subtest: vcs0
[317.534066] Dynamic subtest vcs0: SUCCESS (6.971s)
[317.545025] Starting dynamic subtest: vcs1
[324.580989] Dynamic subtest vcs1: SUCCESS (7.042s)
[324.591426] Starting dynamic subtest: vecs0
[331.553427] Dynamic subtest vecs0: SUCCESS (6.971s)
[331.563193] Starting dynamic subtest: ccs0
[345.455279] Dynamic subtest ccs0: SUCCESS (13.902s)
[345.457709] Subtest hang: SUCCESS (68.407s)
[345.566255] [059/133] (673s left) kms_cursor_crc (cursor-sliding-512x512)
[345.845376] Starting subtest: cursor-sliding-512x512
[345.849890] Subtest cursor-sliding-512x512: SKIP (0.001s)
[345.997233] [060/133] (673s left) gem_exec_balancer (sequential)
[346.062093] Starting subtest: sequential
[349.106621] Subtest sequential: SUCCESS (3.044s)
[349.434823] [061/133] (670s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[349.546142] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[356.514964] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.971s)
[356.616542] [062/133] (662s left) gem_softpin (full)
[356.685561] Starting subtest: full
[356.693197] Subtest full: SUCCESS (0.007s)
[356.834759] [063/133] (662s left) kms_psr (pr-sprite-plane-move)
[356.924761] Starting subtest: pr-sprite-plane-move
[356.929075] Subtest pr-sprite-plane-move: SKIP (0.000s)
[356.996440] [064/133] (662s left) gem_ctx_shared (q-in-order)
[357.055997] Starting subtest: Q-in-order
[357.058400] Starting dynamic subtest: rcs0
[357.156020] Dynamic subtest rcs0: SUCCESS (0.099s)
[357.172463] Starting dynamic subtest: vcs0
[357.240411] Dynamic subtest vcs0: SUCCESS (0.084s)
[357.256880] Starting dynamic subtest: vcs1
[357.324626] Dynamic subtest vcs1: SUCCESS (0.083s)
[357.341117] Starting dynamic subtest: vecs0
[357.394051] Dynamic subtest vecs0: SUCCESS (0.069s)
[357.409788] Starting dynamic subtest: ccs0
[357.472482] Dynamic subtest ccs0: SUCCESS (0.077s)
[357.474693] Subtest Q-in-order: SUCCESS (0.417s)
[359.016407] [065/133] (660s left) kms_chamelium_audio (hdmi-audio)
[359.401282] Subtest hdmi-audio: SKIP (0.000s)
[360.249570] [066/133] (659s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[360.310535] Starting subtest: single-wait-all-for-submit-available-submitted
[360.312696] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[360.391815] [067/133] (659s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[360.688961] Starting subtest: cursor-rapid-movement-512x170
[360.693468] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[360.880835] [068/133] (658s left) kms_big_fb (linear-64bpp-rotate-270)
[360.979973] Starting subtest: linear-64bpp-rotate-270
[361.001785] Subtest linear-64bpp-rotate-270: SKIP (0.018s)
[361.100258] [069/133] (658s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[361.223055] Starting subtest: flip-vs-cursor-atomic
[368.342566] Subtest flip-vs-cursor-atomic: SUCCESS (7.116s)
[369.196644] [070/133] (650s left) gem_exec_schedule (semaphore-power)
[369.389230] Subtest semaphore-power: SKIP (0.000s)
[369.565427] [071/133] (650s left) gem_ctx_persistence (legacy-engines-hang)
[369.614784] Starting subtest: legacy-engines-hang
[369.617054] Starting dynamic subtest: render
[369.734523] Dynamic subtest render: SUCCESS (0.119s)
[369.747606] Starting dynamic subtest: bsd1
[369.830675] Dynamic subtest bsd1: SUCCESS (0.093s)
[369.843042] Starting dynamic subtest: bsd2
[369.921769] Dynamic subtest bsd2: SUCCESS (0.088s)
[369.933364] Starting dynamic subtest: blt
[370.001724] Dynamic subtest blt: SUCCESS (0.077s)
[370.011922] Starting dynamic subtest: vebox
[370.134730] Dynamic subtest vebox: SUCCESS (0.131s)
[370.137204] Subtest legacy-engines-hang: SUCCESS (0.521s)
[370.225419] [072/133] (649s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[370.323304] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[370.325520] Starting dynamic subtest: pipe-A
[370.388245] Dynamic subtest pipe-A: SUCCESS (0.065s)
[370.397436] Starting dynamic subtest: pipe-B
[371.572656] Dynamic subtest pipe-B: SUCCESS (1.183s)
[371.582838] Starting dynamic subtest: pipe-C
[372.741114] Dynamic subtest pipe-C: SUCCESS (1.167s)
[372.749748] Starting dynamic subtest: pipe-D
[373.865951] Dynamic subtest pipe-D: SUCCESS (1.124s)
[373.868268] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.544s)
[375.075936] [073/133] (644s left) syncobj_timeline (multi-wait-for-submit-signaled)
[375.148601] Starting subtest: multi-wait-for-submit-signaled
[375.150982] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[375.245378] [074/133] (644s left) kms_atomic_interruptible (legacy-dpms)
[375.348825] Starting subtest: legacy-dpms
[375.351119] Starting dynamic subtest: pipe-A-eDP-1
[382.767051] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.417s)
[382.769400] Subtest legacy-dpms: SUCCESS (7.418s)
[383.588548] [075/133] (636s left) syncobj_basic (bad-pad-handle-to-fd)
[383.640724] Starting subtest: bad-pad-handle-to-fd
[383.642857] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[383.735998] [076/133] (636s left) drm_read (short-buffer-nonblock)
[383.896653] Starting subtest: short-buffer-nonblock
[383.898909] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[384.023354] [077/133] (636s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[384.131511] Starting subtest: cursorB-vs-flipB-varying-size
[384.136133] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[384.285385] [078/133] (635s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[384.373411] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[384.377450] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[384.494167] [079/133] (635s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[384.589679] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[385.634758] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.044s)
[385.756445] [080/133] (634s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[385.857985] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[390.231376] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.375s)
[391.384502] [081/133] (628s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[391.475815] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[391.479884] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[391.547308] [082/133] (628s left) kms_atomic (crtc-invalid-params)
[391.630294] Starting subtest: crtc-invalid-params
[391.632586] Starting dynamic subtest: pipe-A-eDP-1
[391.985776] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.353s)
[391.988242] Subtest crtc-invalid-params: SUCCESS (0.355s)
[392.816494] [083/133] (627s left) kms_flip (busy-flip)
[392.916534] Starting subtest: busy-flip
[392.918737] Starting dynamic subtest: A-eDP1
[394.562899] Dynamic subtest A-eDP1: SUCCESS (1.648s)
[394.573827] Starting dynamic subtest: B-eDP1
[396.253644] Dynamic subtest B-eDP1: SUCCESS (1.688s)
[396.265503] Starting dynamic subtest: C-eDP1
[397.946454] Dynamic subtest C-eDP1: SUCCESS (1.690s)
[397.956313] Starting dynamic subtest: D-eDP1
[399.643137] Dynamic subtest D-eDP1: SUCCESS (1.696s)
[399.645507] Subtest busy-flip: SUCCESS (6.733s)
[400.889520] [084/133] (619s left) gem_exec_params (cliprects-invalid)
[400.950966] Starting subtest: cliprects-invalid
[400.953419] Subtest cliprects-invalid: SUCCESS (0.000s)
[401.069231] [085/133] (619s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[401.164577] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[405.945215] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.785s)
[407.100931] [086/133] (613s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[407.162185] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[407.164558] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[407.282357] [087/133] (613s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[407.388383] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[407.392669] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[407.499428] [088/133] (613s left) syncobj_timeline (wait-zero-handles)
[407.548759] Starting subtest: wait-zero-handles
[407.550749] Subtest wait-zero-handles: SUCCESS (0.000s)
[407.641440] [089/133] (613s left) kms_psr (fbc-pr-sprite-plane-onoff)
[407.730954] Starting subtest: fbc-pr-sprite-plane-onoff
[407.735397] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[407.809514] [090/133] (612s left) gen9_exec_parse (batch-without-end)
[407.866244] Subtest batch-without-end: SKIP (0.000s)
[407.940821] [091/133] (612s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[408.051712] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[408.055328] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[408.136471] [092/133] (612s left) syncobj_wait (wait-for-submit-snapshot)
[408.200686] Starting subtest: wait-for-submit-snapshot
[408.282551] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[408.359437] [093/133] (612s left) gem_exec_suspend (basic-s0)
[408.418432] Starting subtest: basic-S0
[408.420236] Starting dynamic subtest: smem
[428.860366] Dynamic subtest smem: SUCCESS (6.542s)
[428.863439] Subtest basic-S0: SUCCESS (6.544s)
[429.050214] [094/133] (591s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[429.163310] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[429.167593] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[429.261981] [095/133] (591s left) perf (invalid-open-flags)
[429.397677] Starting subtest: invalid-open-flags
[429.399894] Subtest invalid-open-flags: SUCCESS (0.000s)
[429.438717] [096/133] (591s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[429.524950] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[429.528901] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[429.631248] [097/133] (591s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[429.726933] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[429.731248] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[429.824120] [098/133] (591s left) kms_addfb_basic (unused-modifier)
[429.882992] Starting subtest: unused-modifier
[429.885128] Subtest unused-modifier: SUCCESS (0.000s)
[430.008537] [099/133] (591s left) perf (enable-disable)
[430.100118] Starting subtest: enable-disable
[430.102054] Starting dynamic subtest: 0-rcs0
[432.337954] Dynamic subtest 0-rcs0: SUCCESS (2.263s)
[432.351587] Starting dynamic subtest: 1-vcs0
[436.809735] Dynamic subtest 1-vcs0: SUCCESS (4.507s)
[436.812039] Subtest enable-disable: SUCCESS (6.772s)
[436.863916] [100/133] (584s left) gem_exec_whisper (basic-queues-forked-all)
[436.925621] Starting subtest: basic-queues-forked-all
[457.100207] Subtest basic-queues-forked-all: SUCCESS (20.219s)
[457.281924] [101/133] (563s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[457.346749] Starting subtest: y-tiled-to-vebox-yf-tiled
[457.351531] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[457.530349] [102/133] (563s left) kms_addfb_basic (bad-pitch-0)
[457.585814] Starting subtest: bad-pitch-0
[457.588061] Subtest bad-pitch-0: SUCCESS (0.000s)
[457.719742] [103/133] (563s left) gem_ctx_engines (execute-one)
[457.782839] Starting subtest: execute-one
[459.973720] Subtest execute-one: SUCCESS (2.191s)
[460.155034] [104/133] (561s left) gem_wait (invalid-buf)
[460.203838] Starting subtest: invalid-buf
[460.206162] Subtest invalid-buf: SUCCESS (0.000s)
[460.401456] [105/133] (560s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[460.499133] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[460.501423] Starting dynamic subtest: pipe-A-eDP-1
[460.594728] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[460.604345] Starting dynamic subtest: pipe-B-eDP-1
[461.719839] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[461.724384] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.225s)
[462.969792] [106/133] (558s left) kms_pm_dc (dc3co-vpb-simulation)
[464.063486] Starting subtest: dc3co-vpb-simulation
[472.704985] Subtest dc3co-vpb-simulation: SKIP (8.637s)
[472.824989] [107/133] (548s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[472.914749] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[476.543471] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.632s)
[477.682155] [108/133] (543s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[477.779925] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[477.784094] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[477.875880] [109/133] (543s left) kms_plane_scaling (invalid-parameters)
[477.962827] Starting subtest: invalid-parameters
[477.965054] Starting dynamic subtest: less-than-1-height-src
[477.977371] Dynamic subtest less-than-1-height-src: SUCCESS (0.014s)
[477.986988] Starting dynamic subtest: less-than-1-width-src
[477.998906] Dynamic subtest less-than-1-width-src: SUCCESS (0.019s)
[478.001040] Subtest invalid-parameters: SUCCESS (0.036s)
[478.120372] [110/133] (543s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[478.221964] Starting subtest: yf-tiled-8bpp-rotate-0
[478.226285] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[478.320748] [111/133] (543s left) kms_psr (psr-cursor-plane-move)
[478.409580] Starting subtest: psr-cursor-plane-move
[478.411816] Starting dynamic subtest: eDP-1
[480.130298] Dynamic subtest eDP-1: SUCCESS (1.720s)
[480.132471] Subtest psr-cursor-plane-move: SUCCESS (1.721s)
[480.967562] [112/133] (540s left) i915_suspend (basic-s3-without-i915)
[481.001000] Starting subtest: basic-s3-without-i915
[482.706026] Subtest basic-s3-without-i915: SKIP (1.701s)
[484.222026] [113/133] (537s left) gem_exec_schedule (noreorder)
[484.421250] Starting subtest: noreorder
[484.423517] Starting dynamic subtest: rcs0
[484.484700] Dynamic subtest rcs0: SUCCESS (0.063s)
[484.495666] Starting dynamic subtest: vcs0
[485.800174] Dynamic subtest vcs0: SUCCESS (1.313s)
[485.811012] Starting dynamic subtest: vcs1
[487.805276] Dynamic subtest vcs1: SUCCESS (2.003s)
[487.816228] Starting dynamic subtest: vecs0
[489.786042] Dynamic subtest vecs0: SUCCESS (1.979s)
[489.797557] Starting dynamic subtest: ccs0
[489.883091] Dynamic subtest ccs0: SUCCESS (0.096s)
[489.885846] Subtest noreorder: SUCCESS (5.464s)
[490.080371] [114/133] (531s left) gem_wait (await)
[490.150520] Starting subtest: await
[490.152896] Starting dynamic subtest: all-engines
[491.276040] Dynamic subtest all-engines: SUCCESS (1.126s)
[491.292666] Starting dynamic subtest: rcs0
[492.337006] Dynamic subtest rcs0: SUCCESS (1.058s)
[492.352288] Starting dynamic subtest: bcs0
[493.392945] Dynamic subtest bcs0: SUCCESS (1.054s)
[493.408517] Starting dynamic subtest: vcs0
[494.447217] Dynamic subtest vcs0: SUCCESS (1.052s)
[494.462792] Starting dynamic subtest: vcs1
[495.500320] Dynamic subtest vcs1: SUCCESS (1.051s)
[495.515628] Starting dynamic subtest: vecs0
[496.553361] Dynamic subtest vecs0: SUCCESS (1.051s)
[496.568570] Starting dynamic subtest: ccs0
[497.610403] Dynamic subtest ccs0: SUCCESS (1.055s)
[497.613112] Subtest await: SUCCESS (7.463s)
[497.806438] [115/133] (523s left) kms_getfb (getfb2-handle-protection)
[497.902825] Starting subtest: getfb2-handle-protection
[497.924081] Subtest getfb2-handle-protection: SUCCESS (0.022s)
[498.055329] [116/133] (523s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[498.154445] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[498.158846] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[498.253289] [117/133] (523s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[498.318882] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[498.400048] [118/133] (523s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[498.457189] Starting subtest: etime-multi-wait-for-submit-submitted
[498.567625] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.109s)
[498.641401] [119/133] (523s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[498.742723] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[498.745072] Starting dynamic subtest: pipe-A-eDP-1
[498.768660] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[498.779936] Starting dynamic subtest: pipe-B-eDP-1
[498.801441] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[498.809248] Starting dynamic subtest: pipe-C-eDP-1
[498.830561] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[498.839025] Starting dynamic subtest: pipe-D-eDP-1
[498.860966] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[498.864999] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[500.981366] [120/133] (520s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[501.080086] Starting subtest: extended-pageflip-modeset-hang-oldfb
[501.081986] Starting dynamic subtest: pipe-A
[508.979631] Dynamic subtest pipe-A: SUCCESS (7.903s)
[508.988004] Starting dynamic subtest: pipe-D
[517.604535] Dynamic subtest pipe-D: SUCCESS (8.623s)
[517.606881] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.530s)
[518.440284] [121/133] (503s left) gem_exec_reloc (basic-write-read-active)
[518.505398] Subtest basic-write-read-active: SKIP (0.000s)
[518.577207] [122/133] (503s left) gem_exec_balancer (bonded-true-hang)
[518.825284] Starting subtest: bonded-true-hang
[518.831393] Subtest bonded-true-hang: SKIP (0.000s)
[518.921677] [123/133] (503s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[519.016441] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[519.018744] Starting dynamic subtest: pipe-A-eDP-1
[524.923819] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.908s)
[524.932648] Starting dynamic subtest: pipe-B-eDP-1
[531.559075] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.631s)
[531.561441] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.541s)
[532.428301] [124/133] (489s left) prime_vgem (fence-write-hang)
[532.509606] Subtest fence-write-hang: SKIP (0.000s)
[532.621398] [125/133] (489s left) kms_fb_coherency (memset-crc)
[532.717343] Starting subtest: memset-crc
[532.719465] Starting dynamic subtest: mmap-offset-wc
[533.095216] Dynamic subtest mmap-offset-wc: SUCCESS (0.376s)
[533.394243] Starting dynamic subtest: mmap-offset-uc
[538.239724] Dynamic subtest mmap-offset-uc: SUCCESS (4.851s)
[538.560073] Subtest memset-crc: SUCCESS (5.840s)
[539.401293] [126/133] (482s left) kms_vblank (wait-forked-busy-hang)
[539.496445] Starting subtest: wait-forked-busy-hang
[539.498658] Starting dynamic subtest: pipe-A-eDP-1
[565.629840] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.130s)
[565.643281] Starting dynamic subtest: pipe-D-eDP-1
[590.587152] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.959s)
[590.589673] Subtest wait-forked-busy-hang: SUCCESS (51.094s)
[591.459755] [127/133] (430s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[591.563665] Starting subtest: multiplane-rotation-cropping-bottom
[608.972917] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.415s)
[609.060296] [128/133] (413s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[609.166623] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[609.170203] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[609.262613] [129/133] (412s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[609.358113] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[609.359956] Starting dynamic subtest: pipe-A-eDP-1
[609.376372] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[609.385206] Starting dynamic subtest: pipe-B-eDP-1
[609.401198] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[609.408804] Starting dynamic subtest: pipe-C-eDP-1
[609.424239] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[609.431877] Starting dynamic subtest: pipe-D-eDP-1
[609.447351] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[609.449193] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[611.595829] [130/133] (410s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[611.694207] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[611.720753] Starting dynamic subtest: pipe-A-plane-0
[612.142499] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[612.152516] Starting dynamic subtest: pipe-A-plane-3
[612.409076] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[613.491848] Starting dynamic subtest: pipe-B-plane-0
[613.928974] Dynamic subtest pipe-B-plane-0: SUCCESS (0.444s)
[613.938487] Starting dynamic subtest: pipe-B-plane-3
[614.184394] Dynamic subtest pipe-B-plane-3: SUCCESS (0.254s)
[614.498116] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.805s)
[616.120370] [131/133] (406s left) gem_exec_reloc (basic-cpu-gtt)
[616.183701] Subtest basic-cpu-gtt: SKIP (0.000s)
[616.252189] [132/133] (406s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[616.339421] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[623.146710] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.812s)
[623.259240] [133/133] (399s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[623.319268] Starting subtest: multi-wait-all-for-submit-submitted
[623.423596] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[623.512301] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: kms_cursor_crc
results: parsing output: 2/ for test: gem_mmap_gtt
results: parsing output: 3/ for test: i915_pm_rpm
results: parsing output: 4/ for test: kms_universal_plane
results: parsing output: 5/ for test: perf
results: parsing output: 6/ for test: kms_flip_event_leak
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_rotation_crc
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_chamelium_edid
results: parsing output: 11/ for test: gem_exec_flush
results: parsing output: 12/ for test: gem_ccs
results: parsing output: 13/ for test: kms_invalid_mode
results: parsing output: 14/ for test: perf
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_pxp
results: parsing output: 17/ for test: vgem_basic
results: parsing output: 18/ for test: core_getclient
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_getfb
results: parsing output: 23/ for test: kms_color
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: i915_drm_fdinfo
results: parsing output: 26/ for test: gem_pipe_control_store_loop
results: parsing output: 27/ for test: device_reset
results: parsing output: 28/ for test: kms_content_protection
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_rotation_crc
results: parsing output: 31/ for test: kms_pm_rpm
results: parsing output: 32/ for test: kms_async_flips
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_lease
results: parsing output: 35/ for test: kms_plane_multiple
results: parsing output: 36/ for test: i915_hangman
results: parsing output: 37/ for test: gem_media_fill
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_chamelium_hpd
results: parsing output: 41/ for test: gem_exec_fence
results: parsing output: 42/ for test: gem_workarounds
results: parsing output: 43/ for test: syncobj_eventfd
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_dither
results: parsing output: 50/ for test: gem_pxp
results: parsing output: 51/ for test: kms_force_connector_basic
results: parsing output: 52/ for test: gem_render_copy_redux
results: parsing output: 53/ for test: perf_pmu
results: parsing output: 54/ for test: gem_ctx_isolation
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: prime_busy
results: parsing output: 58/ for test: kms_cursor_crc
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_softpin
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: gem_ctx_shared
results: parsing output: 64/ for test: kms_chamelium_audio
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_cursor_crc
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_cursor_legacy
results: parsing output: 69/ for test: gem_exec_schedule
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_plane_scaling
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_atomic_interruptible
results: parsing output: 74/ for test: syncobj_basic
results: parsing output: 75/ for test: drm_read
results: parsing output: 76/ for test: kms_cursor_legacy
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_psr2_sf
results: parsing output: 81/ for test: kms_atomic
results: parsing output: 82/ for test: kms_flip
results: parsing output: 83/ for test: gem_exec_params
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: gem_exec_suspend
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: perf
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_addfb_basic
results: parsing output: 98/ for test: perf
results: parsing output: 99/ for test: gem_exec_whisper
results: parsing output: 100/ for test: gem_render_copy
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: gem_ctx_engines
results: parsing output: 103/ for test: gem_wait
results: parsing output: 104/ for test: kms_psr2_sf
results: parsing output: 105/ for test: kms_pm_dc
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_plane_scaling
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: i915_suspend
results: parsing output: 112/ for test: gem_exec_schedule
results: parsing output: 113/ for test: gem_wait
results: parsing output: 114/ for test: kms_getfb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_exec_reloc
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: kms_busy
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: gem_exec_balancer
results: parsing output: 122/ for test: kms_psr2_sf
results: parsing output: 123/ for test: prime_vgem
results: parsing output: 124/ for test: kms_fb_coherency
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: kms_rotation_crc
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: kms_plane
results: parsing output: 130/ for test: gem_exec_reloc
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: syncobj_wait
Done.