[55.557490] Initializing watchdogs
[55.557964]   /dev/watchdog0
[55.589812] [001/142] (960s left) kms_vblank (pipe-a-query-forked-busy)
[56.123379] Starting subtest: pipe-A-query-forked-busy
[58.556597] Subtest pipe-A-query-forked-busy: SUCCESS (2.438s)
[59.500488] [002/142] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[60.001551] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[70.435140] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (10.440s)
[70.694250] [003/142] (945s left) kms_vblank (pipe-c-query-busy-hang)
[71.164341] Starting subtest: pipe-C-query-busy-hang
[86.767976] Subtest pipe-C-query-busy-hang: SUCCESS (15.605s)
[87.729552] [004/142] (928s left) kms_vblank (pipe-c-query-forked-busy-hang)
[88.127719] Starting subtest: pipe-C-query-forked-busy-hang
[103.830903] Subtest pipe-C-query-forked-busy-hang: SUCCESS (15.704s)
[104.768338] [005/142] (911s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[106.178412] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[114.819481] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (8.641s)
[116.225233] [006/142] (900s left) kms_flip (2x-flip-vs-rmfb)
[116.630959] Starting subtest: 2x-flip-vs-rmfb
[116.791265] Subtest 2x-flip-vs-rmfb: SKIP (0.160s)
[116.968646] [007/142] (899s left) kms_cursor_crc (cursor-offscreen-max-size)
[118.289756] Starting subtest: cursor-offscreen-max-size
[118.299539] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[118.539954] [008/142] (898s left) perf_pmu (busy-hang)
[118.942072] Starting subtest: busy-hang
[118.945819] Starting dynamic subtest: rcs0
[120.842044] Dynamic subtest rcs0: SUCCESS (1.900s)
[120.879674] Starting dynamic subtest: bcs0
[122.630518] Dynamic subtest bcs0: SUCCESS (1.787s)
[122.671642] Starting dynamic subtest: vcs0
[125.013534] Dynamic subtest vcs0: SUCCESS (2.381s)
[125.052627] Starting dynamic subtest: vcs1
[126.995332] Dynamic subtest vcs1: SUCCESS (1.981s)
[127.033381] Starting dynamic subtest: vecs0
[128.979977] Dynamic subtest vecs0: SUCCESS (1.983s)
[129.017248] Starting dynamic subtest: ccs0
[130.816741] Dynamic subtest ccs0: SUCCESS (1.835s)
[130.821139] Subtest busy-hang: SUCCESS (11.876s)
[131.000670] [009/142] (885s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[131.520382] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[131.624926] [010/142] (885s left) gem_exec_fair (basic-pace-share)
[132.028393] Subtest basic-pace-share: SKIP (0.000s)
[132.137833] [011/142] (884s left) syncobj_timeline (etime-single-wait-all-submitted)
[132.502325] Starting subtest: etime-single-wait-all-submitted
[132.612758] Subtest etime-single-wait-all-submitted: SUCCESS (0.110s)
[132.751133] [012/142] (884s left) kms_ccs (pipe-c-crc-sprite-planes-basic-yf_tiled_ccs)
[134.283522] Starting subtest: pipe-C-crc-sprite-planes-basic-yf_tiled_ccs
[134.295591] Subtest pipe-C-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.013s)
[135.098099] [013/142] (881s left) syncobj_timeline (invalid-transfer-bad-pad)
[135.475064] Starting subtest: invalid-transfer-bad-pad
[135.479159] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[135.609857] [014/142] (881s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_ccs)
[137.355346] Starting subtest: pipe-D-ccs-on-another-bo-y_tiled_ccs
[137.361613] Subtest pipe-D-ccs-on-another-bo-y_tiled_ccs: SKIP (0.000s)
[137.985635] [015/142] (879s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-25)
[138.465842] Starting subtest: plane-downscale-with-modifiers-factor-0-25
[138.473956] Starting dynamic subtest: pipe-A-eDP-1
[138.668164] Dynamic subtest pipe-A-eDP-1: SKIP (0.191s)
[138.711452] Starting dynamic subtest: pipe-B-eDP-1
[138.991493] Dynamic subtest pipe-B-eDP-1: SKIP (0.309s)
[139.029165] Starting dynamic subtest: pipe-C-eDP-1
[139.311154] Dynamic subtest pipe-C-eDP-1: SKIP (0.312s)
[139.348597] Starting dynamic subtest: pipe-D-eDP-1
[139.615148] Dynamic subtest pipe-D-eDP-1: SKIP (0.297s)
[139.621752] Subtest plane-downscale-with-modifiers-factor-0-25: SKIP (1.146s)
[139.804714] [016/142] (877s left) syncobj_wait (wait-for-submit-delayed-submit)
[140.181707] Starting subtest: wait-for-submit-delayed-submit
[140.284829] Subtest wait-for-submit-delayed-submit: SUCCESS (0.106s)
[140.435716] [017/142] (876s left) gem_workarounds (suspend-resume)
[140.887232] Starting subtest: suspend-resume
[157.377022] Subtest suspend-resume: SUCCESS (16.492s)
[157.570260] [018/142] (859s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[158.750421] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[158.757284] Subtest pipe-B-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[159.964579] [019/142] (857s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[160.465558] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[174.944997] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (14.482s)
[175.232520] [020/142] (842s left) gem_spin_batch (engines)
[175.661496] Starting subtest: engines
[175.663966] Starting dynamic subtest: rcs0
[178.902751] Dynamic subtest rcs0: SUCCESS (3.241s)
[178.945840] Starting dynamic subtest: bcs0
[182.136218] Dynamic subtest bcs0: SUCCESS (3.230s)
[182.179548] Starting dynamic subtest: vcs0
[185.467141] Dynamic subtest vcs0: SUCCESS (3.328s)
[185.511330] Starting dynamic subtest: vcs1
[188.698366] Dynamic subtest vcs1: SUCCESS (3.228s)
[188.740505] Starting dynamic subtest: vecs0
[192.028560] Dynamic subtest vecs0: SUCCESS (3.328s)
[192.069544] Starting dynamic subtest: ccs0
[195.261259] Dynamic subtest ccs0: SUCCESS (3.229s)
[195.264760] Subtest engines: SUCCESS (19.600s)
[195.566493] [021/142] (822s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[196.118547] Starting subtest: 4-tiled-32bpp-rotate-270
[196.181796] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.060s)
[196.367389] [022/142] (821s left) gem_readwrite (read-bad-handle)
[196.731392] Subtest read-bad-handle: SKIP (0.000s)
[196.857929] [023/142] (820s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[197.388315] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[199.705145] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (2.312s)
[200.031311] [024/142] (817s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[202.375172] Starting subtest: syncobj-timeline-multiple-ext-nodes
[202.378953] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.006s)
[202.712808] [025/142] (815s left) gem_ctx_param (basic-default)
[203.080417] Starting subtest: basic-default
[203.084042] Subtest basic-default: SUCCESS (0.000s)
[203.253600] [026/142] (814s left) gem_unfence_active_buffers
[203.838457] [027/142] (814s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_gen12_mc_ccs)
[205.149361] Starting subtest: pipe-B-bad-aux-stride-y_tiled_gen12_mc_ccs
[205.155605] Subtest pipe-B-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[206.264827] [028/142] (811s left) kms_cursor_crc (cursor-sliding-256x85)
[207.355678] Starting subtest: cursor-sliding-256x85
[207.389294] Starting dynamic subtest: pipe-A-eDP-1
[213.358892] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.968s)
[213.556872] Starting dynamic subtest: pipe-D-eDP-1
[220.511505] Dynamic subtest pipe-D-eDP-1: SUCCESS (7.002s)
[220.514507] Subtest cursor-sliding-256x85: SUCCESS (13.155s)
[221.481855] [029/142] (796s left) gem_render_copy_redux (flink)
[221.920495] Starting subtest: flink
[228.842645] Subtest flink: SUCCESS (6.923s)
[229.082133] [030/142] (789s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[229.601105] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[229.607727] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[229.769804] [031/142] (788s left) gem_tiled_partial_pwrite_pread (writes)
[230.154530] Subtest writes: SKIP (0.000s)
[230.273275] [032/142] (788s left) dumb_buffer (map-uaf)
[230.646427] Starting subtest: map-uaf
[230.668412] Subtest map-uaf: SUCCESS (0.023s)
[230.848558] [033/142] (787s left) gem_readwrite (beyond-eob)
[231.228522] Subtest beyond-EOB: SKIP (0.000s)
[231.347865] [034/142] (787s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[231.723849] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[231.827277] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[231.972550] [035/142] (786s left) v3d/v3d_wait_bo (unused-bo-1ns)
[232.437538] Subtest unused-bo-1ns: SKIP (0.000s)
[232.570932] [036/142] (786s left) kms_dp_aux_dev
[233.189789] [037/142] (785s left) prime_busy (after)
[233.571517] Starting subtest: after
[233.606786] Starting dynamic subtest: rcs0
[233.623754] Dynamic subtest rcs0: SUCCESS (0.021s)
[233.671629] Starting dynamic subtest: bcs0
[233.675303] Dynamic subtest bcs0: SUCCESS (0.048s)
[233.724975] Starting dynamic subtest: vcs0
[233.728931] Dynamic subtest vcs0: SUCCESS (0.044s)
[233.775107] Starting dynamic subtest: vcs1
[233.778829] Dynamic subtest vcs1: SUCCESS (0.030s)
[233.824526] Starting dynamic subtest: vecs0
[233.827561] Dynamic subtest vecs0: SUCCESS (0.030s)
[233.871378] Starting dynamic subtest: ccs0
[233.874380] Dynamic subtest ccs0: SUCCESS (0.026s)
[233.943889] Subtest after: SUCCESS (0.372s)
[234.093376] [038/142] (784s left) kms_async_flips (test-cursor)
[234.578694] Starting subtest: test-cursor
[234.585210] Subtest test-cursor: SKIP (0.001s)
[237.323131] [039/142] (781s left) gem_softpin (evict-active)
[237.710584] Starting subtest: evict-active
[237.742392] Subtest evict-active: SUCCESS (0.031s)
[237.923998] [040/142] (781s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[238.963320] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[238.967337] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[240.342395] [041/142] (778s left) i915_pm_backlight (fade)
[240.825278] Starting subtest: fade
[241.177918] Starting dynamic subtest: eDP-1
[243.994818] Dynamic subtest eDP-1: SUCCESS (2.819s)
[243.998763] Subtest fade: SUCCESS (3.168s)
[244.947742] [042/142] (774s left) kms_addfb_basic (invalid-set-prop-any)
[245.318429] Starting subtest: invalid-set-prop-any
[245.322536] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[245.585164] [043/142] (773s left) sw_sync (sync_busy)
[245.945709] Starting subtest: sync_busy
[245.954782] Subtest sync_busy: SUCCESS (0.010s)
[246.087888] [044/142] (773s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[246.570512] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[252.034475] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (5.473s)
[252.310109] [045/142] (767s left) kms_prime (basic-modeset-hybrid)
[256.195249] Subtest basic-modeset-hybrid: SKIP (0.000s)
[256.405627] [046/142] (763s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[258.239269] Starting subtest: pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[258.254875] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.013s)
[258.839279] [047/142] (761s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[259.338328] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[259.345366] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[259.506898] [048/142] (760s left) gem_exec_schedule (smoketest-all)
[259.935801] Starting subtest: smoketest-all
[292.786288] Subtest smoketest-all: SUCCESS (32.783s)
[293.650962] [049/142] (726s left) kms_plane_scaling (planes-upscale-20x20)
[294.149998] Starting subtest: planes-upscale-20x20
[294.160235] Starting dynamic subtest: pipe-A-eDP-1
[294.360700] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.204s)
[294.417373] Starting dynamic subtest: pipe-B-eDP-1
[295.889430] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.516s)
[295.944938] Starting dynamic subtest: pipe-C-eDP-1
[297.326910] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.426s)
[297.372571] Starting dynamic subtest: pipe-D-eDP-1
[298.734368] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.397s)
[298.737588] Subtest planes-upscale-20x20: SUCCESS (4.588s)
[300.191138] [050/142] (719s left) gem_exec_schedule (preempt-user)
[300.625352] Starting subtest: preempt-user
[300.630340] Starting dynamic subtest: rcs0
[300.747473] Dynamic subtest rcs0: SUCCESS (0.119s)
[300.794897] Starting dynamic subtest: bcs0
[300.872281] Dynamic subtest bcs0: SUCCESS (0.121s)
[300.933113] Starting dynamic subtest: vcs0
[301.002862] Dynamic subtest vcs0: SUCCESS (0.125s)
[301.052040] Starting dynamic subtest: vcs1
[301.125579] Dynamic subtest vcs1: SUCCESS (0.119s)
[301.173826] Starting dynamic subtest: vecs0
[301.249790] Dynamic subtest vecs0: SUCCESS (0.120s)
[301.299223] Starting dynamic subtest: ccs0
[301.382521] Dynamic subtest ccs0: SUCCESS (0.128s)
[301.386477] Subtest preempt-user: SUCCESS (0.759s)
[302.632313] [051/142] (717s left) kms_hdr (invalid-metadata-sizes)
[303.143897] Starting subtest: invalid-metadata-sizes
[303.151165] Subtest invalid-metadata-sizes: SKIP (0.001s)
[303.299165] [052/142] (716s left) kms_sequence (get-forked-busy)
[303.790206] Starting subtest: get-forked-busy
[303.799809] Starting dynamic subtest: eDP-1-pipe-A
[306.396509] Dynamic subtest eDP-1-pipe-A: SUCCESS (2.602s)
[306.451903] Starting dynamic subtest: eDP-1-pipe-B
[309.662233] Dynamic subtest eDP-1-pipe-B: SUCCESS (3.251s)
[309.719912] Starting dynamic subtest: eDP-1-pipe-C
[313.002838] Dynamic subtest eDP-1-pipe-C: SUCCESS (3.331s)
[313.057481] Starting dynamic subtest: eDP-1-pipe-D
[316.816526] Dynamic subtest eDP-1-pipe-D: SUCCESS (3.803s)
[316.819967] Subtest get-forked-busy: SUCCESS (13.026s)
[317.764105] [053/142] (702s left) gem_lmem_swapping (verify-random)
[318.162812] Subtest verify-random: SKIP (0.000s)
[318.285194] [054/142] (702s left) perf (unprivileged-single-ctx-counters)
[319.201048] Starting subtest: unprivileged-single-ctx-counters
[319.207839] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[319.315365] [055/142] (701s left) syncobj_wait (multi-wait-for-submit-signaled)
[319.693885] Starting subtest: multi-wait-for-submit-signaled
[319.697586] Subtest multi-wait-for-submit-signaled: SUCCESS (0.002s)
[319.848329] [056/142] (700s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[320.351695] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[320.358526] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[320.539728] [057/142] (700s left) v3d/v3d_submit_cl (valid-submission)
[321.010766] Subtest valid-submission: SKIP (0.000s)
[321.136558] [058/142] (699s left) gem_userptr_blits (stress-mm)
[321.597763] Starting subtest: stress-mm
[326.973566] Subtest stress-mm: SUCCESS (5.372s)
[327.243978] [059/142] (693s left) kms_setmode (basic-clone-single-crtc)
[327.632353] Starting subtest: basic-clone-single-crtc
[327.642345] Subtest basic-clone-single-crtc: SKIP (0.002s)
[327.805691] [060/142] (693s left) vc4/vc4_purgeable_bo (mark-unpurgeable-twice)
[328.307255] Subtest mark-unpurgeable-twice: SKIP (0.000s)
[328.449666] [061/142] (692s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[329.028424] Starting subtest: basic-flip-after-cursor-varying-size
[330.463891] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.433s)
[331.427783] [062/142] (689s left) kms_writeback (writeback-pixel-formats)
[331.997369] Subtest writeback-pixel-formats: SKIP (0.000s)
[332.116039] [063/142] (689s left) kms_vblank (pipe-c-query-forked-busy)
[332.591378] Starting subtest: pipe-C-query-forked-busy
[336.050328] Subtest pipe-C-query-forked-busy: SUCCESS (3.458s)
[337.018317] [064/142] (684s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[338.090343] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[338.105745] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.013s)
[339.393380] [065/142] (682s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[339.881521] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[339.888330] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[340.051224] [066/142] (681s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[340.549096] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[356.064924] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (15.523s)
[356.326788] [067/142] (665s left) kms_flip (nonblocking-read)
[356.718114] Starting subtest: nonblocking-read
[356.721511] Subtest nonblocking-read: SUCCESS (0.000s)
[356.923129] [068/142] (664s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[357.447329] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[357.454470] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.001s)
[357.631095] [069/142] (664s left) kms_lease (lease_unleased_connector)
[358.116411] Starting subtest: lease_unleased_connector
[358.120382] Starting dynamic subtest: pipe-A-eDP-1
[358.153146] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.030s)
[358.182722] Starting dynamic subtest: pipe-B-eDP-1
[358.185958] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[358.212862] Starting dynamic subtest: pipe-C-eDP-1
[358.229441] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.028s)
[358.251724] Starting dynamic subtest: pipe-D-eDP-1
[358.255688] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.028s)
[358.260151] Subtest lease_unleased_connector: SUCCESS (0.139s)
[358.408400] [070/142] (663s left) api_intel_bb (blit-noreloc-purge-cache)
[358.787814] Starting subtest: blit-noreloc-purge-cache
[358.825541] Subtest blit-noreloc-purge-cache: SUCCESS (0.042s)
[359.018106] [071/142] (663s left) gem_exec_reloc (basic-concurrent16)
[359.406135] Subtest basic-concurrent16: SKIP (0.000s)
[359.552368] [072/142] (662s left) kms_rotation_crc (cursor-rotation-180)
[360.033147] Starting subtest: cursor-rotation-180
[362.323346] Subtest cursor-rotation-180: SUCCESS (2.294s)
[363.873789] [073/142] (658s left) gem_ringfill (legacy-basic)
[369.512052] Starting subtest: legacy-basic
[369.514980] Starting dynamic subtest: default
[369.588264] Dynamic subtest default: SUCCESS (0.076s)
[369.641833] Starting dynamic subtest: render
[369.651427] Dynamic subtest render: SUCCESS (0.056s)
[369.698934] Starting dynamic subtest: bsd
[371.478762] Dynamic subtest bsd: SUCCESS (1.824s)
[371.525926] Starting dynamic subtest: bsd1
[373.462344] Dynamic subtest bsd1: SUCCESS (1.979s)
[373.509782] Starting dynamic subtest: bsd2
[375.510924] Dynamic subtest bsd2: SUCCESS (2.044s)
[375.564216] Starting dynamic subtest: blt
[375.573234] Dynamic subtest blt: SUCCESS (0.057s)
[375.620887] Starting dynamic subtest: vebox
[377.494577] Dynamic subtest vebox: SUCCESS (1.917s)
[377.498176] Subtest legacy-basic: SUCCESS (7.986s)
[377.680466] [074/142] (644s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_mc_ccs)
[379.288945] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs
[379.294760] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[380.096962] [075/142] (642s left) gem_exec_balancer (parallel-balancer)
[381.322262] Starting subtest: parallel-balancer
[384.365444] Subtest parallel-balancer: SUCCESS (3.044s)
[384.473954] [076/142] (638s left) kms_flip (flip-vs-absolute-wf_vblank)
[384.966404] Starting subtest: flip-vs-absolute-wf_vblank
[384.996709] Starting dynamic subtest: A-eDP1
[394.472981] Dynamic subtest A-eDP1: SUCCESS (9.476s)
[394.528465] Starting dynamic subtest: B-eDP1
[403.798130] Dynamic subtest B-eDP1: SUCCESS (9.319s)
[403.847563] Starting dynamic subtest: C-eDP1
[413.125506] Dynamic subtest C-eDP1: SUCCESS (9.322s)
[413.175450] Starting dynamic subtest: D-eDP1
[422.454822] Dynamic subtest D-eDP1: SUCCESS (9.323s)
[422.481416] Subtest flip-vs-absolute-wf_vblank: SUCCESS (37.517s)
[424.031421] [077/142] (598s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[424.528297] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[424.534763] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[424.699675] [078/142] (597s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[425.265697] Starting subtest: cursorA-vs-flipB-legacy
[425.277811] Subtest cursorA-vs-flipB-legacy: SKIP (0.008s)
[425.604273] [079/142] (597s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[425.973949] Starting subtest: invalid-multi-wait-all-unsubmitted
[425.977737] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[426.132357] [080/142] (596s left) gem_ccs (block-copy-uncompressed)
[426.529596] Starting subtest: block-copy-uncompressed
[426.538896] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[426.657655] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.122s)
[426.715528] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[426.770629] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.108s)
[426.823053] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[426.882878] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.108s)
[426.934946] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[426.994281] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.106s)
[426.997788] Subtest block-copy-uncompressed: SUCCESS (0.466s)
[427.182801] [081/142] (595s left) syncobj_basic (bad-pad-handle-to-fd)
[427.552363] Starting subtest: bad-pad-handle-to-fd
[427.556164] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[427.705196] [082/142] (595s left) kms_cursor_legacy (single-move)
[428.264534] Starting subtest: single-move
[428.268349] Starting dynamic subtest: pipe-A
[433.713693] Dynamic subtest pipe-A: SUCCESS (5.445s)
[433.771272] Starting dynamic subtest: pipe-B
[439.172299] Dynamic subtest pipe-B: SUCCESS (5.457s)
[439.231790] Starting dynamic subtest: pipe-C
[444.633882] Dynamic subtest pipe-C: SUCCESS (5.457s)
[444.686856] Starting dynamic subtest: pipe-D
[450.096217] Dynamic subtest pipe-D: SUCCESS (5.457s)
[450.151843] Starting dynamic subtest: all-pipes
[455.572102] Dynamic subtest all-pipes: SUCCESS (5.472s)
[455.575324] Subtest single-move: SUCCESS (27.311s)
[455.925952] [083/142] (567s left) gem_ctx_persistence (engines-cleanup)
[456.343628] Starting subtest: engines-cleanup
[456.348307] Starting dynamic subtest: rcs0
[456.387391] Dynamic subtest rcs0: SUCCESS (0.042s)
[456.449865] Starting dynamic subtest: bcs0
[456.462571] Dynamic subtest bcs0: SUCCESS (0.070s)
[456.514772] Starting dynamic subtest: vcs0
[456.518333] Dynamic subtest vcs0: SUCCESS (0.051s)
[456.567940] Starting dynamic subtest: vcs1
[456.580557] Dynamic subtest vcs1: SUCCESS (0.059s)
[456.625378] Starting dynamic subtest: vecs0
[456.651879] Dynamic subtest vecs0: SUCCESS (0.067s)
[456.689699] Starting dynamic subtest: ccs0
[456.709992] Dynamic subtest ccs0: SUCCESS (0.054s)
[456.713891] Subtest engines-cleanup: SUCCESS (0.369s)
[456.872136] [084/142] (566s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[457.354086] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[457.364681] Starting dynamic subtest: pipe-A-default-mode
[457.765498] Dynamic subtest pipe-A-default-mode: SKIP (0.394s)
[457.772464] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.408s)
[458.704950] [085/142] (564s left) kms_fbcon_fbt (psr-suspend)
[459.085457] Starting subtest: psr-suspend
[508.507268] Subtest psr-suspend: SUCCESS (49.422s)
[508.700120] [086/142] (514s left) core_hotunplug (unbind-rebind)
[509.133556] Starting subtest: unbind-rebind
[514.631987] Subtest unbind-rebind: SUCCESS (5.498s)
[514.717141] [087/142] (508s left) kms_lease (empty_lease)
[516.540481] Starting subtest: empty_lease
[516.550066] Starting dynamic subtest: pipe-A-eDP-1
[516.553559] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[516.600694] Starting dynamic subtest: pipe-B-eDP-1
[516.604341] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[516.657168] Starting dynamic subtest: pipe-C-eDP-1
[516.661044] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[516.710993] Starting dynamic subtest: pipe-D-eDP-1
[516.714165] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.003s)
[516.717188] Subtest empty_lease: SUCCESS (0.044s)
[516.787663] [088/142] (506s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[517.272544] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[517.282112] Starting dynamic subtest: pipe-A-default-mode
[519.611426] Dynamic subtest pipe-A-default-mode: SKIP (2.321s)
[519.618707] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (2.334s)
[519.891712] [089/142] (503s left) i915_suspend (basic-s2idle-without-i915)
[520.247903] Starting subtest: basic-s2idle-without-i915
[542.723228] Subtest basic-s2idle-without-i915: SUCCESS (22.470s)
[542.816087] [090/142] (480s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[543.237311] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[543.244203] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[543.405620] [091/142] (480s left) kms_frontbuffer_tracking (psr-1p-rte)
[543.915769] Starting subtest: psr-1p-rte
[548.351569] Subtest psr-1p-rte: SUCCESS (4.443s)
[548.636965] [092/142] (475s left) gem_exec_reloc (basic-gtt-cpu-active)
[549.055120] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[549.191909] [093/142] (474s left) sw_sync (alloc_timeline)
[549.553274] Starting subtest: alloc_timeline
[549.556794] Subtest alloc_timeline: SUCCESS (0.001s)
[549.687106] [094/142] (474s left) gem_tiled_pread_pwrite
[550.185771] [095/142] (474s left) syncobj_timeline (wait-all-complex)
[550.564984] Starting subtest: wait-all-complex
[550.785279] Subtest wait-all-complex: SUCCESS (0.219s)
[550.932181] [096/142] (473s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[551.341859] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[551.348141] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[551.616845] [097/142] (472s left) gem_exec_params (secure-non-root)
[552.006069] Starting subtest: secure-non-root
[552.012367] Subtest secure-non-root: SKIP (0.000s)
[552.211170] [098/142] (472s left) kms_ccs (pipe-d-random-ccs-data-yf_tiled_ccs)
[553.940210] Starting subtest: pipe-D-random-ccs-data-yf_tiled_ccs
[553.949226] Subtest pipe-D-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[554.639383] [099/142] (469s left) api_intel_allocator (alloc-simple)
[555.012735] Starting subtest: alloc-simple
[555.023518] Subtest alloc-simple: SUCCESS (0.011s)
[555.208096] [100/142] (469s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[555.746155] Starting subtest: x-tiled-64bpp-rotate-180
[562.389792] Subtest x-tiled-64bpp-rotate-180: SUCCESS (6.647s)
[563.120126] [101/142] (461s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[563.636154] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[563.644417] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[563.911967] [102/142] (460s left) gem_exec_schedule (u-lateslice)
[564.357726] Starting subtest: u-lateslice
[564.361218] Starting dynamic subtest: rcs0
[564.421387] Dynamic subtest rcs0: SUCCESS (0.062s)
[564.474136] Starting dynamic subtest: bcs0
[564.477777] Dynamic subtest bcs0: SUCCESS (0.046s)
[564.522860] Starting dynamic subtest: vcs0
[564.526699] Dynamic subtest vcs0: SUCCESS (0.047s)
[564.568067] Starting dynamic subtest: vcs1
[564.571389] Dynamic subtest vcs1: SUCCESS (0.044s)
[564.618151] Starting dynamic subtest: vecs0
[564.621534] Dynamic subtest vecs0: SUCCESS (0.046s)
[564.672177] Starting dynamic subtest: ccs0
[564.675801] Dynamic subtest ccs0: SUCCESS (0.044s)
[564.679625] Subtest u-lateslice: SUCCESS (0.314s)
[566.629665] [103/142] (458s left) gem_exec_async (concurrent-writes)
[567.050978] Starting subtest: concurrent-writes
[567.054278] Starting dynamic subtest: rcs0
[567.106539] Dynamic subtest rcs0: SUCCESS (0.054s)
[567.170200] Starting dynamic subtest: bcs0
[567.173284] Dynamic subtest bcs0: SUCCESS (0.044s)
[567.228193] Starting dynamic subtest: vcs0
[567.231856] Dynamic subtest vcs0: SUCCESS (0.038s)
[567.280192] Starting dynamic subtest: vcs1
[567.284113] Dynamic subtest vcs1: SUCCESS (0.040s)
[567.327089] Starting dynamic subtest: vecs0
[567.330221] Dynamic subtest vecs0: SUCCESS (0.033s)
[567.373326] Starting dynamic subtest: ccs0
[567.376408] Dynamic subtest ccs0: SUCCESS (0.032s)
[567.379363] Subtest concurrent-writes: SUCCESS (0.269s)
[568.610453] [104/142] (456s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[569.127219] Starting subtest: fbc-rgb101010-draw-mmap-wc
[574.453953] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (5.332s)
[574.719287] [105/142] (450s left) kms_plane_alpha_blend (coverage-7efc)
[575.204510] Starting subtest: coverage-7efc
[576.985775] Starting dynamic subtest: pipe-A-eDP-1
[578.171430] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.184s)
[578.352961] Starting dynamic subtest: pipe-D-eDP-1
[580.611640] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.301s)
[580.615268] Subtest coverage-7efc: SUCCESS (5.412s)
[581.564338] [106/142] (443s left) gen7_exec_parse (basic-offset)
[581.967185] Subtest basic-offset: SKIP (0.000s)
[582.099250] [107/142] (443s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[582.626478] Starting subtest: x-tiled-16bpp-rotate-270
[582.694679] Subtest x-tiled-16bpp-rotate-270: SKIP (0.059s)
[582.876490] [108/142] (442s left) v3d/v3d_submit_cl (bad-extension)
[583.366845] Subtest bad-extension: SKIP (0.000s)
[583.502598] [109/142] (441s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[584.534782] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc
[584.539186] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.001s)
[585.291482] [110/142] (440s left) kms_psr (sprite_plane_move)
[585.780902] Starting subtest: sprite_plane_move
[588.016991] Subtest sprite_plane_move: SUCCESS (2.227s)
[589.005681] [111/142] (436s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[589.492386] Starting subtest: pipe-A-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[589.498747] Subtest pipe-A-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[591.405762] [112/142] (434s left) vc4/vc4_wait_bo (used-bo)
[591.869901] Subtest used-bo: SKIP (0.000s)
[592.004700] [113/142] (433s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[592.887556] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[593.756247] [114/142] (432s left) gen9_exec_parse (batch-invalid-length)
[594.152604] Subtest batch-invalid-length: SKIP (0.000s)
[594.270576] [115/142] (431s left) i915_pm_rpm (modeset-non-lpsp)
[596.706524] Starting subtest: modeset-non-lpsp
[600.354149] Subtest modeset-non-lpsp: SKIP (3.643s)
[601.287793] [116/142] (424s left) gem_mmap_wc (pf-nonblock)
[601.660445] Subtest pf-nonblock: SKIP (0.000s)
[601.774808] [117/142] (424s left) v3d/v3d_create_bo (create-bo-zeroed)
[602.231312] Subtest create-bo-zeroed: SKIP (0.000s)
[602.362595] [118/142] (423s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[602.871322] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[613.518717] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (10.648s)
[613.789521] [119/142] (412s left) sw_sync (sync_multi_consumer_producer)
[614.163318] Starting subtest: sync_multi_consumer_producer
[615.376322] Subtest sync_multi_consumer_producer: SUCCESS (1.213s)
[615.514552] [120/142] (410s left) gem_exec_whisper (basic-sync)
[615.939543] Starting subtest: basic-sync
[633.498329] Subtest basic-sync: SUCCESS (17.554s)
[633.804913] [121/142] (392s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_mc_ccs)
[634.956407] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_mc_ccs
[634.962928] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[636.208675] [122/142] (390s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[636.579377] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[636.699474] [123/142] (389s left) gem_mmap_gtt (big-copy)
[637.073796] Subtest big-copy: SKIP (0.000s)
[637.197652] [124/142] (389s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[637.699701] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[637.706697] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[637.869615] [125/142] (388s left) gem_pwrite (basic-exhaustion)
[638.262553] Subtest basic-exhaustion: SKIP (0.000s)
[638.394253] [126/142] (388s left) i915_pm_lpsp (kms-lpsp)
[639.874898] Starting subtest: kms-lpsp
[639.878576] Starting dynamic subtest: kms-lpsp-eDP
[640.385624] Dynamic subtest kms-lpsp-eDP: SUCCESS (0.508s)
[640.779363] Subtest kms-lpsp: SUCCESS (0.903s)
[641.734727] [127/142] (385s left) drm_fdinfo (busy-check-all)
[642.125424] Starting subtest: busy-check-all
[642.128969] Starting dynamic subtest: rcs0
[642.141856] Dynamic subtest rcs0: SKIP (0.011s)
[642.189648] Starting dynamic subtest: bcs0
[642.196485] Dynamic subtest bcs0: SKIP (0.003s)
[642.241855] Starting dynamic subtest: vcs0
[642.248578] Dynamic subtest vcs0: SKIP (0.003s)
[642.292826] Starting dynamic subtest: vcs1
[642.299341] Dynamic subtest vcs1: SKIP (0.003s)
[642.343234] Starting dynamic subtest: vecs0
[642.349677] Dynamic subtest vecs0: SKIP (0.003s)
[642.393457] Starting dynamic subtest: ccs0
[642.399941] Dynamic subtest ccs0: SKIP (0.003s)
[642.406191] Subtest busy-check-all: SKIP (0.048s)
[642.469936] [128/142] (384s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[642.859502] Starting subtest: multi-wait-for-submit-available-signaled
[642.863585] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.004s)
[643.010478] [129/142] (384s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[643.509598] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[658.502124] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (14.998s)
[658.783598] [130/142] (368s left) gem_exec_whisper (basic-forked)
[659.212154] Starting subtest: basic-forked
[681.249444] Subtest basic-forked: FAIL (21.965s)
[681.636469] [131/142] (345s left) gem_ctx_freq (sysfs)
[682.051419] Starting subtest: sysfs
[682.054903] Starting dynamic subtest: gt0
[687.749830] Dynamic subtest gt0: SUCCESS (5.696s)
[687.810199] Starting dynamic subtest: gt1
[729.496200] Dynamic subtest gt1: SUCCESS (41.741s)
[729.499797] Subtest sysfs: SUCCESS (47.446s)
[729.684635] [132/142] (297s left) kms_content_protection (mei_interface)
[730.380416] Starting subtest: mei_interface
[730.392377] Subtest mei_interface: SKIP (0.005s)
[730.577924] [133/142] (296s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[730.992452] Starting subtest: yf-tiled-32bpp-rotate-0
[730.999688] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[731.149583] [134/142] (296s left) kms_psr (psr2_basic)
[731.631825] Starting subtest: psr2_basic
[734.556151] Subtest psr2_basic: SUCCESS (2.918s)
[735.508627] [135/142] (292s left) perf_pmu (interrupts)
[735.893476] Starting subtest: interrupts
[737.153790] Subtest interrupts: SUCCESS (1.258s)
[737.320803] [136/142] (290s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs)
[738.676189] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs
[739.379224] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SUCCESS (0.699s)
[740.758468] [137/142] (287s left) gem_exec_reloc (basic-write-wc)
[741.162368] Subtest basic-write-wc: SKIP (0.000s)
[741.290491] [138/142] (286s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[742.239820] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[742.245755] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.002s)
[743.697656] [139/142] (284s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[744.563148] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[745.437497] [140/142] (282s left) kms_color (ctm-green-to-red)
[745.921799] Starting subtest: ctm-green-to-red
[745.925335] Starting dynamic subtest: pipe-A
[747.209294] Dynamic subtest pipe-A: SUCCESS (1.283s)
[747.254966] Starting dynamic subtest: pipe-B
[749.136424] Dynamic subtest pipe-B: SUCCESS (1.920s)
[749.182508] Starting dynamic subtest: pipe-C
[751.404307] Dynamic subtest pipe-C: SUCCESS (2.265s)
[751.451858] Starting dynamic subtest: pipe-D
[754.179736] Dynamic subtest pipe-D: SUCCESS (2.771s)
[754.183438] Subtest ctm-green-to-red: SUCCESS (8.257s)
[755.136333] [141/142] (273s left) gem_eio (context-create)
[756.653665] Starting subtest: context-create
[758.871919] Subtest context-create: SUCCESS (2.216s)
[760.593902] [142/142] (267s left) kms_fbcon_fbt (fbc-suspend)
[760.991782] Starting subtest: fbc-suspend
[811.619485] Subtest fbc-suspend: SUCCESS (50.623s)
[811.843202] Closing watchdogs
Done.