[62.063582] Initializing watchdogs
[62.063916]   /dev/watchdog0
[62.079246] [001/137] (960s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[62.302460] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[62.304250] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[62.393430] [002/137] (960s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_mc_ccs)
[62.954572] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs
[62.957537] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[63.471956] [003/137] (959s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[63.682207] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[63.683890] Subtest invalid-multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[63.755440] [004/137] (958s left) vc4/vc4_wait_bo (bad-pad)
[64.023866] Subtest bad-pad: SKIP (0.000s)
[64.087486] [005/137] (958s left) syncobj_timeline (multi-wait-all-signaled)
[64.290432] Starting subtest: multi-wait-all-signaled
[64.294821] Subtest multi-wait-all-signaled: SUCCESS (0.002s)
[64.370982] [006/137] (958s left) kms_concurrent (pipe-d)
[64.679454] Subtest pipe-D: SKIP (0.000s)
[64.755291] [007/137] (958s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[65.390393] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[65.393279] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[65.791648] [008/137] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[66.110285] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[66.112809] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[66.208941] [009/137] (956s left) gem_exec_gttfill (all-engines)
[66.441492] Starting subtest: all-engines
[107.950257] Subtest all-engines: SUCCESS (41.507s)
[108.149377] [010/137] (914s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[108.622488] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[108.626939] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.001s)
[108.781086] [011/137] (914s left) gem_ringfill (legacy-basic)
[109.848488] Starting subtest: legacy-basic
[109.849974] Starting dynamic subtest: default
[109.878653] Dynamic subtest default: SUCCESS (0.032s)
[109.897110] Starting dynamic subtest: render
[109.901192] Dynamic subtest render: SUCCESS (0.022s)
[109.918760] Starting dynamic subtest: bsd
[109.925815] Dynamic subtest bsd: SUCCESS (0.024s)
[109.945259] Starting dynamic subtest: bsd1
[109.949400] Dynamic subtest bsd1: SUCCESS (0.023s)
[109.966674] Starting dynamic subtest: blt
[109.973839] Dynamic subtest blt: SUCCESS (0.023s)
[109.991138] Starting dynamic subtest: vebox
[109.999266] Dynamic subtest vebox: SUCCESS (0.025s)
[110.001175] Subtest legacy-basic: SUCCESS (0.153s)
[110.078109] [012/137] (912s left) gem_exec_capture (pi)
[110.292500] Starting subtest: pi
[110.294900] Starting dynamic subtest: rcs0
[139.566425] Dynamic subtest rcs0: SUCCESS (29.273s)
[139.582581] Starting dynamic subtest: bcs0
[168.956569] Dynamic subtest bcs0: SUCCESS (29.389s)
[168.973386] Starting dynamic subtest: vcs0
[198.961181] Dynamic subtest vcs0: SUCCESS (30.003s)
[198.978281] Starting dynamic subtest: vecs0
[227.930313] Dynamic subtest vecs0: SUCCESS (28.969s)
[227.932383] Subtest pi: SUCCESS (117.636s)
[228.014730] [013/137] (794s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[228.462392] Starting subtest: cursor-rapid-movement-128x128
[228.474580] Starting dynamic subtest: pipe-A-DP-1
[229.211312] Dynamic subtest pipe-A-DP-1: SUCCESS (0.736s)
[229.293309] Starting dynamic subtest: pipe-C-DP-1
[230.141871] Dynamic subtest pipe-C-DP-1: SUCCESS (0.867s)
[230.143282] Subtest cursor-rapid-movement-128x128: SUCCESS (1.681s)
[230.504335] [014/137] (792s left) kms_cursor_crc (cursor-sliding-max-size)
[230.822597] Starting subtest: cursor-sliding-max-size
[230.826970] Subtest cursor-sliding-max-size: SKIP (0.000s)
[230.917797] [015/137] (792s left) gem_mmap_gtt (fault-concurrent-y)
[231.121202] Starting subtest: fault-concurrent-Y
[234.549100] Subtest fault-concurrent-Y: SUCCESS (3.427s)
[234.916673] [016/137] (788s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[235.628084] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[235.937574] [017/137] (787s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[236.254323] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[236.256954] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[236.354309] [018/137] (786s left) gem_exec_schedule (pi-distinct-iova)
[236.739701] Starting subtest: pi-distinct-iova
[236.741461] Starting dynamic subtest: rcs0
[236.813885] Dynamic subtest rcs0: SUCCESS (0.076s)
[236.837411] Starting dynamic subtest: bcs0
[236.883770] Dynamic subtest bcs0: SUCCESS (0.070s)
[236.907482] Starting dynamic subtest: vcs0
[236.935772] Dynamic subtest vcs0: SUCCESS (0.052s)
[236.959305] Starting dynamic subtest: vecs0
[236.991880] Dynamic subtest vecs0: SUCCESS (0.057s)
[236.994565] Subtest pi-distinct-iova: SUCCESS (0.256s)
[237.205562] [019/137] (785s left) vc4/vc4_mmap (mmap-bo)
[237.469425] Subtest mmap-bo: SKIP (0.000s)
[237.538751] [020/137] (785s left) kms_getfb (getfb2-handle-not-fb)
[237.735319] Starting subtest: getfb2-handle-not-fb
[237.834658] Subtest getfb2-handle-not-fb: SUCCESS (0.096s)
[237.921605] [021/137] (785s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[238.235211] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[238.238097] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[238.339304] [022/137] (784s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[238.649182] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[238.651778] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[238.754645] [023/137] (784s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[239.064410] Starting subtest: primary-yf-tiled-reflect-x-90
[239.067732] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.003s)
[239.138509] [024/137] (784s left) gem_exec_balancer (bonded-true-hang)
[243.173328] Starting subtest: bonded-true-hang
[246.734096] Subtest bonded-true-hang: SUCCESS (3.561s)
[246.822848] [025/137] (776s left) kms_async_flips (alternate-sync-async-flip)
[247.120576] Starting subtest: alternate-sync-async-flip
[247.122120] Starting dynamic subtest: pipe-A-DP-1
[249.986676] Dynamic subtest pipe-A-DP-1: SUCCESS (2.865s)
[250.003483] Starting dynamic subtest: pipe-B-DP-1
[252.716381] Dynamic subtest pipe-B-DP-1: SUCCESS (2.726s)
[252.733144] Starting dynamic subtest: pipe-C-DP-1
[255.338812] Dynamic subtest pipe-C-DP-1: SUCCESS (2.619s)
[255.340819] Subtest alternate-sync-async-flip: SUCCESS (8.219s)
[255.880510] [026/137] (767s left) kms_chamelium_hpd (vga-hpd)
[256.281265] Subtest vga-hpd: SKIP (0.000s)
[256.564889] [027/137] (766s left) kms_plane_alpha_blend (constant-alpha-mid)
[256.866842] Starting subtest: constant-alpha-mid
[257.173402] Starting dynamic subtest: pipe-A-DP-1
[257.871746] Dynamic subtest pipe-A-DP-1: SUCCESS (0.696s)
[257.895494] Starting dynamic subtest: pipe-C-DP-1
[258.803376] Dynamic subtest pipe-C-DP-1: SUCCESS (0.928s)
[258.805724] Subtest constant-alpha-mid: SUCCESS (1.938s)
[259.166311] [028/137] (764s left) gem_spin_batch (spin-all)
[259.388196] Starting subtest: spin-all
[259.415689] Subtest spin-all: SUCCESS (0.028s)
[259.644587] [029/137] (763s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[260.041611] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[260.322319] [030/137] (763s left) gem_exec_whisper (basic-queues-priority)
[260.545528] Starting subtest: basic-queues-priority
[272.766960] Subtest basic-queues-priority: SUCCESS (12.219s)
[272.969119] [031/137] (750s left) kms_psr2_sf (cursor-plane-update-sf)
[273.168419] Subtest cursor-plane-update-sf: SKIP (0.000s)
[273.217704] [032/137] (750s left) kms_plane_multiple (tiling-x)
[273.514928] Starting subtest: tiling-x
[273.516518] Starting dynamic subtest: pipe-A-DP-1
[275.030106] Dynamic subtest pipe-A-DP-1: SUCCESS (1.515s)
[275.051141] Starting dynamic subtest: pipe-B-DP-1
[276.704426] Dynamic subtest pipe-B-DP-1: SUCCESS (1.669s)
[276.724153] Starting dynamic subtest: pipe-C-DP-1
[278.352927] Dynamic subtest pipe-C-DP-1: SUCCESS (1.644s)
[278.354352] Subtest tiling-x: SUCCESS (4.839s)
[278.693948] [033/137] (744s left) gem_pxp (create-regular-context-2)
[281.417068] Subtest create-regular-context-2: SKIP (0.000s)
[281.494073] [034/137] (742s left) gem_exec_params (sol-reset-invalid)
[281.700119] Starting subtest: sol-reset-invalid
[281.705551] Subtest sol-reset-invalid: SUCCESS (0.003s)
[281.809919] [035/137] (741s left) perf (global-sseu-config)
[282.142265] Starting subtest: global-sseu-config
[282.143722] Starting dynamic subtest: 0-rcs0
[282.261532] Dynamic subtest 0-rcs0: SUCCESS (0.120s)
[282.263044] Subtest global-sseu-config: SUCCESS (0.121s)
[282.312702] [036/137] (741s left) kms_flip (2x-busy-flip)
[282.599665] Starting subtest: 2x-busy-flip
[282.602471] Subtest 2x-busy-flip: SKIP (0.003s)
[282.710895] [037/137] (741s left) kms_vblank (pipe-d-ts-continuation-idle)
[283.012981] Subtest pipe-D-ts-continuation-idle: SKIP (0.000s)
[283.094637] [038/137] (740s left) drm_fdinfo (idle)
[283.301662] Starting subtest: idle
[283.303421] Starting dynamic subtest: rcs0
[283.809231] Dynamic subtest rcs0: SUCCESS (0.505s)
[283.827681] Starting dynamic subtest: bcs0
[284.315658] Dynamic subtest bcs0: SUCCESS (0.504s)
[284.334095] Starting dynamic subtest: vcs0
[284.819140] Dynamic subtest vcs0: SUCCESS (0.503s)
[284.837654] Starting dynamic subtest: vecs0
[285.321686] Dynamic subtest vecs0: SUCCESS (0.504s)
[285.323120] Subtest idle: SUCCESS (2.018s)
[285.411456] [039/137] (738s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[286.109121] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[286.114379] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.004s)
[286.435184] [040/137] (737s left) perf (rc6-disable)
[286.732247] Starting subtest: rc6-disable
[286.962459] Subtest rc6-disable: SUCCESS (0.230s)
[287.019520] [041/137] (736s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs)
[287.725810] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[288.041858] [042/137] (735s left) kms_dp_aux_dev
[288.775148] [043/137] (735s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[289.108841] Starting subtest: y-tiled-64bpp-rotate-180
[297.570918] Subtest y-tiled-64bpp-rotate-180: SUCCESS (8.460s)
[298.493414] [044/137] (725s left) gem_flink_basic (double-flink)
[298.679586] Starting subtest: double-flink
[298.681163] Subtest double-flink: SUCCESS (0.000s)
[298.775837] [045/137] (725s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[299.085479] Starting subtest: x-tiled-8bpp-rotate-270
[299.176693] Subtest x-tiled-8bpp-rotate-270: SKIP (0.089s)
[299.445930] [046/137] (724s left) kms_vblank (pipe-b-query-idle-hang)
[299.748237] Starting subtest: pipe-B-query-idle-hang
[306.987238] Subtest pipe-B-query-idle-hang: SUCCESS (7.243s)
[307.326458] [047/137] (716s left) v3d/v3d_job_submission (array-job-submission)
[307.588464] Subtest array-job-submission: SKIP (0.000s)
[307.660438] [048/137] (716s left) gem_exec_params (secure-non-master)
[307.865471] Starting subtest: secure-non-master
[307.868752] Subtest secure-non-master: SKIP (0.000s)
[307.977157] [049/137] (716s left) perf_pmu (most-busy-idle-check-all)
[308.183916] Starting subtest: most-busy-idle-check-all
[308.185565] Starting dynamic subtest: rcs0
[308.811974] Dynamic subtest rcs0: SUCCESS (0.629s)
[308.835703] Starting dynamic subtest: bcs0
[309.435703] Dynamic subtest bcs0: SUCCESS (0.623s)
[309.457497] Starting dynamic subtest: vcs0
[310.060676] Dynamic subtest vcs0: SUCCESS (0.624s)
[310.080502] Starting dynamic subtest: vecs0
[310.689246] Dynamic subtest vecs0: SUCCESS (0.627s)
[310.690821] Subtest most-busy-idle-check-all: SUCCESS (2.507s)
[310.778842] [050/137] (713s left) kms_rotation_crc (primary-rotation-180)
[311.081477] Starting subtest: primary-rotation-180
[315.456889] Subtest primary-rotation-180: SUCCESS (4.377s)
[315.863661] [051/137] (708s left) i915_pm_rpm (pc8-residency)
[317.761479] Starting subtest: pc8-residency
[317.766756] Subtest pc8-residency: SKIP (0.000s)
[318.234352] [052/137] (706s left) gem_mmap_gtt (fault-concurrent)
[318.435373] Starting subtest: fault-concurrent
[321.707569] Subtest fault-concurrent: SUCCESS (3.270s)
[321.982526] [053/137] (702s left) gem_exec_params (dr4-dirt)
[322.188175] Starting subtest: DR4-dirt
[322.190318] Subtest DR4-dirt: SUCCESS (0.001s)
[322.282473] [054/137] (702s left) sw_sync (sync_merge_invalid)
[322.478902] Starting subtest: sync_merge_invalid
[322.486321] Subtest sync_merge_invalid: SUCCESS (0.005s)
[322.532851] [055/137] (701s left) kms_flip (2x-modeset-vs-vblank-race)
[322.810900] Starting subtest: 2x-modeset-vs-vblank-race
[322.813779] Subtest 2x-modeset-vs-vblank-race: SKIP (0.003s)
[322.918068] [056/137] (701s left) gem_userptr_blits (create-destroy-unsync)
[323.130291] Subtest create-destroy-unsync: SKIP (0.000s)
[323.283788] [057/137] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[323.587599] Starting subtest: fbcpsr-2p-rte
[323.590372] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[323.682932] [058/137] (700s left) api_intel_bb (render)
[323.883850] Starting subtest: render
[323.885509] Starting dynamic subtest: render-none-512
[323.995330] Dynamic subtest render-none-512: SUCCESS (0.110s)
[324.017689] Starting dynamic subtest: render-none-reloc-512
[324.103257] Dynamic subtest render-none-reloc-512: SUCCESS (0.107s)
[324.125257] Starting dynamic subtest: render-none-1024
[324.471871] Dynamic subtest render-none-1024: SUCCESS (0.368s)
[324.492059] Starting dynamic subtest: render-none-reloc-1024
[324.838441] Dynamic subtest render-none-reloc-1024: SUCCESS (0.366s)
[324.858455] Starting dynamic subtest: render-x-512
[324.939063] Dynamic subtest render-x-512: SUCCESS (0.100s)
[324.960149] Starting dynamic subtest: render-x-reloc-512
[325.031456] Dynamic subtest render-x-reloc-512: SUCCESS (0.091s)
[325.051379] Starting dynamic subtest: render-x-1024
[325.338821] Dynamic subtest render-x-1024: SUCCESS (0.306s)
[325.360272] Starting dynamic subtest: render-x-reloc-1024
[325.654972] Dynamic subtest render-x-reloc-1024: SUCCESS (0.317s)
[325.674312] Starting dynamic subtest: render-y-512
[325.759408] Dynamic subtest render-y-512: SUCCESS (0.103s)
[325.778714] Starting dynamic subtest: render-y-reloc-512
[325.849446] Dynamic subtest render-y-reloc-512: SUCCESS (0.090s)
[325.869485] Starting dynamic subtest: render-y-1024
[326.153595] Dynamic subtest render-y-1024: SUCCESS (0.305s)
[326.174351] Starting dynamic subtest: render-y-reloc-1024
[326.470638] Dynamic subtest render-y-reloc-1024: SUCCESS (0.317s)
[326.471961] Subtest render: SUCCESS (2.587s)
[326.583273] [059/137] (698s left) gem_close_race (basic-process)
[326.809052] Starting subtest: basic-process
[326.898761] Subtest basic-process: SUCCESS (0.089s)
[327.099923] [060/137] (697s left) template (a)
[327.299300] Starting subtest: A
[327.301024] Subtest A: SUCCESS (0.000s)
[327.382843] [061/137] (697s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[327.582420] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[327.584225] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[327.666573] [062/137] (697s left) kms_atomic_interruptible (legacy-cursor)
[327.976075] Starting subtest: legacy-cursor
[327.979884] Starting dynamic subtest: DP-1-pipe-A
[334.520754] Dynamic subtest DP-1-pipe-A: SUCCESS (6.542s)
[334.522520] Subtest legacy-cursor: SUCCESS (6.546s)
[334.832207] [063/137] (690s left) gem_softpin (noreloc-interruptible)
[335.037188] Starting subtest: noreloc-interruptible
[340.604798] Subtest noreloc-interruptible: SUCCESS (5.566s)
[340.730437] [064/137] (684s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[341.032426] Starting subtest: pipe-A-wait-forked-busy-hang
[348.003659] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (6.973s)
[348.339059] [065/137] (676s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[348.648397] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[350.953601] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (2.306s)
[351.089159] [066/137] (673s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[351.505902] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[351.508913] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[352.113754] [067/137] (672s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[352.529572] Starting subtest: plane-primary-overlay-mutable-zpos
[352.537905] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.000s)
[352.955928] [068/137] (672s left) kms_chamelium_color (gamma)
[353.255722] Subtest gamma: SKIP (0.000s)
[353.303600] [069/137] (671s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_ccs)
[353.593656] Starting subtest: pipe-A-bad-pixel-format-y_tiled_ccs
[353.595198] Subtest pipe-A-bad-pixel-format-y_tiled_ccs: SUCCESS (0.001s)
[354.314859] [070/137] (670s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[354.617313] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[354.619935] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[354.714762] [071/137] (670s left) i915_pm_backlight (bad-brightness)
[355.013283] Subtest bad-brightness: SKIP (0.000s)
[355.063491] [072/137] (670s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[357.776916] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s)
[357.864459] [073/137] (667s left) gem_render_copy (linear)
[358.081639] Starting subtest: linear
[358.083131] Starting dynamic subtest: smem
[358.337616] Dynamic subtest smem: SUCCESS (0.254s)
[358.338970] Subtest linear: SUCCESS (0.255s)
[358.535437] [074/137] (666s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[358.847640] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[358.850479] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[358.947781] [075/137] (666s left) syncobj_timeline (wait-all-delayed-signal)
[359.151616] Starting subtest: wait-all-delayed-signal
[359.257136] Subtest wait-all-delayed-signal: SUCCESS (0.104s)
[359.349357] [076/137] (665s left) gem_mmap_wc (bad-object)
[359.552073] Starting subtest: bad-object
[359.553698] Subtest bad-object: SUCCESS (0.000s)
[359.664427] [077/137] (665s left) gem_ccs (ctrl-surf-copy)
[359.872896] Subtest ctrl-surf-copy: SKIP (0.000s)
[359.932072] [078/137] (665s left) syncobj_wait (multi-wait-all-submitted-signaled)
[360.132354] Starting subtest: multi-wait-all-submitted-signaled
[360.236926] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.102s)
[360.332187] [079/137] (665s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[360.641957] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[360.645077] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[360.747688] [080/137] (664s left) kms_psr (sprite_plane_onoff)
[360.948484] Subtest sprite_plane_onoff: SKIP (0.000s)
[360.997185] [081/137] (664s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[361.297181] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[361.298898] Starting dynamic subtest: pipe-A-DP-1
[361.620329] Dynamic subtest pipe-A-DP-1: SUCCESS (0.321s)
[361.646798] Starting dynamic subtest: pipe-B-DP-1
[361.990423] Dynamic subtest pipe-B-DP-1: SUCCESS (0.369s)
[362.014063] Starting dynamic subtest: pipe-C-DP-1
[362.082190] Dynamic subtest pipe-C-DP-1: SKIP (0.085s)
[362.083941] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.788s)
[362.487845] [082/137] (663s left) perf_pmu (invalid-init)
[362.691641] Starting subtest: invalid-init
[362.693333] Subtest invalid-init: SUCCESS (0.001s)
[362.787157] [083/137] (662s left) i915_pm_dc (dc6-psr)
[364.086943] Starting subtest: dc6-psr
[364.091291] Subtest dc6-psr: SKIP (0.000s)
[364.253991] [084/137] (661s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[364.564050] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[364.566724] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[364.670372] [085/137] (661s left) syncobj_wait (wait-for-submit-delayed-submit)
[364.871204] Starting subtest: wait-for-submit-delayed-submit
[364.976135] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[365.070557] [086/137] (660s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[365.278103] Starting subtest: writes-after-reads-snoop
[385.569512] Subtest writes-after-reads-snoop: SUCCESS (20.292s)
[385.688843] [087/137] (640s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[386.004636] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[386.007412] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[386.105065] [088/137] (639s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[386.413955] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[388.296945] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (1.888s)
[388.454171] [089/137] (637s left) vc4/vc4_create_bo (create-bo-4096)
[388.714978] Subtest create-bo-4096: SKIP (0.000s)
[388.786753] [090/137] (637s left) gem_exec_balancer (smoke)
[389.006577] Starting subtest: smoke
[392.676561] Subtest smoke: SUCCESS (3.668s)
[396.605164] [091/137] (629s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[396.932991] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[396.964622] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.030s)
[397.072113] [092/137] (628s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[397.379925] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[397.381539] Starting dynamic subtest: pipe-A-valid-mode
[397.480637] Dynamic subtest pipe-A-valid-mode: SKIP (0.096s)
[397.483707] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (0.099s)
[397.785640] [093/137] (628s left) kms_atomic_transition (plane-all-transition-fencing)
[398.083032] Starting subtest: plane-all-transition-fencing
[398.084475] Starting dynamic subtest: pipe-A-DP-1
[402.093597] Dynamic subtest pipe-A-DP-1: SUCCESS (4.011s)
[402.221219] Starting dynamic subtest: pipe-B-DP-1
[406.573321] Dynamic subtest pipe-B-DP-1: SUCCESS (4.377s)
[406.674760] Subtest plane-all-transition-fencing: SUCCESS (8.592s)
[407.005174] [094/137] (619s left) sysfs_timeslice_duration (duration)
[407.217865] Starting subtest: duration
[407.223149] Starting dynamic subtest: vecs0
[407.303131] Dynamic subtest vecs0: SUCCESS (0.083s)
[407.331236] Starting dynamic subtest: vcs0
[407.390985] Dynamic subtest vcs0: SUCCESS (0.086s)
[407.416045] Starting dynamic subtest: bcs0
[407.494226] Dynamic subtest bcs0: SUCCESS (0.101s)
[407.519088] Starting dynamic subtest: rcs0
[407.599876] Dynamic subtest rcs0: SUCCESS (0.104s)
[407.601593] Subtest duration: SUCCESS (0.383s)
[407.688096] [095/137] (618s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[407.999891] Starting subtest: psr-rgb101010-draw-pwrite
[408.002713] Subtest psr-rgb101010-draw-pwrite: SKIP (0.000s)
[408.104891] [096/137] (618s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[408.588884] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[408.591687] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[409.126296] [097/137] (617s left) prime_busy (hang)
[409.335050] Starting subtest: hang
[409.339665] Starting dynamic subtest: rcs0
[415.887752] Dynamic subtest rcs0: SUCCESS (6.547s)
[415.908737] Starting dynamic subtest: bcs0
[422.863538] Dynamic subtest bcs0: SUCCESS (6.974s)
[422.884220] Starting dynamic subtest: vcs0
[429.903703] Dynamic subtest vcs0: SUCCESS (7.038s)
[429.924439] Starting dynamic subtest: vecs0
[436.879178] Dynamic subtest vecs0: SUCCESS (6.975s)
[436.880712] Subtest hang: SUCCESS (27.541s)
[436.976559] [098/137] (589s left) gem_exec_schedule (u-fairslice)
[437.207428] Starting subtest: u-fairslice
[437.209138] Starting dynamic subtest: rcs0
[439.241999] Dynamic subtest rcs0: SUCCESS (2.030s)
[439.263311] Starting dynamic subtest: bcs0
[441.277455] Dynamic subtest bcs0: SUCCESS (2.032s)
[441.297591] Starting dynamic subtest: vcs0
[443.303276] Dynamic subtest vcs0: SUCCESS (2.025s)
[443.323894] Starting dynamic subtest: vecs0
[445.330294] Dynamic subtest vecs0: SUCCESS (2.026s)
[445.331938] Subtest u-fairslice: SUCCESS (8.120s)
[445.660934] [099/137] (580s left) gem_ctx_persistence (legacy-engines-queued)
[445.874408] Starting subtest: legacy-engines-queued
[445.875844] Starting dynamic subtest: render
[445.931149] Dynamic subtest render: SUCCESS (0.056s)
[445.953632] Starting dynamic subtest: bsd
[445.994564] Dynamic subtest bsd: SUCCESS (0.060s)
[446.016475] Starting dynamic subtest: blt
[446.137337] Dynamic subtest blt: SUCCESS (0.140s)
[446.158687] Starting dynamic subtest: vebox
[446.192632] Dynamic subtest vebox: SUCCESS (0.053s)
[446.194108] Subtest legacy-engines-queued: SUCCESS (0.321s)
[446.294945] [100/137] (580s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[446.505928] Starting subtest: legacy-engines-hostile-preempt
[446.511020] Starting dynamic subtest: render
[446.595052] Dynamic subtest render: SUCCESS (0.086s)
[446.617189] Starting dynamic subtest: bsd
[446.685834] Dynamic subtest bsd: SUCCESS (0.088s)
[446.709116] Starting dynamic subtest: blt
[446.774523] Dynamic subtest blt: SUCCESS (0.086s)
[446.795506] Starting dynamic subtest: vebox
[446.862850] Dynamic subtest vebox: SUCCESS (0.085s)
[446.864573] Subtest legacy-engines-hostile-preempt: SUCCESS (0.356s)
[446.960136] [101/137] (579s left) kms_psr (psr2_primary_render)
[447.162629] Subtest psr2_primary_render: SKIP (0.000s)
[447.209685] [102/137] (579s left) kms_chamelium_audio (hdmi-audio-edid)
[447.600525] Subtest hdmi-audio-edid: SKIP (0.000s)
[447.898407] [103/137] (578s left) kms_flip (flip-vs-blocking-wf-vblank)
[448.185192] Starting subtest: flip-vs-blocking-wf-vblank
[448.189784] Starting dynamic subtest: A-DP1
[459.158613] Dynamic subtest A-DP1: SUCCESS (10.971s)
[459.180818] Starting dynamic subtest: B-DP1
[469.901547] Dynamic subtest B-DP1: SUCCESS (10.739s)
[469.922234] Starting dynamic subtest: C-DP1
[480.630762] Dynamic subtest C-DP1: SUCCESS (10.727s)
[480.632272] Subtest flip-vs-blocking-wf-vblank: SUCCESS (32.449s)
[481.056584] [104/137] (545s left) gem_vm_create (invalid-create)
[481.264179] Starting subtest: invalid-create
[481.265774] Subtest invalid-create: SUCCESS (0.001s)
[481.371960] [105/137] (545s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[481.685520] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[481.687206] Starting dynamic subtest: pipe-A-DP-1
[481.777045] Dynamic subtest pipe-A-DP-1: SUCCESS (0.091s)
[481.804613] Starting dynamic subtest: pipe-B-DP-1
[482.185822] Dynamic subtest pipe-B-DP-1: SUCCESS (0.402s)
[482.213592] Starting dynamic subtest: pipe-C-DP-1
[482.575678] Dynamic subtest pipe-C-DP-1: SUCCESS (0.387s)
[482.577801] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (0.894s)
[482.969210] [106/137] (543s left) kms_properties (get_properties-sanity-non-atomic)
[483.278070] Starting subtest: get_properties-sanity-non-atomic
[483.279787] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[483.369366] [107/137] (543s left) i915_suspend (basic-s2idle-without-i915)
[483.565945] Starting subtest: basic-s2idle-without-i915
[503.347400] Subtest basic-s2idle-without-i915: SUCCESS (19.768s)
[505.518622] [108/137] (523s left) kms_flip (2x-plain-flip)
[505.812956] Starting subtest: 2x-plain-flip
[505.820882] Subtest 2x-plain-flip: SKIP (0.003s)
[505.927261] [109/137] (522s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[506.129142] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[506.235214] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[506.326461] [110/137] (522s left) kms_invalid_mode (uint-max-clock)
[506.632355] Starting subtest: uint-max-clock
[506.633824] Starting dynamic subtest: DP-1-pipe-A
[506.719380] Dynamic subtest DP-1-pipe-A: SUCCESS (0.084s)
[506.738673] Starting dynamic subtest: DP-1-pipe-B
[506.740031] Dynamic subtest DP-1-pipe-B: SUCCESS (0.002s)
[506.758976] Starting dynamic subtest: DP-1-pipe-C
[506.760784] Dynamic subtest DP-1-pipe-C: SUCCESS (0.002s)
[506.762258] Subtest uint-max-clock: SUCCESS (0.099s)
[507.042450] [111/137] (521s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[507.352229] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[507.355334] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.006s)
[507.442945] [112/137] (521s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[507.777924] Starting subtest: x-tiled-16bpp-rotate-0
[511.604840] Subtest x-tiled-16bpp-rotate-0: SUCCESS (3.824s)
[511.926159] [113/137] (516s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[512.257909] Starting subtest: yf-tiled-64bpp-rotate-0
[512.262370] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[512.412814] [114/137] (516s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[512.728750] Starting subtest: fbcpsr-stridechange
[512.731746] Subtest fbcpsr-stridechange: SKIP (0.000s)
[512.827345] [115/137] (516s left) gem_exec_fence (syncobj-channel)
[513.508967] Starting subtest: syncobj-channel
[514.576354] Subtest syncobj-channel: SUCCESS (1.064s)
[514.945908] [116/137] (514s left) gen9_exec_parse (batch-invalid-length)
[515.172662] Starting subtest: batch-invalid-length
[515.184282] Subtest batch-invalid-length: SUCCESS (0.011s)
[515.412918] [117/137] (513s left) gem_ctx_exec (basic-invalid-context)
[515.616714] Starting subtest: basic-invalid-context
[515.623584] Subtest basic-invalid-context: SUCCESS (0.007s)
[515.743307] [118/137] (513s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[516.045209] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[516.048113] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[516.144700] [119/137] (512s left) gem_linear_blits (basic)
[517.310059] Starting subtest: basic
[517.322612] Subtest basic: SUCCESS (0.017s)
[517.471627] [120/137] (511s left) fbdev (info)
[517.785258] Starting subtest: info
[517.786815] Subtest info: SUCCESS (0.000s)
[517.878133] [121/137] (511s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[518.194577] Starting subtest: fbc-farfromfence-mmap-gtt
[520.767189] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (2.577s)
[520.928600] [122/137] (508s left) kms_vblank (pipe-a-query-forked-busy)
[521.231368] Starting subtest: pipe-A-query-forked-busy
[523.686511] Subtest pipe-A-query-forked-busy: SUCCESS (2.455s)
[524.014957] [123/137] (505s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[524.310664] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[524.318735] Starting dynamic subtest: pipe-A-valid-mode
[525.003982] Dynamic subtest pipe-A-valid-mode: SKIP (0.685s)
[525.006640] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (0.688s)
[525.393256] [124/137] (503s left) api_intel_allocator (simple-allocator)
[525.593176] Starting subtest: simple-allocator
[525.594817] Starting dynamic subtest: basic
[525.601319] Dynamic subtest basic: SUCCESS (0.007s)
[525.623795] Starting dynamic subtest: parallel-one
[525.753434] Dynamic subtest parallel-one: SUCCESS (0.149s)
[525.776529] Starting dynamic subtest: print
[525.778022] Dynamic subtest print: SUCCESS (0.000s)
[525.799201] Starting dynamic subtest: reuse
[525.800606] Dynamic subtest reuse: SUCCESS (0.000s)
[525.821610] Starting dynamic subtest: reserve
[525.822980] Dynamic subtest reserve: SUCCESS (0.000s)
[525.844885] Starting dynamic subtest: fork-reopen-allocator
[525.846283] Dynamic subtest fork-reopen-allocator: SUCCESS (0.042s)
[525.847632] Subtest simple-allocator: SUCCESS (0.208s)
[525.894272] [125/137] (503s left) gem_userptr_blits (unsync-unmap-after-close)
[526.107486] Subtest unsync-unmap-after-close: SKIP (0.000s)
[526.276807] [126/137] (503s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[526.591188] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[526.594378] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[526.693170] [127/137] (502s left) gem_eio (in-flight-contexts-1us)
[526.928987] Starting subtest: in-flight-contexts-1us
[538.227199] Subtest in-flight-contexts-1us: SUCCESS (11.300s)
[538.410373] [128/137] (491s left) gem_exec_store (dword)
[538.637194] Starting subtest: dword
[538.641590] Starting dynamic subtest: rcs0
[538.663671] Dynamic subtest rcs0: SUCCESS (0.025s)
[538.688298] Starting dynamic subtest: bcs0
[538.697178] Dynamic subtest bcs0: SUCCESS (0.032s)
[538.724237] Starting dynamic subtest: vcs0
[538.726304] Dynamic subtest vcs0: SUCCESS (0.019s)
[538.748686] Starting dynamic subtest: vecs0
[538.750190] Dynamic subtest vecs0: SUCCESS (0.015s)
[538.751627] Subtest dword: SUCCESS (0.096s)
[538.943958] [129/137] (490s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[539.261220] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[539.263981] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[539.360672] [130/137] (490s left) kms_cursor_crc (cursor-offscreen-32x10)
[539.669162] Starting subtest: cursor-offscreen-32x10
[539.799899] Subtest cursor-offscreen-32x10: SKIP (0.127s)
[539.910686] [131/137] (489s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[540.310778] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc
[540.313672] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[540.917577] [132/137] (488s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[541.222111] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[541.224942] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[541.334163] [133/137] (488s left) syncobj_wait (invalid-reset-illegal-handle)
[541.553143] Starting subtest: invalid-reset-illegal-handle
[541.554886] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[541.651461] [134/137] (488s left) kms_scaling_modes (scaling-mode-none)
[541.952773] Starting subtest: scaling-mode-none
[541.955424] Subtest scaling-mode-none: SKIP (0.001s)
[542.033898] [135/137] (487s left) v3d/v3d_submit_cl (bad-multisync-pad)
[542.291749] Subtest bad-multisync-pad: SKIP (0.000s)
[542.366860] [136/137] (487s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_rc_ccs)
[542.971535] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs
[542.975140] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[543.418745] [137/137] (486s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[543.738814] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[545.955407] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SUCCESS (2.219s)
[546.096223] Closing watchdogs
Done.