[52.394119] Initializing watchdogs
[52.394179]   /dev/watchdog0
[52.407033] [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]
[52.410906] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[52.412997] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[52.414874] [001/131] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[52.585177] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[52.587084] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[52.669021] [002/131] (960s left) gem_mmap_wc (write-prefaulted)
[52.714138] Subtest write-prefaulted: SKIP (0.000s)
[52.769887] [003/131] (960s left) kms_chamelium_hpd (hdmi-hpd)
[53.137215] Subtest hdmi-hpd: SKIP (0.000s)
[53.882743] [004/131] (959s left) kms_force_connector_basic (force-connector-state)
[53.963174] Starting subtest: force-connector-state
[54.537095] Subtest force-connector-state: SUCCESS (0.583s)
[55.517996] [005/131] (957s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[55.609134] Starting subtest: x-tiled-16bpp-rotate-0
[56.914670] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.322s)
[57.055634] [006/131] (955s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[62.129710] Starting subtest: psr2-cursor-plane-move-continuous-sf
[62.130615] Starting dynamic subtest: pipe-A-eDP-1
[73.142653] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.044s)
[73.153449] Starting dynamic subtest: pipe-B-eDP-1
[84.238620] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.101s)
[84.239553] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (22.148s)
[85.098158] [007/131] (927s left) kms_hdr (brightness-with-hdr)
[85.190607] Starting subtest: brightness-with-hdr
[85.193298] Subtest brightness-with-hdr: SKIP (0.000s)
[85.253173] [008/131] (927s left) gem_fence_thrash (bo-write-verify-x)
[85.280586] Starting subtest: bo-write-verify-x
[85.310036] Subtest bo-write-verify-x: SKIP (0.027s)
[85.362034] [009/131] (927s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[85.463889] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[85.476053] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[85.567582] [010/131] (927s left) kms_pm_backlight (fade-with-suspend)
[85.663823] Starting subtest: fade-with-suspend
[101.695271] Starting dynamic subtest: eDP-1
[104.350737] Dynamic subtest eDP-1: SUCCESS (2.708s)
[104.352076] Subtest fade-with-suspend: SUCCESS (3.993s)
[105.124739] [011/131] (922s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[105.222209] Starting subtest: 2x-flip-vs-modeset-vs-hang
[105.233632] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.011s)
[105.303736] [012/131] (922s left) gem_caching (reads)
[105.369962] Subtest reads: SKIP (0.000s)
[105.447432] [013/131] (922s left) kms_async_flips (crc-atomic)
[105.545408] Starting subtest: crc-atomic
[105.912847] Starting dynamic subtest: pipe-A-eDP-1
[108.779328] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.905s)
[109.147400] Starting dynamic subtest: pipe-B-eDP-1
[112.037671] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.923s)
[112.407044] Starting dynamic subtest: pipe-C-eDP-1
[115.294642] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.910s)
[115.653607] Starting dynamic subtest: pipe-D-eDP-1
[118.466436] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.832s)
[118.467961] Subtest crc-atomic: SUCCESS (13.029s)
[119.605760] [014/131] (908s left) sysfs_timeslice_duration (invalid)
[119.651782] Starting subtest: invalid
[119.653065] Starting dynamic subtest: vecs0
[119.654140] Dynamic subtest vecs0: SUCCESS (0.000s)
[119.660720] Starting dynamic subtest: ccs0
[119.661936] Dynamic subtest ccs0: SUCCESS (0.000s)
[119.667643] Starting dynamic subtest: vcs0
[119.668696] Dynamic subtest vcs0: SUCCESS (0.000s)
[119.674755] Starting dynamic subtest: bcs0
[119.675839] Dynamic subtest bcs0: SUCCESS (0.000s)
[119.682167] Starting dynamic subtest: rcs0
[119.683367] Dynamic subtest rcs0: SUCCESS (0.000s)
[119.689152] Starting dynamic subtest: vcs1
[119.690333] Dynamic subtest vcs1: SUCCESS (0.000s)
[119.691675] Subtest invalid: SUCCESS (0.003s)
[119.758658] [015/131] (907s left) gem_eio (reset-stress)
[119.955372] Starting subtest: reset-stress
[164.129223] Subtest reset-stress: SUCCESS (44.176s)
[165.732665] [FACT gem_eio (reset-stress)] new: kernel.kmod_is_loaded.vgem: true
[165.734676] [016/131] (862s left) gem_softpin (allocator-basic)
[165.796917] Starting subtest: allocator-basic
[165.862368] Subtest allocator-basic: SUCCESS (0.065s)
[165.999704] [017/131] (861s left) gem_userptr_blits (process-exit)
[166.071346] Starting subtest: process-exit
[166.086104] Subtest process-exit: SUCCESS (0.014s)
[166.220261] [018/131] (861s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[166.325116] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[166.327585] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[166.431456] [019/131] (861s left) gem_create (hog-create)
[166.494227] Starting subtest: hog-create
[166.495675] Starting dynamic subtest: smem0
[199.713715] Dynamic subtest smem0: SUCCESS (33.221s)
[199.715362] Subtest hog-create: SUCCESS (33.221s)
[199.894664] [020/131] (827s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[200.005059] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[200.006729] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[200.101297] [021/131] (827s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[200.201744] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[201.268290] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.067s)
[201.430818] [022/131] (826s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[201.523413] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[201.524608] Starting dynamic subtest: pipe-A-eDP-1
[201.534128] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[201.541808] Starting dynamic subtest: pipe-B-eDP-1
[201.552033] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[201.558794] Starting dynamic subtest: pipe-C-eDP-1
[201.570247] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[201.577218] Starting dynamic subtest: pipe-D-eDP-1
[201.593091] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[201.594853] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[203.784808] [023/131] (824s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[203.835346] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[203.945756] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[204.012018] [024/131] (823s left) sw_sync (sync_merge)
[204.044934] Starting subtest: sync_merge
[204.046394] Subtest sync_merge: SUCCESS (0.000s)
[204.087445] [025/131] (823s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[204.215343] Starting subtest: cursorB-vs-flipA-atomic
[204.219091] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[204.343918] [026/131] (823s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[204.427346] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[204.428896] Starting dynamic subtest: pipe-A-eDP-1
[204.605909] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.172s)
[204.614730] Starting dynamic subtest: pipe-B-eDP-1
[204.625912] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[204.632880] Starting dynamic subtest: pipe-C-eDP-1
[204.642018] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[204.648379] Starting dynamic subtest: pipe-D-eDP-1
[204.657475] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[204.658305] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.178s)
[206.787202] [027/131] (821s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[207.001915] Starting subtest: cursor-rapid-movement-256x256
[207.004640] Starting dynamic subtest: pipe-A-eDP-1
[207.555656] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.550s)
[207.579667] Starting dynamic subtest: pipe-D-eDP-1
[208.773583] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.200s)
[208.774838] Subtest cursor-rapid-movement-256x256: SUCCESS (1.771s)
[209.588500] [028/131] (818s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[214.673170] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[214.674809] Starting dynamic subtest: pipe-A-eDP-1
[215.438693] Dynamic subtest pipe-A-eDP-1: SKIP (0.764s)
[215.445941] Starting dynamic subtest: pipe-B-eDP-1
[216.639210] Dynamic subtest pipe-B-eDP-1: SKIP (1.198s)
[216.642426] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.966s)
[217.904403] [029/131] (810s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[217.974120] Subtest writes-after-reads: SKIP (0.000s)
[218.033223] [030/131] (810s left) gem_basic (create-close)
[218.092130] Starting subtest: create-close
[218.094886] Subtest create-close: SUCCESS (0.000s)
[218.199520] [031/131] (810s left) kms_psr (psr2-cursor-plane-onoff)
[218.282339] Starting subtest: psr2-cursor-plane-onoff
[218.283530] Starting dynamic subtest: eDP-1
[219.945600] Dynamic subtest eDP-1: SUCCESS (1.662s)
[219.946667] Subtest psr2-cursor-plane-onoff: SUCCESS (1.663s)
[220.790055] [032/131] (807s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[220.891539] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[220.892810] Starting dynamic subtest: pipe-A-eDP-1
[220.902120] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[220.909298] Starting dynamic subtest: pipe-B-eDP-1
[220.918975] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[220.925843] Starting dynamic subtest: pipe-C-eDP-1
[220.934970] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[220.941360] Starting dynamic subtest: pipe-D-eDP-1
[220.950761] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[220.952519] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[223.095115] [033/131] (805s left) kms_color (ctm-blue-to-red)
[223.191243] Starting subtest: ctm-blue-to-red
[223.192381] Starting dynamic subtest: pipe-A-eDP-1
[223.879784] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.686s)
[223.887736] Starting dynamic subtest: pipe-B-eDP-1
[225.228245] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.349s)
[225.241093] Starting dynamic subtest: pipe-C-eDP-1
[226.601593] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[226.608705] Starting dynamic subtest: pipe-D-eDP-1
[227.886680] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.284s)
[227.887957] Subtest ctm-blue-to-red: SUCCESS (4.694s)
[228.687240] [034/131] (799s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[228.791577] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[234.045403] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.255s)
[234.166939] [035/131] (794s left) gem_lmem_swapping (random-engines)
[234.205619] Subtest random-engines: SKIP (0.000s)
[234.250617] [036/131] (794s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[234.345049] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[234.347864] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[234.451397] [037/131] (793s left) gem_sync (basic-each)
[234.527058] Starting subtest: basic-each
[237.037361] Subtest basic-each: SUCCESS (2.493s)
[237.196380] [038/131] (791s left) kms_dsc (dsc-fractional-bpp)
[237.286129] Starting subtest: dsc-fractional-bpp
[237.293670] Subtest dsc-fractional-bpp: SKIP (0.000s)
[237.351945] [039/131] (791s left) sw_sync (sync_merge_same)
[237.386589] Starting subtest: sync_merge_same
[237.388051] Subtest sync_merge_same: SUCCESS (0.000s)
[237.438915] [040/131] (791s left) kms_addfb_basic (tile-pitch-mismatch)
[237.491906] Starting subtest: tile-pitch-mismatch
[237.493775] Subtest tile-pitch-mismatch: SKIP (0.000s)
[237.637005] [041/131] (790s left) kms_psr (psr-cursor-mmap-cpu)
[237.735834] Starting subtest: psr-cursor-mmap-cpu
[237.737011] Starting dynamic subtest: eDP-1
[239.454384] Dynamic subtest eDP-1: SUCCESS (1.717s)
[239.455530] Subtest psr-cursor-mmap-cpu: SUCCESS (1.718s)
[240.291034] [042/131] (788s left) kms_getfb (getfb-handle-closed)
[240.342253] Starting subtest: getfb-handle-closed
[240.344028] Subtest getfb-handle-closed: SUCCESS (0.000s)
[240.430496] [043/131] (788s left) perf_pmu (module-unload)
[240.515623] Starting subtest: module-unload
[281.128559] Subtest module-unload: SUCCESS (40.614s)
[281.150049] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[281.150851] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[281.153347] [044/131] (747s left) gem_exec_fence (submit)
[282.842386] Starting subtest: submit
[282.845138] Subtest submit: SKIP (0.000s)
[284.730034] [FACT gem_exec_fence (submit)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[284.731815] [FACT gem_exec_fence (submit)] new: kernel.kmod_is_loaded.i915: true
[284.734663] [045/131] (743s left) kms_writeback (writeback-check-output-xrgb2101010)
[284.828891] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[284.866884] [046/131] (743s left) device_reset (unbind-reset-rebind)
[284.909404] Starting subtest: unbind-reset-rebind
[288.020737] Subtest unbind-reset-rebind: SUCCESS (3.107s)
[288.050149] [047/131] (740s left) gem_create (busy-create)
[288.113388] Starting subtest: busy-create
[288.114761] Starting dynamic subtest: smem0
[321.659842] Dynamic subtest smem0: SUCCESS (33.549s)
[321.661395] Subtest busy-create: SUCCESS (33.551s)
[321.839557] [048/131] (706s left) kms_setmode (invalid-clone-exclusive-crtc)
[321.886989] Starting subtest: invalid-clone-exclusive-crtc
[321.890586] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[322.000685] [049/131] (706s left) gem_exec_balancer (persistence)
[322.264400] Starting subtest: persistence
[323.028009] Subtest persistence: SUCCESS (0.762s)
[323.157739] [050/131] (705s left) kms_atomic (plane-primary-legacy)
[323.248553] Starting subtest: plane-primary-legacy
[323.249899] Starting dynamic subtest: pipe-A-eDP-1
[323.725979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.475s)
[323.727207] Subtest plane-primary-legacy: SUCCESS (0.476s)
[324.581137] [051/131] (704s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[324.683005] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[324.685004] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[324.778736] [052/131] (704s left) gem_exec_reloc (basic-write-gtt-noreloc)
[324.823988] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[324.905016] [053/131] (704s left) kms_cursor_crc (cursor-onscreen-64x21)
[325.095768] Starting subtest: cursor-onscreen-64x21
[325.141842] Subtest cursor-onscreen-64x21: SKIP (0.043s)
[325.393276] [054/131] (703s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[325.485759] Starting subtest: addfb25-y-tiled-small-legacy
[325.487917] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[325.565746] [055/131] (703s left) gem_exec_fence (syncobj-timeline-signal)
[326.112269] Starting subtest: syncobj-timeline-signal
[326.119123] Subtest syncobj-timeline-signal: SUCCESS (0.009s)
[327.759037] [056/131] (701s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[327.855397] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[330.370206] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.515s)
[330.491859] [057/131] (698s left) kms_plane (plane-panning-bottom-right-suspend)
[330.590723] Starting subtest: plane-panning-bottom-right-suspend
[330.591904] Starting dynamic subtest: pipe-A
[351.359250] Dynamic subtest pipe-A: SUCCESS (5.150s)
[351.366211] Starting dynamic subtest: pipe-B
[370.071690] Dynamic subtest pipe-B: SUCCESS (3.372s)
[370.073196] Subtest plane-panning-bottom-right-suspend: SUCCESS (8.525s)
[374.880035] [058/131] (689s left) kms_psr (fbc-psr2-sprite-render)
[374.983551] Starting subtest: fbc-psr2-sprite-render
[374.984808] Starting dynamic subtest: eDP-1
[374.986958] Dynamic subtest eDP-1: SKIP (0.000s)
[374.989084] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[375.051583] [059/131] (689s left) api_intel_bb (object-reloc-keep-cache)
[375.106949] Starting subtest: object-reloc-keep-cache
[375.109680] Subtest object-reloc-keep-cache: SKIP (0.003s)
[375.213868] [060/131] (688s left) kms_pipe_crc_basic (hang-read-crc)
[375.307682] Starting subtest: hang-read-crc
[375.308921] Starting dynamic subtest: pipe-A-eDP-1
[377.306592] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.044s)
[377.314623] Starting dynamic subtest: pipe-B-eDP-1
[380.108063] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.847s)
[380.115910] Starting dynamic subtest: pipe-C-eDP-1
[382.700576] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.622s)
[382.711436] Starting dynamic subtest: pipe-D-eDP-1
[385.306863] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.627s)
[385.308058] Subtest hang-read-crc: SUCCESS (10.145s)
[386.137982] [061/131] (677s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[386.237231] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[386.239454] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[386.325437] [062/131] (677s left) kms_pm_dc (dc6-psr)
[387.428221] Starting subtest: dc6-psr
[394.303741] Subtest dc6-psr: FAIL (6.648s)
[394.331733] [063/131] (669s left) syncobj_wait (single-wait-signaled)
[394.382625] Starting subtest: single-wait-signaled
[394.383712] Subtest single-wait-signaled: SUCCESS (0.000s)
[394.487776] [064/131] (669s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[394.591417] Starting subtest: multiplane-rotation-cropping-bottom
[402.039710] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.415s)
[402.101480] [065/131] (661s left) gem_exec_reloc (basic-wc)
[402.158916] Subtest basic-wc: SKIP (0.000s)
[402.229600] [066/131] (661s left) kms_vblank (query-idle)
[402.331455] Starting subtest: query-idle
[402.332539] Starting dynamic subtest: pipe-A-eDP-1
[404.083418] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.751s)
[404.092972] Starting dynamic subtest: pipe-D-eDP-1
[406.514963] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.433s)
[406.516519] Subtest query-idle: SUCCESS (4.188s)
[407.330621] [067/131] (656s left) gem_wait (invalid-buf)
[407.391689] Starting subtest: invalid-buf
[407.393012] Subtest invalid-buf: SUCCESS (0.000s)
[407.596724] [068/131] (656s left) syncobj_timeline (single-wait-all-available-submitted)
[407.652843] Starting subtest: single-wait-all-available-submitted
[407.654503] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[407.749741] [069/131] (656s left) syncobj_wait (invalid-wait-illegal-handle)
[407.803917] Starting subtest: invalid-wait-illegal-handle
[407.805149] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[407.885037] [070/131] (656s left) perf (blocking)
[407.992134] Starting subtest: blocking
[407.993604] Starting dynamic subtest: 0-rcs0
[418.013205] Dynamic subtest 0-rcs0: SUCCESS (10.018s)
[418.021618] Starting dynamic subtest: 1-vecs0
[428.031453] Dynamic subtest 1-vecs0: SUCCESS (10.019s)
[428.032940] Subtest blocking: SUCCESS (20.039s)
[428.074685] [071/131] (636s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[428.132239] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[428.204530] [072/131] (636s left) gem_exec_balancer (bonded-true-hang)
[428.459449] Starting subtest: bonded-true-hang
[428.462639] Subtest bonded-true-hang: SKIP (0.000s)
[428.573086] [073/131] (635s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[428.663598] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[428.664752] Starting dynamic subtest: pipe-A-eDP-1
[428.675446] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[428.683253] Starting dynamic subtest: pipe-B-eDP-1
[428.692758] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[428.701945] Starting dynamic subtest: pipe-C-eDP-1
[428.712126] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[428.719454] Starting dynamic subtest: pipe-D-eDP-1
[428.728826] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[428.730830] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[430.877647] [074/131] (633s left) api_intel_bb (blit-reloc-keep-cache)
[430.928535] Starting subtest: blit-reloc-keep-cache
[430.930670] Subtest blit-reloc-keep-cache: SKIP (0.001s)
[431.045741] [075/131] (633s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[431.130379] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[431.131509] Starting dynamic subtest: pipe-A-default-mode
[431.415739] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[431.417829] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.284s)
[432.198828] [076/131] (632s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[432.292344] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[437.212129] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.920s)
[437.364362] [077/131] (627s left) kms_chamelium_frames (dp-crc-multiple)
[437.748867] Subtest dp-crc-multiple: SKIP (0.000s)
[438.548967] [078/131] (625s left) kms_prop_blob (invalid-set-prop-any)
[438.598798] Starting subtest: invalid-set-prop-any
[438.600059] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[438.695627] [079/131] (625s left) gem_busy (close-race)
[438.763893] Starting subtest: close-race
[464.624789] Subtest close-race: SUCCESS (25.868s)
[464.992568] [080/131] (599s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[465.115405] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[465.117112] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[465.196547] [081/131] (599s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[465.287184] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[465.288388] Starting dynamic subtest: pipe-A
[465.836154] Dynamic subtest pipe-A: SUCCESS (0.548s)
[465.846710] Starting dynamic subtest: pipe-B
[467.420237] Dynamic subtest pipe-B: SUCCESS (1.583s)
[467.430025] Starting dynamic subtest: pipe-C
[468.986705] Dynamic subtest pipe-C: SUCCESS (1.565s)
[468.997202] Starting dynamic subtest: pipe-D
[470.591986] Dynamic subtest pipe-D: SUCCESS (1.605s)
[470.593586] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (5.307s)
[471.778161] [082/131] (592s left) gen9_exec_parse (bb-start-out)
[471.838010] Subtest bb-start-out: SKIP (0.000s)
[471.892734] [083/131] (592s left) kms_invalid_mode (bad-vsync-start)
[471.993557] Starting subtest: bad-vsync-start
[471.994769] Starting dynamic subtest: pipe-A-eDP-1
[472.276092] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[472.284475] Starting dynamic subtest: pipe-B-eDP-1
[472.285406] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[472.293732] Starting dynamic subtest: pipe-C-eDP-1
[472.294737] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[472.302453] Starting dynamic subtest: pipe-D-eDP-1
[472.303651] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[472.304636] Subtest bad-vsync-start: SUCCESS (0.288s)
[473.105081] [084/131] (591s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[473.196145] Starting subtest: psr-2p-primscrn-spr-indfb-move
[473.198488] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[473.301347] [085/131] (591s left) kms_legacy_colorkey (invalid-plane)
[473.387224] Starting subtest: invalid-plane
[473.388638] Starting dynamic subtest: zero-id
[473.389915] Dynamic subtest zero-id: SUCCESS (0.000s)
[473.397928] Starting dynamic subtest: outrange-id
[473.399155] Dynamic subtest outrange-id: SUCCESS (0.000s)
[473.400372] Subtest invalid-plane: SUCCESS (0.001s)
[473.456964] [086/131] (591s left) kms_flip (dpms-off-confusion)
[473.549355] Starting subtest: dpms-off-confusion
[473.550493] Starting dynamic subtest: A-eDP1
[482.313474] Dynamic subtest A-eDP1: SUCCESS (8.759s)
[482.323209] Starting dynamic subtest: B-eDP1
[491.036661] Dynamic subtest B-eDP1: SUCCESS (8.718s)
[491.050584] Starting dynamic subtest: C-eDP1
[499.750921] Dynamic subtest C-eDP1: SUCCESS (8.710s)
[499.759600] Starting dynamic subtest: D-eDP1
[508.473911] Dynamic subtest D-eDP1: SUCCESS (8.721s)
[508.476703] Subtest dpms-off-confusion: SUCCESS (34.917s)
[509.636811] [087/131] (555s left) gem_exec_reloc (basic-write-wc-active)
[509.699053] Subtest basic-write-wc-active: SKIP (0.000s)
[509.783102] [088/131] (555s left) i915_query (query-topology-matches-eu-total)
[509.837908] Starting subtest: query-topology-matches-eu-total
[509.839213] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[509.951934] [089/131] (554s left) gem_exec_whisper (basic-contexts-all)
[510.014614] Starting subtest: basic-contexts-all
[520.224450] Subtest basic-contexts-all: SUCCESS (10.206s)
[520.399135] [090/131] (544s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[520.510318] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[520.511977] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[520.594318] [091/131] (544s left) sw_sync (sync_expired_merge)
[520.633536] Starting subtest: sync_expired_merge
[521.709982] Subtest sync_expired_merge: SUCCESS (1.074s)
[521.762439] [092/131] (543s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[521.859079] Starting subtest: fbcpsr-rgb565-draw-pwrite
[526.757821] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (4.900s)
[526.882336] [093/131] (538s left) gem_render_tiled_blits (basic)
[526.935784] Subtest basic: SKIP (0.000s)
[527.002243] [094/131] (538s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[527.056672] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[527.163953] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.105s)
[527.234594] [095/131] (537s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[527.330296] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[527.332010] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[527.438434] [096/131] (537s left) syncobj_timeline (wait-all-for-submit-complex)
[527.493565] Starting subtest: wait-all-for-submit-complex
[527.712203] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[527.782492] [097/131] (537s left) kms_psr (pr-cursor-plane-move)
[527.877584] Starting subtest: pr-cursor-plane-move
[527.879863] Subtest pr-cursor-plane-move: SKIP (0.000s)
[527.949814] [098/131] (537s left) gem_softpin (safe-alignment)
[528.016472] Starting subtest: safe-alignment
[528.040962] Subtest safe-alignment: SUCCESS (0.025s)
[529.679941] [099/131] (535s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[529.791621] Starting subtest: y-tiled-16bpp-rotate-180
[529.793748] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[529.907998] [100/131] (535s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[529.998472] Starting subtest: psr-rgb101010-draw-pwrite
[535.554718] Subtest psr-rgb101010-draw-pwrite: SUCCESS (5.558s)
[535.661968] [101/131] (529s left) kms_content_protection (lic-type-0)
[535.846263] Starting subtest: lic-type-0
[539.195897] Subtest lic-type-0: SKIP (3.348s)
[540.364518] [102/131] (524s left) core_auth (many-magics)
[540.418787] Starting subtest: many-magics
[541.226367] Subtest many-magics: SUCCESS (0.809s)
[541.395471] [103/131] (523s left) gem_exec_schedule (u-fairslice)
[541.458290] Starting subtest: u-fairslice
[541.459217] Starting dynamic subtest: rcs0
[543.484166] Dynamic subtest rcs0: SUCCESS (2.023s)
[543.493654] Starting dynamic subtest: bcs0
[545.515085] Dynamic subtest bcs0: SUCCESS (2.032s)
[545.523239] Starting dynamic subtest: vcs0
[547.538894] Dynamic subtest vcs0: SUCCESS (2.023s)
[547.555543] Starting dynamic subtest: vcs1
[549.563745] Dynamic subtest vcs1: SUCCESS (2.023s)
[549.573015] Starting dynamic subtest: vecs0
[551.584884] Dynamic subtest vecs0: SUCCESS (2.021s)
[551.593760] Starting dynamic subtest: ccs0
[553.612473] Dynamic subtest ccs0: SUCCESS (2.026s)
[553.613524] Subtest u-fairslice: SUCCESS (12.153s)
[553.910199] [104/131] (511s left) gem_mmap_gtt (fault-concurrent-y)
[553.964353] Subtest fault-concurrent-Y: SKIP (0.000s)
[554.022583] [105/131] (511s left) kms_psr (fbc-pr-suspend)
[554.124614] Starting subtest: fbc-pr-suspend
[554.126912] Subtest fbc-pr-suspend: SKIP (0.000s)
[554.194727] [106/131] (511s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[554.297219] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[554.299564] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[554.409004] [107/131] (511s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[554.519765] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[554.521854] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[554.625004] [108/131] (510s left) syncobj_timeline (multi-wait-all-signaled)
[554.679871] Starting subtest: multi-wait-all-signaled
[554.681114] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[554.788512] [109/131] (510s left) kms_addfb_basic (invalid-set-prop)
[554.851388] Starting subtest: invalid-set-prop
[554.852661] Subtest invalid-set-prop: SUCCESS (0.000s)
[554.983564] [110/131] (510s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[555.077254] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[555.078377] Starting dynamic subtest: pipe-A-eDP-1
[555.103422] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[555.112556] Starting dynamic subtest: pipe-B-eDP-1
[555.137116] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[555.145233] Starting dynamic subtest: pipe-C-eDP-1
[555.168615] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[555.176865] Starting dynamic subtest: pipe-D-eDP-1
[555.198725] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[555.200384] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.008s)
[557.212879] [111/131] (508s left) i915_pm_rpm (reg-read-ioctl)
[558.858243] Starting subtest: reg-read-ioctl
[561.162606] Subtest reg-read-ioctl: SUCCESS (2.307s)
[561.523135] [112/131] (504s left) gem_pread (exhaustion)
[561.579094] Subtest exhaustion: SKIP (0.000s)
[561.641836] [113/131] (504s left) gem_exec_schedule (reorder-wide)
[561.826847] Subtest reorder-wide: SKIP (0.000s)
[562.005948] [114/131] (503s left) syncobj_eventfd (binary-wait-before-signal)
[562.061839] Starting subtest: binary-wait-before-signal
[562.092077] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[562.160541] [115/131] (503s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[562.244563] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[562.245754] Starting dynamic subtest: pipe-A-default-mode
[563.367616] Dynamic subtest pipe-A-default-mode: SKIP (1.121s)
[563.369840] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.122s)
[563.531439] [116/131] (502s left) gem_softpin (evict-active)
[563.604192] Starting subtest: evict-active
[563.609769] Subtest evict-active: SUCCESS (0.006s)
[563.743715] [117/131] (502s left) gem_ctx_persistence (process)
[563.804893] Starting subtest: process
[563.999257] Subtest process: SUCCESS (0.194s)
[564.077662] [118/131] (501s left) i915_hangman (gt-engine-error)
[564.149221] Starting subtest: gt-engine-error
[564.150767] Starting dynamic subtest: rcs0
[565.583575] Dynamic subtest rcs0: SUCCESS (1.431s)
[565.597042] Starting dynamic subtest: bcs0
[567.631765] Dynamic subtest bcs0: SUCCESS (2.047s)
[567.644473] Starting dynamic subtest: vcs0
[569.550286] Dynamic subtest vcs0: SUCCESS (1.917s)
[569.559968] Starting dynamic subtest: vcs1
[571.598144] Dynamic subtest vcs1: SUCCESS (2.046s)
[571.609499] Starting dynamic subtest: vecs0
[573.581961] Dynamic subtest vecs0: SUCCESS (1.982s)
[573.593961] Starting dynamic subtest: ccs0
[575.629696] Dynamic subtest ccs0: SUCCESS (2.046s)
[575.631724] Subtest gt-engine-error: SUCCESS (11.483s)
[575.710019] [119/131] (490s left) perf_pmu (busy-no-semaphores)
[575.746635] Starting subtest: busy-no-semaphores
[575.748141] Starting dynamic subtest: rcs0
[576.333218] Dynamic subtest rcs0: SUCCESS (0.583s)
[576.344366] Starting dynamic subtest: bcs0
[576.888076] Dynamic subtest bcs0: SUCCESS (0.552s)
[576.897787] Starting dynamic subtest: vcs0
[577.441166] Dynamic subtest vcs0: SUCCESS (0.551s)
[577.453250] Starting dynamic subtest: vcs1
[579.121527] Dynamic subtest vcs1: SUCCESS (1.678s)
[579.133242] Starting dynamic subtest: vecs0
[581.172217] Dynamic subtest vecs0: SUCCESS (2.049s)
[581.183517] Starting dynamic subtest: ccs0
[583.155051] Dynamic subtest ccs0: SUCCESS (1.981s)
[583.156680] Subtest busy-no-semaphores: SUCCESS (7.409s)
[583.267513] [120/131] (482s left) gem_ctx_isolation (preservation-reset)
[583.432634] Starting subtest: preservation-reset
[583.434071] Starting dynamic subtest: rcs0
[583.855505] Dynamic subtest rcs0: SUCCESS (0.423s)
[583.864997] Starting dynamic subtest: bcs0
[584.330868] Dynamic subtest bcs0: SUCCESS (0.474s)
[584.341800] Starting dynamic subtest: vcs0
[584.789030] Dynamic subtest vcs0: SUCCESS (0.459s)
[584.799937] Starting dynamic subtest: vcs1
[586.070505] Dynamic subtest vcs1: SUCCESS (1.281s)
[586.082538] Starting dynamic subtest: vecs0
[588.043237] Dynamic subtest vecs0: SUCCESS (1.973s)
[588.054716] Starting dynamic subtest: ccs0
[590.043739] Dynamic subtest ccs0: SUCCESS (2.000s)
[590.045459] Subtest preservation-reset: SUCCESS (6.615s)
[590.217042] [121/131] (475s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[590.330317] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[591.298083] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.967s)
[591.436313] [122/131] (474s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[591.498480] Subtest untiled-to-tiled: SKIP (0.000s)
[591.576572] [123/131] (474s left) gem_exec_reloc (basic-active)
[591.632244] Subtest basic-active: SKIP (0.000s)
[591.718961] [124/131] (474s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[591.781247] Starting subtest: yf-tiled-ccs-to-y-tiled
[591.784083] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[591.955734] [125/131] (474s left) kms_flip (2x-flip-vs-panning-interruptible)
[592.055017] Starting subtest: 2x-flip-vs-panning-interruptible
[592.066941] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s)
[592.140802] [126/131] (473s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[592.255563] Starting subtest: basic-flip-before-cursor-varying-size
[593.211330] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.956s)
[594.052916] [127/131] (472s left) drm_fdinfo (virtual-busy-all)
[594.092688] Starting subtest: virtual-busy-all
[594.094705] Subtest virtual-busy-all: SKIP (0.000s)
[594.151546] [128/131] (472s left) gem_wait (write-wait)
[594.219130] Starting subtest: write-wait
[594.220595] Starting dynamic subtest: all-engines
[595.361200] Dynamic subtest all-engines: SUCCESS (1.142s)
[595.377028] Starting dynamic subtest: rcs0
[596.423800] Dynamic subtest rcs0: SUCCESS (1.062s)
[596.440252] Starting dynamic subtest: bcs0
[597.485882] Dynamic subtest bcs0: SUCCESS (1.060s)
[597.500972] Starting dynamic subtest: vcs0
[598.544048] Dynamic subtest vcs0: SUCCESS (1.057s)
[598.559434] Starting dynamic subtest: vcs1
[599.601873] Dynamic subtest vcs1: SUCCESS (1.057s)
[599.617838] Starting dynamic subtest: vecs0
[600.658135] Dynamic subtest vecs0: SUCCESS (1.054s)
[600.674182] Starting dynamic subtest: ccs0
[601.717989] Dynamic subtest ccs0: SUCCESS (1.058s)
[601.719840] Subtest write-wait: SUCCESS (7.501s)
[601.911916] [129/131] (464s left) kms_cursor_edge_walk (256x256-top-bottom)
[602.005226] Starting subtest: 256x256-top-bottom
[602.006369] Starting dynamic subtest: pipe-A-eDP-1
[605.425772] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.417s)
[605.434894] Starting dynamic subtest: pipe-D-eDP-1
[608.543547] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.115s)
[608.544430] Subtest 256x256-top-bottom: SUCCESS (6.534s)
[609.343969] [130/131] (456s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[609.441609] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[609.442766] Starting dynamic subtest: pipe-A-default-mode
[609.730438] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[609.732603] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.287s)
[610.567089] [131/131] (455s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[610.671498] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[610.673373] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[610.787014] Closing watchdogs
Done.