[102.720158] Initializing watchdogs
[102.720206]   /dev/watchdog0
[102.732844] [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]
[102.736695] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[102.739213] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[102.742386] [001/130] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[102.921403] Starting subtest: fbcpsr-rgb101010-draw-blt
[107.583688] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (4.665s)
[107.700119] [002/130] (955s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[107.826501] Starting subtest: flip-vs-cursor-busy-crc-legacy
[108.618785] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.793s)
[109.467099] [003/130] (953s left) perf (enable-disable)
[109.577331] Starting subtest: enable-disable
[109.579200] Starting dynamic subtest: 0-rcs0
[111.862310] Dynamic subtest 0-rcs0: SUCCESS (2.284s)
[111.868801] Starting dynamic subtest: 1-vcs0
[116.375639] Dynamic subtest 1-vcs0: SUCCESS (4.513s)
[116.378037] Subtest enable-disable: SUCCESS (6.797s)
[116.416480] [004/130] (946s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[116.520343] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[119.482341] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.966s)
[119.626282] [005/130] (943s left) prime_busy (hang)
[119.690096] Starting subtest: hang
[119.692106] Starting dynamic subtest: rcs0
[145.473686] Dynamic subtest rcs0: SUCCESS (25.781s)
[145.479903] Starting dynamic subtest: bcs0
[152.565177] Dynamic subtest bcs0: SUCCESS (7.091s)
[152.571774] Starting dynamic subtest: vcs0
[159.606557] Dynamic subtest vcs0: SUCCESS (7.041s)
[159.615292] Starting dynamic subtest: vcs1
[166.584203] Dynamic subtest vcs1: SUCCESS (6.975s)
[166.590302] Starting dynamic subtest: vecs0
[173.553254] Dynamic subtest vecs0: SUCCESS (6.968s)
[173.559380] Starting dynamic subtest: ccs0
[187.457665] Dynamic subtest ccs0: SUCCESS (13.904s)
[187.459816] Subtest hang: SUCCESS (67.765s)
[187.578772] [006/130] (875s left) syncobj_wait (wait-for-submit-snapshot)
[187.632836] Starting subtest: wait-for-submit-snapshot
[187.712788] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[187.785269] [007/130] (875s left) gem_exec_create (basic)
[187.850136] Starting subtest: basic
[187.852518] Starting dynamic subtest: smem
[191.913936] Dynamic subtest smem: SUCCESS (4.062s)
[191.916313] Subtest basic: SUCCESS (4.062s)
[192.085278] [008/130] (871s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[192.185119] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[192.187570] Starting dynamic subtest: pipe-A
[192.269299] Dynamic subtest pipe-A: SUCCESS (0.084s)
[192.277890] Starting dynamic subtest: pipe-B
[193.407764] Dynamic subtest pipe-B: SUCCESS (1.138s)
[193.417264] Starting dynamic subtest: pipe-C
[194.507949] Dynamic subtest pipe-C: SUCCESS (1.100s)
[194.514682] Starting dynamic subtest: pipe-D
[195.636844] Dynamic subtest pipe-D: SUCCESS (1.129s)
[195.639241] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.452s)
[196.775245] [009/130] (866s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[196.873591] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[196.886863] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[196.990889] [010/130] (866s left) kms_pm_rpm (dpms-lpsp)
[199.145803] Starting subtest: dpms-lpsp
[199.436713] Subtest dpms-lpsp: SUCCESS (0.292s)
[199.549325] [011/130] (864s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[199.649058] Starting subtest: psr-rgb565-draw-mmap-gtt
[199.653541] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[199.756534] [012/130] (863s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[199.867051] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[201.122519] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.256s)
[201.302148] [013/130] (862s left) kms_psr (fbc-psr2-dpms)
[201.407874] Starting subtest: fbc-psr2-dpms
[201.410309] Starting dynamic subtest: eDP-1
[201.414867] Dynamic subtest eDP-1: SKIP (0.000s)
[201.419377] Subtest fbc-psr2-dpms: SKIP (0.000s)
[201.471351] [014/130] (862s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[201.573187] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[201.577661] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[201.661824] [015/130] (862s left) kms_color (ctm-blue-to-red)
[201.749976] Starting subtest: ctm-blue-to-red
[201.752475] Starting dynamic subtest: pipe-A-eDP-1
[202.437459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[202.445075] Starting dynamic subtest: pipe-B-eDP-1
[203.755765] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.318s)
[203.764270] Starting dynamic subtest: pipe-C-eDP-1
[205.043769] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.287s)
[205.052599] Starting dynamic subtest: pipe-D-eDP-1
[206.369844] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[206.371862] Subtest ctm-blue-to-red: SUCCESS (4.616s)
[207.198901] [016/130] (856s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[207.320749] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[237.951558] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (30.630s)
[238.819146] [017/130] (824s left) gem_exec_schedule (preemptive-hang)
[238.998845] Starting subtest: preemptive-hang
[239.001819] Starting dynamic subtest: rcs0
[248.015094] Dynamic subtest rcs0: SUCCESS (9.016s)
[248.023968] Starting dynamic subtest: bcs0
[257.040556] Dynamic subtest bcs0: SUCCESS (9.026s)
[257.053318] Starting dynamic subtest: vcs0
[266.067168] Dynamic subtest vcs0: SUCCESS (9.025s)
[266.076329] Starting dynamic subtest: vcs1
[275.023615] Dynamic subtest vcs1: SUCCESS (8.956s)
[275.032967] Starting dynamic subtest: vecs0
[284.045360] Dynamic subtest vecs0: SUCCESS (9.021s)
[284.054887] Starting dynamic subtest: ccs0
[293.071601] Dynamic subtest ccs0: SUCCESS (9.026s)
[293.074045] Subtest preemptive-hang: SUCCESS (54.072s)
[293.330208] [018/130] (770s left) gem_workarounds (suspend-resume)
[293.395105] Starting subtest: suspend-resume
[309.111910] Subtest suspend-resume: SUCCESS (1.244s)
[309.247983] [019/130] (769s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[309.347237] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[309.351294] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[309.461413] [020/130] (768s left) tools_test (tools_test)
[309.512283] Starting subtest: tools_test
[309.555109] Subtest tools_test: SUCCESS (0.060s)
[309.607492] [021/130] (768s left) gem_ctx_bad_destroy (invalid-pad)
[309.662596] Starting subtest: invalid-pad
[309.664829] Subtest invalid-pad: SUCCESS (0.000s)
[309.727387] [022/130] (768s left) gem_wait (invalid-buf)
[309.783267] Starting subtest: invalid-buf
[309.785999] Subtest invalid-buf: SUCCESS (0.000s)
[309.987862] [023/130] (768s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[310.108553] Starting subtest: cursorA-vs-flipB-atomic
[310.112880] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[310.234997] [024/130] (768s left) gem_exec_params (no-bsd)
[310.301115] Starting subtest: no-bsd
[310.303498] Subtest no-bsd: SUCCESS (0.003s)
[311.450388] [025/130] (767s left) kms_big_fb (y-tiled-addfb)
[311.558498] Starting subtest: y-tiled-addfb
[311.562715] Subtest y-tiled-addfb: SKIP (0.000s)
[311.659264] [026/130] (766s left) kms_psr (psr-sprite-mmap-cpu)
[311.757423] Starting subtest: psr-sprite-mmap-cpu
[311.759722] Starting dynamic subtest: eDP-1
[313.477703] Dynamic subtest eDP-1: SUCCESS (1.714s)
[313.480132] Subtest psr-sprite-mmap-cpu: SUCCESS (1.715s)
[314.319981] [027/130] (764s left) syncobj_timeline (invalid-signal-bad-pad)
[314.387314] Starting subtest: invalid-signal-bad-pad
[314.389672] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[314.498545] [028/130] (764s left) i915_pm_rps (thresholds-park)
[314.565722] Starting subtest: thresholds-park
[314.570391] Subtest thresholds-park: SKIP (0.001s)
[314.638860] [029/130] (763s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[314.743186] Starting subtest: plane-upscale-factor-0-25-with-rotation
[314.745595] Starting dynamic subtest: pipe-A
[314.970488] Dynamic subtest pipe-A: SUCCESS (0.227s)
[314.980714] Starting dynamic subtest: pipe-B
[316.305452] Dynamic subtest pipe-B: SUCCESS (1.333s)
[316.314872] Starting dynamic subtest: pipe-C
[317.632386] Dynamic subtest pipe-C: SUCCESS (1.325s)
[317.642698] Starting dynamic subtest: pipe-D
[318.959525] Dynamic subtest pipe-D: SUCCESS (1.325s)
[318.961907] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.211s)
[320.134080] [030/130] (758s left) gem_lmem_swapping (heavy-multi)
[320.176803] Subtest heavy-multi: SKIP (0.000s)
[320.207702] [031/130] (758s left) gem_softpin (invalid)
[320.270117] Starting subtest: invalid
[320.279334] Subtest invalid: SUCCESS (0.008s)
[320.395006] [032/130] (758s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[320.497630] Starting subtest: fbcpsr-suspend
[358.396582] Subtest fbcpsr-suspend: SUCCESS (7.728s)
[358.545575] [033/130] (750s left) kms_scaling_modes (scaling-mode-center)
[358.651028] Starting subtest: scaling-mode-center
[358.653642] Starting dynamic subtest: pipe-A-eDP-1
[358.769503] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.118s)
[358.778448] Starting dynamic subtest: pipe-B-eDP-1
[359.933579] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.161s)
[359.943046] Starting dynamic subtest: pipe-C-eDP-1
[361.049167] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s)
[361.057009] Starting dynamic subtest: pipe-D-eDP-1
[362.278539] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[362.281012] Subtest scaling-mode-center: SUCCESS (3.624s)
[363.427263] [034/130] (745s left) perf_pmu (invalid-open)
[363.467249] Starting subtest: invalid-open
[363.470025] Subtest invalid-open: SUCCESS (0.000s)
[363.580011] [035/130] (745s left) kms_async_flips (async-flip-suspend-resume)
[363.684007] Starting subtest: async-flip-suspend-resume
[364.044585] Starting dynamic subtest: pipe-A-eDP-1
[382.085255] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.885s)
[382.456237] Starting dynamic subtest: pipe-B-eDP-1
[385.214848] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.764s)
[385.578544] Starting dynamic subtest: pipe-C-eDP-1
[388.342685] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.770s)
[388.699323] Starting dynamic subtest: pipe-D-eDP-1
[391.468341] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.772s)
[391.470940] Subtest async-flip-suspend-resume: SUCCESS (12.621s)
[392.649510] [036/130] (731s left) kms_vrr (negative-basic)
[392.753692] Starting subtest: negative-basic
[392.760321] Starting dynamic subtest: pipe-A-eDP-1
[398.713083] Dynamic subtest pipe-A-eDP-1: FAIL (5.450s)
[398.715056] Subtest negative-basic: FAIL (5.738s)
[399.289352] [037/130] (724s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[399.684378] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[400.483245] [038/130] (723s left) sw_sync (sync_random_merge)
[400.526414] Starting subtest: sync_random_merge
[400.565700] Subtest sync_random_merge: SUCCESS (0.042s)
[400.614402] [039/130] (723s left) fbdev (read)
[400.653791] Starting subtest: read
[401.185993] Subtest read: SUCCESS (0.530s)
[401.240349] [040/130] (723s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[401.303374] Starting subtest: y-tiled-to-vebox-y-tiled
[401.308010] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[401.478957] [041/130] (722s left) kms_cursor_crc (cursor-random-512x170)
[401.754866] Starting subtest: cursor-random-512x170
[401.759362] Subtest cursor-random-512x170: SKIP (0.001s)
[401.901923] [042/130] (722s left) i915_query (query-regions-unallocated)
[401.954132] Starting subtest: query-regions-unallocated
[401.956332] Subtest query-regions-unallocated: SUCCESS (0.000s)
[402.049161] [043/130] (722s left) syncobj_timeline (invalid-wait-illegal-handle)
[402.109995] Starting subtest: invalid-wait-illegal-handle
[402.112324] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[402.215170] [044/130] (722s left) gem_exec_whisper (basic-contexts-forked)
[402.272829] Starting subtest: basic-contexts-forked
[425.133346] Subtest basic-contexts-forked: SUCCESS (22.850s)
[425.312773] [045/130] (699s left) gem_spin_batch (legacy)
[425.384102] Starting subtest: legacy
[425.386541] Starting dynamic subtest: default
[428.626876] Dynamic subtest default: SUCCESS (3.236s)
[428.636434] Starting dynamic subtest: render
[431.856827] Dynamic subtest render: SUCCESS (3.227s)
[431.867519] Starting dynamic subtest: bsd
[435.089387] Dynamic subtest bsd: SUCCESS (3.229s)
[435.100128] Starting dynamic subtest: bsd1
[438.318827] Dynamic subtest bsd1: SUCCESS (3.227s)
[438.329137] Starting dynamic subtest: bsd2
[441.553795] Dynamic subtest bsd2: SUCCESS (3.232s)
[441.564101] Starting dynamic subtest: blt
[444.784855] Dynamic subtest blt: SUCCESS (3.228s)
[444.795916] Starting dynamic subtest: vebox
[448.019138] Dynamic subtest vebox: SUCCESS (3.231s)
[448.021264] Subtest legacy: SUCCESS (22.624s)
[448.791955] [046/130] (675s left) gem_madvise (dontneed-before-mmap)
[448.826675] Starting subtest: dontneed-before-mmap
[448.856740] Subtest dontneed-before-mmap: SUCCESS (0.032s)
[448.951569] [047/130] (675s left) kms_feature_discovery (display)
[449.053593] Starting subtest: display
[449.055989] Subtest display: SUCCESS (0.000s)
[449.120725] [048/130] (675s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[449.220079] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[454.422344] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.203s)
[454.548293] [049/130] (670s left) syncobj_wait (reset-unsignaled)
[454.604649] Starting subtest: reset-unsignaled
[454.606870] Subtest reset-unsignaled: SUCCESS (0.000s)
[454.700171] [050/130] (669s left) gem_exec_schedule (u-independent)
[454.763787] Starting subtest: u-independent
[454.765968] Starting dynamic subtest: rcs0
[456.702295] Dynamic subtest rcs0: SUCCESS (1.945s)
[456.712697] Starting dynamic subtest: vcs0
[458.769345] Dynamic subtest vcs0: SUCCESS (2.065s)
[458.781279] Starting dynamic subtest: vcs1
[460.770674] Dynamic subtest vcs1: SUCCESS (2.000s)
[460.782259] Starting dynamic subtest: vecs0
[462.747087] Dynamic subtest vecs0: SUCCESS (1.973s)
[462.759714] Starting dynamic subtest: ccs0
[464.699868] Dynamic subtest ccs0: SUCCESS (1.951s)
[464.702439] Subtest u-independent: SUCCESS (9.941s)
[465.019804] [051/130] (659s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[465.075622] Starting subtest: multi-wait-all-for-submit-signaled
[465.077922] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[465.170196] [052/130] (659s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[465.274914] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[465.279228] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[465.373654] [053/130] (659s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[465.491188] Starting subtest: flip-vs-cursor-legacy
[472.358809] Subtest flip-vs-cursor-legacy: SUCCESS (6.863s)
[473.204593] [054/130] (651s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[473.314125] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[473.318256] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[473.412867] [055/130] (651s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[473.510299] Starting subtest: stress-xrgb8888-ytiled
[473.514761] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[473.574903] [056/130] (651s left) api_intel_bb (render)
[473.635869] Starting subtest: render
[473.638375] Starting dynamic subtest: render-none-512
[473.740131] Dynamic subtest render-none-512: SUCCESS (0.103s)
[473.750991] Starting dynamic subtest: render-none-1024
[474.123102] Dynamic subtest render-none-1024: SUCCESS (0.381s)
[474.134069] Starting dynamic subtest: render-x-512
[474.244410] Dynamic subtest render-x-512: SUCCESS (0.120s)
[474.255155] Starting dynamic subtest: render-x-1024
[474.680491] Dynamic subtest render-x-1024: SUCCESS (0.435s)
[474.691720] Starting dynamic subtest: render-y-512
[474.765287] Dynamic subtest render-y-512: SUCCESS (0.085s)
[474.776549] Starting dynamic subtest: render-y-1024
[475.108678] Dynamic subtest render-y-1024: SUCCESS (0.341s)
[475.111533] Subtest render: SUCCESS (1.472s)
[475.221864] [057/130] (649s left) kms_psr (pr-sprite-plane-onoff)
[475.323669] Starting subtest: pr-sprite-plane-onoff
[475.327768] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[475.388395] [058/130] (649s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[475.491944] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[475.494377] Starting dynamic subtest: pipe-A-default-mode
[475.780347] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[475.785043] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.284s)
[476.623860] [059/130] (648s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[476.724223] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[476.728698] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[476.842798] [060/130] (648s left) perf_pmu (event-wait)
[476.884675] Starting subtest: event-wait
[476.886956] Starting dynamic subtest: rcs0
[476.891433] Dynamic subtest rcs0: SKIP (0.000s)
[476.895789] Subtest event-wait: SKIP (0.000s)
[476.993941] [061/130] (648s left) gem_flink_race (flink_name)
[477.030950] Starting subtest: flink_name
[482.467632] Subtest flink_name: SUCCESS (5.437s)
[482.545625] [062/130] (642s left) gem_set_tiling_vs_pwrite
[482.668107] [063/130] (642s left) kms_prop_blob (invalid-set-prop-any)
[482.722561] Starting subtest: invalid-set-prop-any
[482.725411] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[482.830523] [064/130] (642s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[482.934179] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[482.938212] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[483.029644] [065/130] (642s left) gem_create (create-ext-placement-each)
[483.204664] Starting subtest: create-ext-placement-each
[483.207233] Subtest create-ext-placement-each: SUCCESS (0.000s)
[483.310678] [066/130] (641s left) drm_fdinfo (busy)
[483.350892] Starting subtest: busy
[483.352863] Starting dynamic subtest: rcs0
[483.365675] Dynamic subtest rcs0: SKIP (0.014s)
[483.374230] Starting dynamic subtest: bcs0
[483.379052] Dynamic subtest bcs0: SKIP (0.012s)
[483.390025] Starting dynamic subtest: vcs0
[483.394692] Dynamic subtest vcs0: SKIP (0.000s)
[483.402827] Starting dynamic subtest: vcs1
[483.407485] Dynamic subtest vcs1: SKIP (0.000s)
[483.415874] Starting dynamic subtest: vecs0
[483.420373] Dynamic subtest vecs0: SKIP (0.000s)
[483.429230] Starting dynamic subtest: ccs0
[483.434013] Dynamic subtest ccs0: SKIP (0.000s)
[483.438692] Subtest busy: SKIP (0.030s)
[483.469987] [067/130] (641s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[483.572653] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[483.574907] Starting dynamic subtest: pipe-A-eDP-1
[483.583685] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[483.592555] Starting dynamic subtest: pipe-B-eDP-1
[483.601336] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[483.609341] Starting dynamic subtest: pipe-C-eDP-1
[483.617535] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[483.625452] Starting dynamic subtest: pipe-D-eDP-1
[483.633669] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[483.637667] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[485.870939] [068/130] (639s left) perf (invalid-oa-exponent)
[485.949669] Starting subtest: invalid-oa-exponent
[485.966215] Subtest invalid-oa-exponent: SUCCESS (0.016s)
[485.999021] [069/130] (639s left) gem_basic (create-fd-close)
[486.047374] Starting subtest: create-fd-close
[486.051712] Subtest create-fd-close: SUCCESS (0.000s)
[486.141597] [070/130] (639s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[486.264423] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[486.268693] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[486.358723] [071/130] (639s left) kms_lease (multimaster-lease)
[486.458560] Starting subtest: multimaster-lease
[486.463646] Subtest multimaster-lease: SUCCESS (0.004s)
[486.562947] [072/130] (638s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[486.662683] Starting subtest: extended-pageflip-modeset-hang-oldfb
[486.664953] Starting dynamic subtest: pipe-A
[494.577178] Dynamic subtest pipe-A: SUCCESS (7.914s)
[494.586487] Starting dynamic subtest: pipe-D
[503.173027] Dynamic subtest pipe-D: SUCCESS (8.593s)
[503.175651] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.511s)
[504.016568] [073/130] (621s left) api_intel_allocator (fork-simple-once)
[504.077139] Starting subtest: fork-simple-once
[504.093458] Subtest fork-simple-once: SUCCESS (0.019s)
[504.230659] [074/130] (621s left) perf (oa-exponents)
[504.303451] Starting subtest: oa-exponents
[504.305782] Starting dynamic subtest: 0-rcs0
[506.178066] Dynamic subtest 0-rcs0: SUCCESS (1.873s)
[506.189638] Starting dynamic subtest: 1-vecs0
[508.087945] Dynamic subtest 1-vecs0: SUCCESS (1.909s)
[508.090378] Subtest oa-exponents: SUCCESS (3.783s)
[508.126655] [075/130] (617s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[508.232765] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[508.235047] Starting dynamic subtest: pipe-A-eDP-1
[508.258163] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[508.267045] Starting dynamic subtest: pipe-B-eDP-1
[508.288652] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[508.296994] Starting dynamic subtest: pipe-C-eDP-1
[508.319072] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[508.327769] Starting dynamic subtest: pipe-D-eDP-1
[508.350919] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[508.355095] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[510.380365] [076/130] (615s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[510.478846] Starting subtest: yf-tiled-64bpp-rotate-90
[510.483206] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[510.571278] [077/130] (615s left) gem_exec_balancer (nohangcheck)
[510.821438] Starting subtest: nohangcheck
[511.579147] Subtest nohangcheck: SUCCESS (0.758s)
[511.715067] [078/130] (613s left) gem_pxp (create-valid-protected-context)
[511.846147] Starting subtest: create-valid-protected-context
[511.848431] Subtest create-valid-protected-context: SUCCESS (0.001s)
[511.973394] [079/130] (613s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[512.090286] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[513.335652] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.248s)
[513.723959] [080/130] (612s left) api_intel_allocator (reopen-fork)
[513.792443] Starting subtest: reopen-fork
[517.133932] Subtest reopen-fork: SUCCESS (3.343s)
[517.203824] [081/130] (608s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[522.220285] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[522.227923] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.004s)
[523.079371] [082/130] (602s left) gem_exec_fence (basic-wait)
[523.263397] Starting subtest: basic-wait
[523.267396] Starting dynamic subtest: rcs0
[523.375050] Dynamic subtest rcs0: SUCCESS (0.111s)
[523.386342] Starting dynamic subtest: bcs0
[523.438301] Dynamic subtest bcs0: SUCCESS (0.061s)
[523.449219] Starting dynamic subtest: vcs0
[523.505485] Dynamic subtest vcs0: SUCCESS (0.066s)
[523.515538] Starting dynamic subtest: vcs1
[523.572538] Dynamic subtest vcs1: SUCCESS (0.065s)
[523.583672] Starting dynamic subtest: vecs0
[523.640282] Dynamic subtest vecs0: SUCCESS (0.066s)
[523.651432] Starting dynamic subtest: ccs0
[523.704037] Dynamic subtest ccs0: SUCCESS (0.062s)
[523.706486] Subtest basic-wait: SUCCESS (0.442s)
[525.794962] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[525.798529] [083/130] (600s left) kms_chamelium_audio (hdmi-audio-edid)
[526.192544] Subtest hdmi-audio-edid: SKIP (0.000s)
[526.992432] [084/130] (598s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[527.091047] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[527.095374] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[527.167649] [085/130] (598s left) prime_self_import (basic-llseek-size)
[527.204275] Starting subtest: basic-llseek-size
[527.222374] Subtest basic-llseek-size: SUCCESS (0.020s)
[527.340476] [086/130] (598s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[527.393567] Starting subtest: etime-multi-wait-all-available-unsubmitted
[527.496318] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.102s)
[527.568538] [087/130] (598s left) kms_psr (fbc-psr-primary-blt)
[527.677101] Starting subtest: fbc-psr-primary-blt
[527.679454] Starting dynamic subtest: eDP-1
[527.684078] Dynamic subtest eDP-1: SKIP (0.000s)
[527.688700] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[527.744390] [088/130] (598s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-gtt)
[527.855226] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-gtt
[527.859923] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[527.975028] [089/130] (598s left) gem_vm_create (create-ext)
[528.047864] Starting subtest: create-ext
[528.052680] Subtest create-ext: SUCCESS (0.005s)
[528.182961] [090/130] (597s left) kms_invalid_mode (bad-htotal)
[528.290412] Starting subtest: bad-htotal
[528.293059] Starting dynamic subtest: pipe-A-eDP-1
[528.570885] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[528.580741] Starting dynamic subtest: pipe-B-eDP-1
[528.583004] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[528.591814] Starting dynamic subtest: pipe-C-eDP-1
[528.594186] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[528.602596] Starting dynamic subtest: pipe-D-eDP-1
[528.604590] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[528.606545] Subtest bad-htotal: SUCCESS (0.287s)
[529.434662] [091/130] (596s left) kms_hdr (bpc-switch)
[529.546896] Starting subtest: bpc-switch
[530.602662] Starting dynamic subtest: pipe-A-eDP-1
[533.983745] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.380s)
[533.986142] Subtest bpc-switch: SUCCESS (4.435s)
[534.101907] [092/130] (592s left) gem_ctx_persistence (engines-mixed)
[534.163221] Starting subtest: engines-mixed
[534.165635] Starting dynamic subtest: rcs0
[534.279681] Dynamic subtest rcs0: SUCCESS (0.116s)
[534.291442] Starting dynamic subtest: bcs0
[534.338278] Dynamic subtest bcs0: SUCCESS (0.056s)
[534.349221] Starting dynamic subtest: vcs0
[534.407882] Dynamic subtest vcs0: SUCCESS (0.068s)
[534.419383] Starting dynamic subtest: vcs1
[534.484658] Dynamic subtest vcs1: SUCCESS (0.075s)
[534.495460] Starting dynamic subtest: vecs0
[534.556235] Dynamic subtest vecs0: SUCCESS (0.070s)
[534.566796] Starting dynamic subtest: ccs0
[534.610321] Dynamic subtest ccs0: SUCCESS (0.052s)
[534.612658] Subtest engines-mixed: SUCCESS (0.448s)
[534.682952] [093/130] (591s left) kms_chamelium_edid (dp-mode-timings)
[535.066563] Subtest dp-mode-timings: SKIP (0.000s)
[535.839854] [094/130] (590s left) drm_read (short-buffer-block)
[536.003907] Starting subtest: short-buffer-block
[536.006322] Subtest short-buffer-block: SUCCESS (0.000s)
[536.142929] [095/130] (590s left) syncobj_basic (create-signaled)
[536.206673] Starting subtest: create-signaled
[536.208844] Subtest create-signaled: SUCCESS (0.000s)
[536.298990] [096/130] (590s left) kms_psr (fbc-pr-sprite-render)
[536.413666] Starting subtest: fbc-pr-sprite-render
[536.417862] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[536.480463] [097/130] (589s left) i915_hangman (engine-engine-error)
[536.556275] Starting subtest: engine-engine-error
[536.558666] Starting dynamic subtest: rcs0
[537.690647] Dynamic subtest rcs0: SUCCESS (1.134s)
[537.700365] Starting dynamic subtest: bcs0
[539.674935] Dynamic subtest bcs0: SUCCESS (1.981s)
[539.685714] Starting dynamic subtest: vcs0
[541.658120] Dynamic subtest vcs0: SUCCESS (1.981s)
[541.670700] Starting dynamic subtest: vcs1
[543.704979] Dynamic subtest vcs1: SUCCESS (2.044s)
[543.716621] Starting dynamic subtest: vecs0
[545.690943] Dynamic subtest vecs0: SUCCESS (1.983s)
[545.701442] Starting dynamic subtest: ccs0
[547.674315] Dynamic subtest ccs0: SUCCESS (1.982s)
[547.677013] Subtest engine-engine-error: SUCCESS (11.115s)
[547.750616] [098/130] (578s left) gem_ctx_param (invalid-size-set)
[547.813430] Starting subtest: invalid-size-set
[547.815881] Subtest invalid-size-set: SUCCESS (0.001s)
[547.903399] [099/130] (578s left) syncobj_wait (wait-zero-handles)
[547.960225] Starting subtest: wait-zero-handles
[547.962568] Subtest wait-zero-handles: SUCCESS (0.000s)
[548.066454] [100/130] (578s left) kms_dsc (dsc-with-formats)
[548.161885] Starting subtest: dsc-with-formats
[548.203036] Subtest dsc-with-formats: SKIP (0.001s)
[548.236747] [101/130] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[548.344254] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[548.348840] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[548.452717] [102/130] (578s left) vgem_basic (bad-fence)
[548.497281] Starting subtest: bad-fence
[548.499173] Subtest bad-fence: SUCCESS (0.000s)
[548.552853] [103/130] (578s left) gem_exec_fence (syncobj-signal)
[548.957443] Starting subtest: syncobj-signal
[548.963330] Subtest syncobj-signal: SUCCESS (0.004s)
[550.784054] [104/130] (575s left) gem_workarounds (basic-read-fd)
[550.845955] Starting subtest: basic-read-fd
[550.857713] Subtest basic-read-fd: SUCCESS (0.010s)
[550.994708] [105/130] (575s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[556.009497] Starting subtest: psr2-overlay-plane-update-continuous-sf
[556.011757] Starting dynamic subtest: pipe-A-eDP-1
[557.767334] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[557.777280] Starting dynamic subtest: pipe-B-eDP-1
[559.490370] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.720s)
[559.492776] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.482s)
[560.302346] [106/130] (566s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[560.419589] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[560.423832] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[560.517449] [107/130] (566s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[560.611025] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[560.615542] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[561.733045] [108/130] (565s left) gem_mmap_gtt (isolation)
[561.795184] Subtest isolation: SKIP (0.000s)
[561.858906] [109/130] (564s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[561.987395] Starting subtest: 2x-long-flip-vs-cursor-atomic
[561.991714] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[562.083566] [110/130] (564s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[562.182427] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[562.184929] Starting dynamic subtest: pipe-A-default-mode
[562.476061] Dynamic subtest pipe-A-default-mode: SKIP (0.290s)
[562.480664] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.291s)
[563.260153] [111/130] (563s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[563.384289] Starting subtest: cursorA-vs-flipA-varying-size
[574.552806] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.163s)
[575.396465] [112/130] (551s left) kms_addfb_basic (basic)
[575.451403] Starting subtest: basic
[575.453422] Subtest basic: SUCCESS (0.000s)
[575.577460] [113/130] (551s left) kms_flip (2x-blocking-absolute-wf_vblank)
[575.686001] Starting subtest: 2x-blocking-absolute-wf_vblank
[575.698443] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.012s)
[575.774178] [114/130] (551s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[575.873213] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[580.887581] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (5.015s)
[580.996448] [115/130] (546s left) kms_vblank (query-forked)
[581.091813] Starting subtest: query-forked
[581.094460] Starting dynamic subtest: pipe-A-eDP-1
[582.939440] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.845s)
[582.953828] Starting dynamic subtest: pipe-D-eDP-1
[585.487277] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.544s)
[585.489693] Subtest query-forked: SUCCESS (4.393s)
[586.282910] [116/130] (540s left) gem_ctx_persistence (idempotent)
[586.355346] Starting subtest: idempotent
[586.357851] Subtest idempotent: SUCCESS (0.000s)
[586.474382] [117/130] (540s left) kms_content_protection (atomic-dpms)
[586.645853] Starting subtest: atomic-dpms
[590.008621] Subtest atomic-dpms: SKIP (3.359s)
[591.177981] [118/130] (535s left) i915_pm_rpm (gem-evict-pwrite)
[592.813994] Starting subtest: gem-evict-pwrite
[592.819231] Subtest gem-evict-pwrite: SKIP (0.000s)
[593.416646] [119/130] (533s left) kms_atomic_transition (modeset-transition-fencing)
[593.515883] Starting subtest: modeset-transition-fencing
[593.518128] Starting dynamic subtest: 1x-outputs
[604.025689] Dynamic subtest 1x-outputs: SUCCESS (10.507s)
[604.286349] Subtest modeset-transition-fencing: SUCCESS (10.769s)
[605.140532] [120/130] (522s left) gem_exec_params (mmapped)
[605.196333] Starting subtest: mmapped
[605.201281] Subtest mmapped: SUCCESS (0.002s)
[605.338215] [121/130] (521s left) gem_render_copy (yf-tiled-to-vebox-linear)
[605.402509] Starting subtest: yf-tiled-to-vebox-linear
[605.406944] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[605.581125] [122/130] (521s left) gem_mmap_offset (open-flood)
[605.645526] Starting subtest: open-flood
[627.130360] Subtest open-flood: SUCCESS (21.494s)
[627.252258] [123/130] (500s left) gem_userptr_blits (coherency-unsync)
[627.318274] Subtest coherency-unsync: SKIP (0.000s)
[627.446839] [124/130] (499s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[627.561290] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[627.564890] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[627.663493] [125/130] (499s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[627.722105] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[627.724070] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[627.808751] [126/130] (499s left) gem_basic (multigpu-create-close)
[627.865717] Starting subtest: multigpu-create-close
[627.869400] Subtest multigpu-create-close: SKIP (0.000s)
[627.966402] [127/130] (499s left) gem_exec_suspend (basic-s3)
[628.030588] Starting subtest: basic-S3
[628.032791] Starting dynamic subtest: smem
[649.000797] Dynamic subtest smem: SUCCESS (6.652s)
[649.003600] Subtest basic-S3: SUCCESS (6.654s)
[649.179800] [128/130] (492s left) kms_cursor_crc (cursor-sliding-32x10)
[649.337916] Starting subtest: cursor-sliding-32x10
[649.378739] Subtest cursor-sliding-32x10: SKIP (0.035s)
[649.645625] [129/130] (492s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[649.751124] Starting subtest: x-tiled-8bpp-rotate-0
[650.895309] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.144s)
[651.035968] [130/130] (490s left) kms_plane_multiple (tiling-x)
[651.134123] Starting subtest: tiling-x
[651.136336] Starting dynamic subtest: pipe-A-eDP-1
[654.764442] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.624s)
[654.774824] Starting dynamic subtest: pipe-B-eDP-1
[659.152002] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.384s)
[659.161390] Starting dynamic subtest: pipe-C-eDP-1
[663.451991] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.297s)
[663.465866] Starting dynamic subtest: pipe-D-eDP-1
[667.737528] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.282s)
[667.739550] Subtest tiling-x: SUCCESS (16.592s)
[668.546457] Closing watchdogs
Done.