[72.215490] Initializing watchdogs
[72.215984]   /dev/watchdog0
[72.248670] [001/142] (960s left) gem_ctx_isolation (nonpriv-switch)
[72.728883] Starting subtest: nonpriv-switch
[72.732043] Starting dynamic subtest: rcs0
[74.047315] Dynamic subtest rcs0: SUCCESS (1.318s)
[74.079062] Starting dynamic subtest: bcs0
[75.444708] Dynamic subtest bcs0: SUCCESS (1.396s)
[75.468989] Starting dynamic subtest: vcs0
[76.405892] Dynamic subtest vcs0: SUCCESS (0.961s)
[76.437389] Starting dynamic subtest: vcs1
[78.497219] Dynamic subtest vcs1: SUCCESS (2.091s)
[78.524633] Starting dynamic subtest: vecs0
[80.731542] Dynamic subtest vecs0: SUCCESS (2.234s)
[80.758741] Starting dynamic subtest: ccs0
[82.925563] Dynamic subtest ccs0: SUCCESS (2.193s)
[82.929185] Subtest nonpriv-switch: SUCCESS (10.197s)
[83.321888] [002/142] (949s left) kms_plane_cursor (viewport)
[83.833678] Starting subtest: viewport
[83.840839] Starting dynamic subtest: pipe-A-eDP-1-size-64
[91.374294] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (7.538s)
[91.475850] Starting dynamic subtest: pipe-A-eDP-1-size-128
[97.565846] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (6.118s)
[97.660640] Starting dynamic subtest: pipe-A-eDP-1-size-256
[105.067315] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (7.440s)
[105.584927] Starting dynamic subtest: pipe-B-eDP-1-size-64
[113.468150] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (7.914s)
[113.569843] Starting dynamic subtest: pipe-B-eDP-1-size-128
[120.946631] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (7.406s)
[121.057296] Starting dynamic subtest: pipe-B-eDP-1-size-256
[128.425824] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (7.406s)
[128.915538] Starting dynamic subtest: pipe-C-eDP-1-size-64
[136.493666] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (7.606s)
[136.590138] Starting dynamic subtest: pipe-C-eDP-1-size-128
[143.463240] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (6.907s)
[143.551285] Starting dynamic subtest: pipe-C-eDP-1-size-256
[150.499373] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (6.971s)
[150.972307] Starting dynamic subtest: pipe-D-eDP-1-size-64
[158.509493] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (7.561s)
[158.592464] Starting dynamic subtest: pipe-D-eDP-1-size-128
[165.538619] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (6.964s)
[165.610429] Starting dynamic subtest: pipe-D-eDP-1-size-256
[172.506170] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (6.920s)
[172.949842] Subtest viewport: SUCCESS (89.115s)
[173.884509] [003/142] (859s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[174.430508] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[174.437929] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[174.602542] [004/142] (858s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[175.121396] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[175.128345] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[175.280657] [005/142] (857s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[175.786429] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[175.793822] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[175.943667] [006/142] (857s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[176.482393] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[176.489249] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[176.641703] [007/142] (856s left) gem_ctx_persistence (engines-mixed-process)
[177.044874] Starting subtest: engines-mixed-process
[177.049708] Starting dynamic subtest: rcs0
[178.606353] Dynamic subtest rcs0: SUCCESS (1.553s)
[178.639870] Starting dynamic subtest: bcs0
[180.593425] Dynamic subtest bcs0: SUCCESS (1.982s)
[180.628461] Starting dynamic subtest: vcs0
[200.696551] Dynamic subtest vcs0: SUCCESS (20.105s)
[200.739800] Starting dynamic subtest: vcs1
[220.800254] Dynamic subtest vcs1: SUCCESS (20.097s)
[220.839774] Starting dynamic subtest: vecs0
[240.902554] Dynamic subtest vecs0: SUCCESS (20.087s)
[240.939974] Starting dynamic subtest: ccs0
[242.591944] Dynamic subtest ccs0: SUCCESS (1.693s)
[242.595653] Subtest engines-mixed-process: SUCCESS (65.550s)
[242.773739] [008/142] (790s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[243.298214] Starting subtest: psr-rgb565-draw-render
[259.892986] Subtest psr-rgb565-draw-render: SUCCESS (16.600s)
[260.172562] [009/142] (773s left) gem_exec_balancer (indices)
[260.590816] Starting subtest: indices
[269.495724] Subtest indices: SUCCESS (8.903s)
[270.516909] [010/142] (762s left) kms_flip (2x-wf_vblank-ts-check)
[270.932724] Starting subtest: 2x-wf_vblank-ts-check
[271.090731] Subtest 2x-wf_vblank-ts-check: SKIP (0.159s)
[271.277997] [011/142] (762s left) kms_ccs (pipe-c-bad-aux-stride-4_tiled_mtl_mc_ccs)
[272.840136] Starting subtest: pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs
[273.348805] Subtest pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs: SUCCESS (0.509s)
[274.200132] [012/142] (759s left) api_intel_bb (object-noreloc-purge-cache-simple)
[274.582866] Starting subtest: object-noreloc-purge-cache-simple
[274.600646] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.021s)
[274.783767] [013/142] (758s left) kms_cursor_edge_walk (64x64-left-edge)
[275.263441] Starting subtest: 64x64-left-edge
[275.269242] Starting dynamic subtest: pipe-A-eDP-1
[279.418394] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.147s)
[279.460368] Starting dynamic subtest: pipe-D-eDP-1
[282.885411] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.455s)
[282.888874] Subtest 64x64-left-edge: SUCCESS (7.623s)
[283.797523] [014/142] (749s left) i915_pm_backlight (fade-with-suspend)
[284.285270] Starting subtest: fade-with-suspend
[287.543710] [015/142] (746s left) kms_lease (lessee_list)
[288.035639] Starting subtest: lessee_list
[288.039271] Starting dynamic subtest: pipe-A-eDP-1
[288.049139] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[288.093339] Starting dynamic subtest: pipe-B-eDP-1
[288.096757] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[288.138141] Starting dynamic subtest: pipe-C-eDP-1
[288.141624] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[288.181892] Starting dynamic subtest: pipe-D-eDP-1
[288.185172] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[288.188199] Subtest lessee_list: SUCCESS (0.044s)
[288.251214] [016/142] (745s left) perf (oa-formats)
[289.202683] Starting subtest: oa-formats
[289.206282] Starting dynamic subtest: 0-rcs0
[289.248929] Dynamic subtest 0-rcs0: SUCCESS (0.045s)
[289.302323] Starting dynamic subtest: 1-vecs0
[289.317936] Dynamic subtest 1-vecs0: SUCCESS (0.063s)
[289.321542] Subtest oa-formats: SUCCESS (0.118s)
[289.418159] [017/142] (744s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[289.994279] Starting subtest: cursorB-vs-flipB-toggle
[290.005436] Subtest cursorB-vs-flipB-toggle: SKIP (0.008s)
[290.318277] [018/142] (743s left) gem_exec_nop (basic-parallel)
[290.749886] Starting subtest: basic-parallel
[306.487273] Subtest basic-parallel: SUCCESS (15.737s)
[306.830446] [019/142] (727s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[307.901328] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[325.190189] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SUCCESS (17.288s)
[326.816686] [020/142] (707s left) gem_tiled_wc
[327.333145] [021/142] (707s left) gem_exec_whisper (basic-normal)
[327.758401] Starting subtest: basic-normal
[339.516961] Subtest basic-normal: SUCCESS (11.754s)
[339.827636] [022/142] (694s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[340.729137] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[341.597739] [023/142] (692s left) prime_self_import (basic-with_two_bos)
[341.972585] Starting subtest: basic-with_two_bos
[341.990698] Subtest basic-with_two_bos: SUCCESS (0.018s)
[342.160697] [024/142] (692s left) gem_mmap_gtt (basic-small-copy-xy)
[342.544931] Subtest basic-small-copy-XY: SKIP (0.000s)
[342.659438] [025/142] (692s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5)
[343.142956] Starting subtest: plane-downscale-with-pixel-format-factor-0-5
[343.151351] Starting dynamic subtest: pipe-A-eDP-1
[343.262831] Dynamic subtest pipe-A-eDP-1: SKIP (0.109s)
[343.313639] Starting dynamic subtest: pipe-B-eDP-1
[343.489833] Dynamic subtest pipe-B-eDP-1: SKIP (0.214s)
[343.542466] Starting dynamic subtest: pipe-C-eDP-1
[343.703189] Dynamic subtest pipe-C-eDP-1: SKIP (0.200s)
[343.748426] Starting dynamic subtest: pipe-D-eDP-1
[343.928535] Dynamic subtest pipe-D-eDP-1: SKIP (0.214s)
[343.935276] Subtest plane-downscale-with-pixel-format-factor-0-5: SKIP (0.781s)
[344.120502] [026/142] (690s left) i915_suspend (fence-restore-tiled2untiled)
[344.507795] Starting subtest: fence-restore-tiled2untiled
[344.515337] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[344.650722] [027/142] (690s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[345.117803] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[345.124445] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[346.455289] [028/142] (688s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[346.972233] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[346.978603] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[347.143659] [029/142] (687s left) kms_selftest (drm_format)
[347.505345] Starting subtest: drm_format
[347.518881] Subtest drm_format: SKIP (0.007s)
[347.633402] [030/142] (687s left) gem_mmap_offset (perf)
[347.992223] Starting subtest: perf
[347.995779] Starting dynamic subtest: smem0
[349.156384] Dynamic subtest smem0: SUCCESS (1.159s)
[349.160518] Subtest perf: SUCCESS (1.163s)
[349.331668] [031/142] (685s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[349.861910] Starting subtest: x-tiled-16bpp-rotate-180
[354.315993] Subtest x-tiled-16bpp-rotate-180: SUCCESS (4.451s)
[354.643687] [032/142] (680s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[355.458135] Starting subtest: cursor-rapid-movement-128x128
[355.489371] Starting dynamic subtest: pipe-A-eDP-1
[356.784694] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.292s)
[356.985865] Starting dynamic subtest: pipe-D-eDP-1
[359.665088] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.718s)
[359.669360] Subtest cursor-rapid-movement-128x128: SUCCESS (4.206s)
[360.742964] [033/142] (674s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[361.249378] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[372.702990] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (11.458s)
[372.970791] [034/142] (662s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[373.350607] Starting subtest: multi-wait-all-for-submit-available-signaled
[373.353790] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.004s)
[373.505869] [035/142] (662s left) gem_softpin (overlap)
[373.910152] Starting subtest: overlap
[381.302301] Subtest overlap: SUCCESS (7.385s)
[381.512149] [036/142] (654s left) dumb_buffer (map-invalid-size)
[381.899842] Starting subtest: map-invalid-size
[381.903612] Subtest map-invalid-size: SUCCESS (0.001s)
[382.097595] [037/142] (653s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[382.597370] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[382.604364] Starting dynamic subtest: pipe-A-default-mode
[383.014378] Dynamic subtest pipe-A-default-mode: SKIP (0.403s)
[383.021394] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.417s)
[383.962048] [038/142] (651s left) syncobj_basic (bad-handle-to-fd)
[384.331247] Starting subtest: bad-handle-to-fd
[384.334758] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[384.479925] [039/142] (651s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[385.020413] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[387.320058] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (2.301s)
[387.625221] [040/142] (648s left) gem_exec_suspend (basic-s3-devices)
[388.061760] Starting subtest: basic-S3-devices
[388.065286] Starting dynamic subtest: smem
[396.014957] Dynamic subtest smem: FAIL (7.868s)
[396.018462] Subtest basic-S3-devices: FAIL (7.871s)
[396.272966] [041/142] (639s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[396.782881] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[396.789384] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[396.961486] [042/142] (639s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[397.460143] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[397.467175] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[397.602487] [043/142] (638s left) gen9_exec_parse (batch-invalid-length)
[398.001165] Subtest batch-invalid-length: SKIP (0.000s)
[398.125269] [044/142] (638s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[398.595233] Starting subtest: modeset-transition-nonblocking-fencing
[398.598419] Starting dynamic subtest: 1x-outputs
[425.563903] Dynamic subtest 1x-outputs: SUCCESS (26.963s)
[425.937693] Subtest modeset-transition-nonblocking-fencing: SUCCESS (27.340s)
[426.867564] [045/142] (609s left) kms_psr2_sf (cursor-plane-move-continuous-sf)
[433.654658] Starting subtest: cursor-plane-move-continuous-sf
[433.667880] Starting dynamic subtest: pipe-A-eDP-1
[444.984633] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.316s)
[445.038037] Starting dynamic subtest: pipe-B-eDP-1
[456.275836] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.276s)
[456.279812] Subtest cursor-plane-move-continuous-sf: SUCCESS (22.620s)
[457.229981] [046/142] (579s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[457.767170] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[462.990469] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: FAIL (4.436s)
[463.064554] [047/142] (573s left) kms_chamelium_hpd (hdmi-hpd-fast)
[463.942213] Subtest hdmi-hpd-fast: SKIP (0.000s)
[464.819278] [048/142] (571s left) gem_softpin (zero)
[465.209466] Starting subtest: zero
[465.226341] Subtest zero: SUCCESS (0.017s)
[465.410561] [049/142] (571s left) kms_psr (psr2_primary_mmap_cpu)
[465.893306] Starting subtest: psr2_primary_mmap_cpu
[469.061204] Subtest psr2_primary_mmap_cpu: SUCCESS (3.163s)
[470.013258] [050/142] (566s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[470.425898] Starting subtest: x-tiled-to-vebox-yf-tiled
[470.431937] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[470.709317] [051/142] (566s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[471.125603] Starting subtest: y-tiled-ccs-to-x-tiled
[471.132504] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[471.405439] [052/142] (565s left) gem_exec_params (invalid-bsd-ring)
[471.795595] Starting subtest: invalid-bsd-ring
[471.799613] Subtest invalid-bsd-ring: SUCCESS (0.001s)
[471.964827] [053/142] (565s left) perf_pmu (frequency)
[472.367072] Starting subtest: frequency
[472.370608] Starting dynamic subtest: gt0
[474.550806] Dynamic subtest gt0: SUCCESS (2.174s)
[474.595955] Starting dynamic subtest: idle-gt0
[475.071801] Dynamic subtest idle-gt0: SUCCESS (0.518s)
[475.120637] Starting dynamic subtest: gt1
[479.574610] Dynamic subtest gt1: SUCCESS (4.493s)
[479.620745] Starting dynamic subtest: idle-gt1
[480.083315] Dynamic subtest idle-gt1: SUCCESS (0.508s)
[480.087938] Subtest frequency: SUCCESS (7.713s)
[480.266984] [054/142] (556s left) prime_mmap (test_forked)
[480.650854] Starting subtest: test_forked
[480.654374] Starting dynamic subtest: test_forked-smem
[481.248815] Dynamic subtest test_forked-smem: SUCCESS (0.599s)
[481.252854] Subtest test_forked: SUCCESS (0.604s)
[481.437166] [055/142] (555s left) template (b)
[481.808730] Starting subtest: B
[481.812239] Subtest B: SUCCESS (0.000s)
[481.988534] [056/142] (555s left) kms_vblank (pipe-a-query-forked-hang)
[482.471373] Starting subtest: pipe-A-query-forked-hang
[496.827210] Subtest pipe-A-query-forked-hang: SUCCESS (14.355s)
[497.784086] [057/142] (539s left) v3d/v3d_submit_cl (single-out-sync)
[498.262339] Subtest single-out-sync: SKIP (0.000s)
[498.393419] [058/142] (539s left) perf_pmu (gt-awake)
[498.792416] Starting subtest: gt-awake
[507.513572] Subtest gt-awake: SUCCESS (8.721s)
[507.738378] [059/142] (529s left) syncobj_timeline (invalid-transfer-non-existent-point)
[508.117234] Starting subtest: invalid-transfer-non-existent-point
[508.120763] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[508.298677] [060/142] (529s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[508.814585] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[508.821349] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[508.995685] [061/142] (528s left) i915_pm_rpm (fences-dpms)
[511.729787] Starting subtest: fences-dpms
[511.736746] Subtest fences-dpms: SKIP (0.000s)
[512.358955] [062/142] (525s left) kms_universal_plane (universal-plane-pipe-d-sanity)
[512.853334] Starting subtest: universal-plane-pipe-D-sanity
[515.508771] Subtest universal-plane-pipe-D-sanity: SUCCESS (2.651s)
[516.463176] [063/142] (521s left) perf (gen12-group-concurrent-oa-buffer-read)
[517.096577] Starting subtest: gen12-group-concurrent-oa-buffer-read
[527.256223] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.160s)
[527.380480] [064/142] (510s left) kms_big_fb (yf-tiled-addfb)
[527.928059] Starting subtest: yf-tiled-addfb
[527.934819] Subtest yf-tiled-addfb: SKIP (0.000s)
[528.086528] [065/142] (510s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[529.750791] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[529.757937] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[530.209736] [066/142] (508s left) kms_vblank (pipe-c-query-forked-busy)
[530.691532] Starting subtest: pipe-C-query-forked-busy
[533.853167] Subtest pipe-C-query-forked-busy: SUCCESS (3.161s)
[534.820761] [067/142] (503s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[535.337364] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[535.345084] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[535.511969] [068/142] (502s left) gem_gtt_cpu_tlb
[536.001572] [069/142] (502s left) gem_mmap_wc (close)
[536.372769] Subtest close: SKIP (0.000s)
[536.487403] [070/142] (502s left) gem_softpin (evict-prime-sanity-check)
[536.885254] Starting subtest: evict-prime-sanity-check
[536.888919] Starting dynamic subtest: rcs0
[538.026080] Dynamic subtest rcs0: SUCCESS (1.138s)
[538.080664] Starting dynamic subtest: bcs0
[539.153360] Dynamic subtest bcs0: SUCCESS (1.122s)
[539.209639] Starting dynamic subtest: vcs0
[540.545490] Dynamic subtest vcs0: SUCCESS (1.387s)
[540.594097] Starting dynamic subtest: vcs1
[542.532643] Dynamic subtest vcs1: SUCCESS (1.981s)
[542.581179] Starting dynamic subtest: vecs0
[544.526996] Dynamic subtest vecs0: SUCCESS (1.991s)
[544.577135] Starting dynamic subtest: ccs0
[545.655534] Dynamic subtest ccs0: SUCCESS (1.126s)
[545.711307] Starting dynamic subtest: all
[546.790116] Dynamic subtest all: SUCCESS (1.130s)
[546.793971] Subtest evict-prime-sanity-check: SUCCESS (9.907s)
[546.963163] [071/142] (491s left) kms_addfb_basic (clobberred-modifier)
[547.338466] Starting subtest: clobberred-modifier
[547.344425] Subtest clobberred-modifier: SKIP (0.000s)
[547.596259] [072/142] (491s left) gem_exec_params (larger-than-life-batch)
[547.985586] Starting subtest: larger-than-life-batch
[559.489651] Subtest larger-than-life-batch: SUCCESS (11.503s)
[560.702494] [073/142] (478s left) gem_linear_blits (normal)
[561.487065] Starting subtest: normal
[582.930161] Subtest normal: SUCCESS (21.443s)
[583.125086] [074/142] (455s left) kms_frontbuffer_tracking (fbc-tiling-y)
[583.798182] Starting subtest: fbc-tiling-y
[583.804410] Subtest fbc-tiling-y: SKIP (0.000s)
[583.975856] [075/142] (455s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[584.373952] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[584.477734] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[584.629298] [076/142] (454s left) kms_psr (primary_page_flip)
[585.110546] Starting subtest: primary_page_flip
[588.211570] Subtest primary_page_flip: SUCCESS (3.095s)
[589.121448] [077/142] (450s left) gem_ctx_param (invalid-param-get)
[589.261950] Starting subtest: invalid-param-get
[589.264569] Subtest invalid-param-get: SUCCESS (0.001s)
[589.345994] [078/142] (449s left) vc4/vc4_purgeable_bo (access-purgeable-bo-mem)
[589.524967] Subtest access-purgeable-bo-mem: SKIP (0.000s)
[589.623561] [079/142] (449s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[590.912372] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[599.559398] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (8.647s)
[600.902692] [080/142] (438s left) sw_sync (sync_random_merge)
[601.287804] Starting subtest: sync_random_merge
[601.577669] Subtest sync_random_merge: SUCCESS (0.297s)
[601.717960] [081/142] (437s left) vc4/vc4_wait_bo (bad-pad)
[602.170628] Subtest bad-pad: SKIP (0.000s)
[602.307977] [082/142] (437s left) gem_exec_schedule (wide)
[602.921224] Starting subtest: wide
[602.924652] Starting dynamic subtest: rcs0
[635.592275] Dynamic subtest rcs0: SUCCESS (32.673s)
[635.649655] Starting dynamic subtest: bcs0
[669.525859] Dynamic subtest bcs0: SUCCESS (33.925s)
[669.587614] Starting dynamic subtest: vcs0
[702.229048] Dynamic subtest vcs0: SUCCESS (32.700s)
[702.276280] Starting dynamic subtest: vcs1
[733.292532] Dynamic subtest vcs1: SUCCESS (31.058s)
[733.330463] Starting dynamic subtest: vecs0
[764.153988] Dynamic subtest vecs0: SUCCESS (30.861s)
[764.197190] Starting dynamic subtest: ccs0
[798.867598] Dynamic subtest ccs0: SUCCESS (34.708s)
[798.883440] Subtest wide: SUCCESS (195.967s)
[800.588457] [083/142] (239s left) vgem_slow (nohang)
[801.000485] Starting subtest: nohang
[811.257241] Subtest nohang: SUCCESS (10.260s)
[811.425820] [084/142] (228s left) kms_content_protection (dp-mst-lic-type-0)
[812.135181] Starting subtest: dp-mst-lic-type-0
[812.141840] Subtest dp-mst-lic-type-0: SKIP (0.001s)
[812.330576] [085/142] (227s left) gem_caching (read-writes)
[812.734686] Subtest read-writes: SKIP (0.000s)
[812.860709] [086/142] (227s left) v3d/v3d_wait_bo (unused-bo-0ns)
[813.348913] Subtest unused-bo-0ns: SKIP (0.000s)
[813.487539] [087/142] (226s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[814.004947] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[814.011809] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[814.174862] [088/142] (226s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[814.693970] Starting subtest: fbc-rgb565-draw-blt
[827.996199] Subtest fbc-rgb565-draw-blt: SUCCESS (13.304s)
[828.329901] [089/142] (211s left) kms_chamelium_audio (hdmi-audio)
[829.222370] Subtest hdmi-audio: SKIP (0.000s)
[830.104268] [090/142] (210s left) drm_fdinfo (basics)
[830.504611] Starting subtest: basics
[830.508466] Subtest basics: SUCCESS (0.000s)
[830.661305] [091/142] (209s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[831.150772] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[831.158301] Starting dynamic subtest: pipe-A-default-mode
[832.638447] Dynamic subtest pipe-A-default-mode: SKIP (1.470s)
[832.644913] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.484s)
[832.898262] [092/142] (207s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[833.411904] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[850.240461] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (16.836s)
[850.502539] [093/142] (190s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs)
[851.614496] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs
[851.621223] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[852.887678] [094/142] (187s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[853.399852] Starting subtest: fbc-rgb565-draw-mmap-cpu
[863.011286] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (9.613s)
[863.343679] [095/142] (177s left) gem_exercise_blt (fast-copy)
[863.743963] Starting subtest: fast-copy
[863.748081] Starting dynamic subtest: linear-smem-smem
[863.883314] Dynamic subtest linear-smem-smem: SUCCESS (0.137s)
[863.945129] Starting dynamic subtest: xmajor-smem-smem
[864.014182] Dynamic subtest xmajor-smem-smem: SUCCESS (0.125s)
[864.067487] Starting dynamic subtest: tile4-smem-smem
[864.125532] Dynamic subtest tile4-smem-smem: SUCCESS (0.107s)
[864.176060] Starting dynamic subtest: tile64-smem-smem
[864.236163] Dynamic subtest tile64-smem-smem: SUCCESS (0.107s)
[864.239859] Subtest fast-copy: SUCCESS (0.495s)
[864.429175] [096/142] (176s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[864.986698] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[870.070271] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: FAIL (4.297s)
[870.151785] [097/142] (170s left) kms_cursor_crc (cursor-offscreen-512x170)
[871.402163] Starting subtest: cursor-offscreen-512x170
[871.414563] Subtest cursor-offscreen-512x170: SKIP (0.007s)
[871.742242] [098/142] (169s left) v3d/v3d_get_bo_offset (create-get-offsets)
[872.211309] Subtest create-get-offsets: SKIP (0.000s)
[872.356760] [099/142] (168s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[872.756095] Subtest tiled-to-tiled: SKIP (0.000s)
[872.884196] [100/142] (168s left) gem_exec_fair (basic-deadline)
[873.283091] Subtest basic-deadline: SKIP (0.000s)
[873.432409] [101/142] (167s left) gem_userptr_blits (input-checking)
[873.832067] Starting subtest: input-checking
[873.835808] Subtest input-checking: SUCCESS (0.000s)
[874.156318] [102/142] (167s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[874.668442] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[891.224821] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (16.559s)
[891.490159] [103/142] (150s left) gem_reg_read (bad-register)
[891.866888] Starting subtest: bad-register
[891.870752] Subtest bad-register: SUCCESS (0.000s)
[892.025374] [104/142] (149s left) gem_ctx_persistence (legacy-engines-queued)
[892.434839] Starting subtest: legacy-engines-queued
[892.438165] Starting dynamic subtest: render
[892.512568] Dynamic subtest render: SUCCESS (0.075s)
[892.575479] Starting dynamic subtest: bsd1
[894.521975] Dynamic subtest bsd1: SUCCESS (2.003s)
[894.583339] Starting dynamic subtest: bsd2
[896.506292] Dynamic subtest bsd2: SUCCESS (1.979s)
[896.560272] Starting dynamic subtest: blt
[896.589468] Dynamic subtest blt: SUCCESS (0.080s)
[896.649144] Starting dynamic subtest: vebox
[898.490915] Dynamic subtest vebox: SUCCESS (1.894s)
[898.494618] Subtest legacy-engines-queued: SUCCESS (6.057s)
[898.710720] [105/142] (143s left) kms_vblank (pipe-c-query-idle-hang)
[899.216719] Starting subtest: pipe-C-query-idle-hang
[914.861439] Subtest pipe-C-query-idle-hang: SUCCESS (15.647s)
[915.823172] [106/142] (126s left) gem_media_fill (media-fill)
[916.201671] Subtest media-fill: SKIP (0.000s)
[916.383405] [107/142] (125s left) kms_vrr (flip-suspend)
[916.794422] Starting subtest: flip-suspend
[916.801502] Subtest flip-suspend: SKIP (0.000s)
[916.922900] [108/142] (125s left) api_intel_allocator (open-vm)
[917.302694] Starting subtest: open-vm
[917.311284] Subtest open-vm: SUCCESS (0.009s)
[917.500820] [109/142] (124s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_dg2_mc_ccs)
[918.914376] Starting subtest: pipe-C-crc-primary-basic-4_tiled_dg2_mc_ccs
[918.921434] Subtest pipe-C-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[919.931577] [110/142] (122s left) kms_hdmi_inject (inject-4k)
[920.727597] Starting subtest: inject-4k
[921.255900] Subtest inject-4k: SUCCESS (0.530s)
[921.911693] [111/142] (120s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_rc_ccs)
[923.164372] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs
[923.171071] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[924.279423] [112/142] (118s left) gem_exec_schedule (noreorder-priority)
[924.894534] Starting subtest: noreorder-priority
[924.898219] Starting dynamic subtest: rcs0
[926.057593] Dynamic subtest rcs0: SUCCESS (1.169s)
[926.117519] Starting dynamic subtest: bcs0
[927.193225] Dynamic subtest bcs0: SUCCESS (1.131s)
[927.246159] Starting dynamic subtest: vcs0
[928.533978] Dynamic subtest vcs0: SUCCESS (1.336s)
[928.593173] Starting dynamic subtest: vcs1
[930.513974] Dynamic subtest vcs1: SUCCESS (1.974s)
[930.570177] Starting dynamic subtest: vecs0
[932.563100] Dynamic subtest vecs0: SUCCESS (2.045s)
[932.616998] Starting dynamic subtest: ccs0
[933.702920] Dynamic subtest ccs0: SUCCESS (1.137s)
[933.706887] Subtest noreorder-priority: SUCCESS (8.818s)
[934.019651] [113/142] (108s left) i915_pm_rpm (system-suspend-modeset)
[936.732199] Starting subtest: system-suspend-modeset
[941.624827] Subtest system-suspend-modeset: FAIL (4.824s)
[942.396356] [114/142] (100s left) syncobj_wait (wait-any-interrupted)
[942.766758] Starting subtest: wait-any-interrupted
[942.870909] Subtest wait-any-interrupted: SUCCESS (0.104s)
[943.031755] [115/142] ( 99s left) kms_flip (2x-flip-vs-suspend-interruptible)
[943.448694] Starting subtest: 2x-flip-vs-suspend-interruptible
[943.616873] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.164s)
[943.797179] [116/142] ( 98s left) gem_exec_fence (submit3)
[944.382687] Starting subtest: submit3
[944.388779] Subtest submit3: SKIP (0.000s)
[946.774242] [117/142] ( 96s left) v3d/v3d_job_submission (array-job-submission)
[947.282131] Subtest array-job-submission: SKIP (0.000s)
[947.437445] [118/142] ( 95s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[949.189970] Starting subtest: pipe-D-missing-ccs-buffer-y_tiled_ccs
[949.196196] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[949.873929] [119/142] ( 93s left) gen9_exec_parse (allowed-all)
[950.277248] Subtest allowed-all: SKIP (0.000s)
[950.419938] [120/142] ( 92s left) gem_create (create-ext-set-pat)
[950.948246] Starting subtest: create-ext-set-pat
[950.951721] Subtest create-ext-set-pat: SUCCESS (0.002s)
[951.134954] [121/142] ( 92s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-signaled)
[951.517265] Starting subtest: invalid-multi-wait-all-available-unsubmitted-signaled
[951.520506] Subtest invalid-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.003s)
[951.677507] [122/142] ( 91s left) syncobj_timeline (wait-all-interrupted)
[952.059119] Starting subtest: wait-all-interrupted
[952.166778] Subtest wait-all-interrupted: SUCCESS (0.107s)
[952.321920] [123/142] ( 91s left) kms_lease (invalid-create-leases)
[952.819120] Starting subtest: invalid-create-leases
[952.837560] Subtest invalid-create-leases: SUCCESS (0.016s)
[953.039945] [124/142] ( 90s left) syncobj_wait (reset-unsignaled)
[953.412272] Starting subtest: reset-unsignaled
[953.415545] Subtest reset-unsignaled: SUCCESS (0.001s)
[953.575442] [125/142] ( 90s left) kms_busy (extended-pageflip-hang-oldfb)
[954.081475] Starting subtest: extended-pageflip-hang-oldfb
[954.090492] Starting dynamic subtest: pipe-A
[955.125395] Dynamic subtest pipe-A: SUCCESS (1.034s)
[955.201351] Starting dynamic subtest: pipe-D
[956.629117] Dynamic subtest pipe-D: SUCCESS (1.476s)
[956.632357] Subtest extended-pageflip-hang-oldfb: SUCCESS (2.548s)
[957.597597] [126/142] ( 86s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_mc_ccs)
[958.951980] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs
[958.958178] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[959.990763] [127/142] ( 83s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_ccs)
[961.549343] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_ccs
[961.565628] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.013s)
[962.397783] [128/142] ( 81s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[962.967495] Starting subtest: cursor-vs-flip-atomic
[996.682814] Subtest cursor-vs-flip-atomic: SUCCESS (33.716s)
[997.611092] [129/142] ( 46s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[998.821109] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc
[999.937281] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.115s)
[1001.629307] [130/142] ( 42s left) kms_plane_scaling (planes-scaler-unity-scaling)
[1002.113637] Starting subtest: planes-scaler-unity-scaling
[1002.122728] Starting dynamic subtest: pipe-A-eDP-1
[1002.324833] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.203s)
[1002.387987] Starting dynamic subtest: pipe-B-eDP-1
[1003.859158] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.524s)
[1003.923379] Starting dynamic subtest: pipe-C-eDP-1
[1005.219372] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.348s)
[1005.289166] Starting dynamic subtest: pipe-D-eDP-1
[1006.726778] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.497s)
[1006.730317] Subtest planes-scaler-unity-scaling: SUCCESS (4.615s)
[1008.258478] [131/142] ( 35s left) gem_exec_reloc (basic-write-cpu-active)
[1008.675188] Subtest basic-write-cpu-active: SKIP (0.000s)
[1008.818400] [132/142] ( 35s left) core_setmaster (master-drop-set-root)
[1012.796869] Starting subtest: master-drop-set-root
[1012.829418] Subtest master-drop-set-root: SUCCESS (0.028s)
[1012.927099] [133/142] ( 31s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[1013.455351] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[1013.462318] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[1013.646542] [134/142] ( 30s left) gem_pwrite (basic-self)
[1014.028436] Subtest basic-self: SKIP (0.000s)
[1014.168595] [135/142] ( 30s left) i915_pm_rpm (gem-execbuf)
[1016.726582] Starting subtest: gem-execbuf
[1016.729901] Starting dynamic subtest: smem0
[1019.165185] Dynamic subtest smem0: SUCCESS (2.439s)
[1019.168788] Subtest gem-execbuf: SUCCESS (2.443s)
[1019.380669] [136/142] ( 25s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[1019.895194] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[1019.902529] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[1020.098644] [137/142] ( 24s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[1021.018293] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[1022.369344] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.351s)
[1024.129761] [138/142] ( 20s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[1025.368990] Starting subtest: parallel-dmabuf-import-out-fence
[1032.741962] Subtest parallel-dmabuf-import-out-fence: SUCCESS (7.374s)
[1032.931055] [139/142] ( 12s left) gem_exec_parallel (fds)
[1033.358759] Starting subtest: fds
[1033.361788] Starting dynamic subtest: rcs0
[1040.619348] Dynamic subtest rcs0: SUCCESS (7.260s)
[1040.677819] Starting dynamic subtest: bcs0
[1046.204086] Dynamic subtest bcs0: SUCCESS (5.582s)
[1046.261333] Starting dynamic subtest: vcs0
[1053.252188] Dynamic subtest vcs0: SUCCESS (7.045s)
[1053.311386] Starting dynamic subtest: vcs1
[1059.966738] Dynamic subtest vcs1: SUCCESS (6.709s)
[1060.024878] Starting dynamic subtest: vecs0
[1066.335497] Dynamic subtest vecs0: SUCCESS (6.363s)
[1066.391550] Starting dynamic subtest: ccs0
[1074.295612] Dynamic subtest ccs0: SUCCESS (7.957s)
[1074.299343] Subtest fds: SUCCESS (40.941s)
[1074.631815] Overall timeout time exceeded, stopping.
[1074.633314] Closing watchdogs
Done.