[43.123542] Initializing watchdogs
[43.123607]   /dev/watchdog0
[43.129357] [001/142] (960s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[43.195431] Starting subtest: invalid-single-wait-all-available-unsubmitted
[43.196246] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[43.264287] [002/142] (960s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_ccs)
[45.287142] Starting subtest: pipe-A-random-ccs-data-y_tiled_ccs
[45.290083] Subtest pipe-A-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[45.487792] [003/142] (958s left) perf_pmu (rc6)
[45.552116] Starting subtest: rc6
[45.553111] Starting dynamic subtest: gt0
[51.899018] Dynamic subtest gt0: SUCCESS (6.338s)
[51.903234] Starting dynamic subtest: runtime-pm-gt0
[59.540399] Dynamic subtest runtime-pm-gt0: SUCCESS (7.641s)
[59.544875] Starting dynamic subtest: runtime-pm-long-gt0
[72.139396] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.597s)
[72.144104] Starting dynamic subtest: other-idle-gt0
[72.145956] Dynamic subtest other-idle-gt0: SKIP (0.000s)
[72.146865] Subtest rc6: SUCCESS (26.577s)
[72.187275] [004/142] (931s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[74.148908] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[74.150457] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[74.199966] [005/142] (929s left) i915_pm_rpm (pm-tiling)
[78.502574] Starting subtest: pm-tiling
[78.505506] Subtest pm-tiling: SKIP (0.000s)
[79.633869] [006/142] (924s left) gem_ppgtt (blt-vs-render-ctxn)
[79.695813] Starting subtest: blt-vs-render-ctxN
[112.309324] Subtest blt-vs-render-ctxN: SUCCESS (32.610s)
[112.367792] [007/142] (891s left) perf (stress-open-close)
[112.764192] Starting subtest: stress-open-close
[112.765015] Starting dynamic subtest: 0-rcs0
[114.960691] Dynamic subtest 0-rcs0: SUCCESS (2.197s)
[114.961519] Subtest stress-open-close: SUCCESS (2.197s)
[114.983263] [008/142] (888s left) drm_fdinfo (busy-idle-check-all)
[115.043242] Starting subtest: busy-idle-check-all
[115.044085] Starting dynamic subtest: rcs0
[115.060911] Dynamic subtest rcs0: SKIP (0.017s)
[115.066881] Starting dynamic subtest: bcs0
[115.068704] Dynamic subtest bcs0: SKIP (0.000s)
[115.074556] Starting dynamic subtest: vcs0
[115.076701] Dynamic subtest vcs0: SKIP (0.000s)
[115.083270] Starting dynamic subtest: vcs1
[115.085436] Dynamic subtest vcs1: SKIP (0.000s)
[115.090916] Starting dynamic subtest: vecs0
[115.092511] Dynamic subtest vecs0: SKIP (0.000s)
[115.098174] Starting dynamic subtest: vecs1
[115.100186] Dynamic subtest vecs1: SKIP (0.000s)
[115.105728] Starting dynamic subtest: ccs0
[115.107824] Dynamic subtest ccs0: SKIP (0.000s)
[115.113543] Starting dynamic subtest: ccs1
[115.115575] Dynamic subtest ccs1: SKIP (0.000s)
[115.121382] Starting dynamic subtest: ccs2
[115.123508] Dynamic subtest ccs2: SKIP (0.000s)
[115.129263] Starting dynamic subtest: ccs3
[115.131169] Dynamic subtest ccs3: SKIP (0.000s)
[115.132705] Subtest busy-idle-check-all: SKIP (0.020s)
[115.141538] [009/142] (888s left) i915_hangman (detector)
[115.247116] Starting subtest: detector
[115.248024] Starting dynamic subtest: rcs0
[124.650706] Dynamic subtest rcs0: SUCCESS (9.404s)
[124.658961] Starting dynamic subtest: bcs0
[134.625506] Dynamic subtest bcs0: SUCCESS (9.975s)
[134.632779] Starting dynamic subtest: vcs0
[144.617472] Dynamic subtest vcs0: SUCCESS (9.992s)
[144.624981] Starting dynamic subtest: vcs1
[154.670602] Dynamic subtest vcs1: SUCCESS (10.053s)
[154.677166] Starting dynamic subtest: vecs0
[164.649753] Dynamic subtest vecs0: SUCCESS (9.979s)
[164.660654] Starting dynamic subtest: vecs1
[174.630530] Dynamic subtest vecs1: SUCCESS (9.981s)
[174.638665] Starting dynamic subtest: ccs0
[184.634332] Dynamic subtest ccs0: SUCCESS (10.004s)
[184.640688] Starting dynamic subtest: ccs1
[194.670563] Dynamic subtest ccs1: SUCCESS (10.036s)
[194.677369] Starting dynamic subtest: ccs2
[204.647466] Dynamic subtest ccs2: SUCCESS (9.977s)
[204.654979] Starting dynamic subtest: ccs3
[214.646566] Dynamic subtest ccs3: SUCCESS (9.999s)
[214.647504] Subtest detector: SUCCESS (99.400s)
[214.709448] [010/142] (789s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[216.675570] Starting subtest: 4-tiled-64bpp-rotate-0
[218.516825] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.833s)
[218.584019] [011/142] (785s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[220.534691] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[220.536380] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[220.570586] [012/142] (783s left) sw_sync (timeline_closed_signaled)
[220.629762] Starting subtest: timeline_closed_signaled
[220.630733] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[220.642957] [013/142] (783s left) core_setmaster (master-drop-set-shared-fd)
[221.480066] Starting subtest: master-drop-set-shared-fd
[221.507854] Subtest master-drop-set-shared-fd: SUCCESS (0.028s)
[221.519243] [014/142] (782s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[221.579485] Starting subtest: invalid-bsd1-flag-on-blt
[221.580402] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[221.623532] [015/142] (782s left) kms_color (ctm-0-50)
[223.582602] Starting subtest: ctm-0-50
[223.584021] Starting dynamic subtest: pipe-A
[224.912381] Dynamic subtest pipe-A: SUCCESS (1.332s)
[224.918923] Starting dynamic subtest: pipe-B
[226.075143] Dynamic subtest pipe-B: SUCCESS (1.162s)
[226.081579] Starting dynamic subtest: pipe-C
[227.313703] Dynamic subtest pipe-C: SUCCESS (1.238s)
[227.323887] Starting dynamic subtest: pipe-D
[228.579022] Dynamic subtest pipe-D: SUCCESS (1.264s)
[228.579980] Subtest ctm-0-50: SUCCESS (4.997s)
[228.659736] [016/142] (775s left) syncobj_basic (bad-pad-fd-to-handle)
[228.719656] Starting subtest: bad-pad-fd-to-handle
[228.720493] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[228.741627] [017/142] (775s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[230.696164] Starting subtest: y-tiled-16bpp-rotate-90
[230.697954] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[230.741894] [018/142] (773s left) vc4/vc4_wait_bo (used-bo)
[230.830738] Subtest used-bo: SKIP (0.000s)
[230.848732] [019/142] (773s left) i915_pm_backlight (fade)
[232.816598] Subtest fade: SKIP (0.000s)
[232.837899] [020/142] (771s left) gem_exec_reloc (basic-scanout)
[232.899890] Subtest basic-scanout: SKIP (0.000s)
[232.938285] [021/142] (770s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[235.050972] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[235.052501] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[235.130460] [022/142] (768s left) kms_vblank (pipe-b-wait-idle)
[237.083116] Starting subtest: pipe-B-wait-idle
[239.435667] Subtest pipe-B-wait-idle: SUCCESS (2.353s)
[239.515009] [023/142] (764s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[241.474461] Starting subtest: 4-tiled-16bpp-rotate-270
[241.518972] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.044s)
[241.582555] [024/142] (762s left) kms_addfb_basic (bo-too-small)
[243.579303] Starting subtest: bo-too-small
[243.580267] Subtest bo-too-small: SUCCESS (0.000s)
[243.613519] [025/142] (760s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_rc_ccs)
[245.639830] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs
[245.641488] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[245.783681] [026/142] (758s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[247.742897] Starting subtest: yf-tiled-8bpp-rotate-270
[247.744492] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[247.781347] [027/142] (756s left) gem_exec_balancer (hang)
[248.090149] Starting subtest: hang
[255.078166] Subtest hang: SUCCESS (6.998s)
[255.134683] [028/142] (748s left) syncobj_wait (wait-for-submit-snapshot)
[255.196825] Starting subtest: wait-for-submit-snapshot
[255.278937] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[255.308311] [029/142] (748s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs)
[257.349268] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs
[257.351066] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.000s)
[257.486981] [030/142] (746s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[257.545350] Starting subtest: etime-multi-wait-all-submitted-signaled
[257.651407] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[257.668481] [031/142] (746s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[257.757730] Subtest destroy-valid-perfmon: SKIP (0.000s)
[257.769103] [032/142] (746s left) kms_ccs (pipe-d-random-ccs-data-yf_tiled_ccs)
[259.873853] Starting subtest: pipe-D-random-ccs-data-yf_tiled_ccs
[259.876271] Subtest pipe-D-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[259.943304] [033/142] (744s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[262.014859] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[262.016570] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[262.112713] [034/142] (741s left) kms_psr (cursor_render)
[262.170869] Subtest cursor_render: SKIP (0.000s)
[262.190924] [035/142] (741s left) kms_rotation_crc (primary-rotation-90)
[264.145330] Starting subtest: primary-rotation-90
[264.147049] Subtest primary-rotation-90: SKIP (0.001s)
[264.161596] [036/142] (739s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[266.131456] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[266.134524] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[266.193338] [037/142] (737s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[268.170449] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[268.177159] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[268.211386] [038/142] (735s left) gem_exec_fair (basic-none-rrul)
[268.277114] Subtest basic-none-rrul: SKIP (0.000s)
[268.308709] [039/142] (735s left) kms_cursor_crc (cursor-random-32x32)
[270.313600] Starting subtest: cursor-random-32x32
[270.351281] Subtest cursor-random-32x32: SKIP (0.035s)
[270.578242] [040/142] (733s left) gem_ctx_sseu (mmap-args)
[270.639811] Subtest mmap-args: SKIP (0.000s)
[270.657238] [041/142] (733s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[272.614008] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[272.615539] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[272.660948] [042/142] (731s left) gem_exec_params (batch-first)
[272.724553] Starting subtest: batch-first
[272.745519] Subtest batch-first: SUCCESS (0.021s)
[272.777421] [043/142] (731s left) vgem_basic (create)
[272.883068] Starting subtest: create
[272.883913] Subtest create: SUCCESS (0.000s)
[272.895531] [044/142] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[274.853209] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[274.854774] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[274.878291] [045/142] (729s left) gem_pxp (create-regular-context-1)
[277.497214] Subtest create-regular-context-1: SKIP (0.000s)
[277.511781] [046/142] (726s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[279.468250] Starting subtest: fbcpsr-tiling-linear
[279.469795] Subtest fbcpsr-tiling-linear: SKIP (0.000s)
[279.511723] [047/142] (724s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[281.472913] Starting subtest: x-tiled-8bpp-rotate-90
[281.517605] Subtest x-tiled-8bpp-rotate-90: SKIP (0.044s)
[281.582217] [048/142] (722s left) gem_spin_batch (legacy)
[281.694164] Starting subtest: legacy
[281.695037] Starting dynamic subtest: default
[284.976982] Dynamic subtest default: SUCCESS (3.245s)
[284.987692] Starting dynamic subtest: render
[288.206496] Dynamic subtest render: SUCCESS (3.228s)
[288.217528] Starting dynamic subtest: bsd
[291.438069] Dynamic subtest bsd: SUCCESS (3.230s)
[291.448763] Starting dynamic subtest: bsd1
[294.669804] Dynamic subtest bsd1: SUCCESS (3.231s)
[294.678885] Starting dynamic subtest: bsd2
[297.900646] Dynamic subtest bsd2: SUCCESS (3.229s)
[297.909415] Starting dynamic subtest: blt
[301.132866] Dynamic subtest blt: SUCCESS (3.230s)
[301.143661] Starting dynamic subtest: vebox
[304.330843] Dynamic subtest vebox: SUCCESS (3.230s)
[304.332774] Subtest legacy: SUCCESS (22.635s)
[304.481740] [049/142] (699s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_mc_ccs)
[306.597207] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs
[306.599153] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[306.655256] [050/142] (697s left) gem_exec_schedule (preempt-engines)
[306.726109] Starting subtest: preempt-engines
[306.727026] Starting dynamic subtest: rcs0
[308.940933] Dynamic subtest rcs0: SUCCESS (2.206s)
[308.947943] Starting dynamic subtest: bcs0
[311.131050] Dynamic subtest bcs0: SUCCESS (2.189s)
[311.137799] Starting dynamic subtest: vcs0
[313.465397] Dynamic subtest vcs0: SUCCESS (2.308s)
[313.472382] Starting dynamic subtest: vcs1
[315.774502] Dynamic subtest vcs1: SUCCESS (2.308s)
[315.781418] Starting dynamic subtest: vecs0
[318.082412] Dynamic subtest vecs0: SUCCESS (2.307s)
[318.089261] Starting dynamic subtest: vecs1
[320.391505] Dynamic subtest vecs1: SUCCESS (2.308s)
[320.398483] Starting dynamic subtest: ccs0
[322.582070] Dynamic subtest ccs0: SUCCESS (2.215s)
[322.589073] Starting dynamic subtest: ccs1
[324.799545] Dynamic subtest ccs1: SUCCESS (2.216s)
[324.806609] Starting dynamic subtest: ccs2
[327.014964] Dynamic subtest ccs2: SUCCESS (2.214s)
[327.021961] Starting dynamic subtest: ccs3
[329.232666] Dynamic subtest ccs3: SUCCESS (2.215s)
[329.234482] Subtest preempt-engines: SUCCESS (22.497s)
[329.505624] [051/142] (674s left) gem_mmap_gtt (big-copy-xy)
[329.571347] Subtest big-copy-XY: SKIP (0.000s)
[329.583628] [052/142] (674s left) kms_chamelium_color (ctm-red-to-blue)
[331.538295] Subtest ctm-red-to-blue: SKIP (0.000s)
[331.567794] [053/142] (672s left) kms_cursor_crc (cursor-onscreen-512x170)
[333.698610] Starting subtest: cursor-onscreen-512x170
[333.705369] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[333.806331] [054/142] (670s left) kms_vblank (pipe-b-ts-continuation-idle)
[335.773390] Starting subtest: pipe-B-ts-continuation-idle
[336.079477] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.306s)
[336.140206] [055/142] (668s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[338.180167] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[338.181884] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[338.310015] [056/142] (666s left) gem_exec_suspend (basic-s0)
[338.377750] Starting subtest: basic-S0
[338.378603] Starting dynamic subtest: smem
[357.168880] Dynamic subtest smem: SUCCESS (4.951s)
[357.176627] Starting dynamic subtest: lmem0
[376.220323] Dynamic subtest lmem0: SUCCESS (4.568s)
[376.221388] Subtest basic-S0: SUCCESS (9.520s)
[380.400748] [057/142] (656s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs)
[382.499188] Starting subtest: pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs
[382.833131] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs: SUCCESS (0.334s)
[382.989509] [058/142] (653s left) v3d/v3d_submit_cl (multisync-out-syncs)
[383.091826] Subtest multisync-out-syncs: SKIP (0.000s)
[383.106484] [059/142] (653s left) vc4/vc4_label_bo (set-bad-handle)
[383.191563] Subtest set-bad-handle: SKIP (0.000s)
[383.204337] [060/142] (653s left) gem_ctx_persistence (hang)
[383.269503] Starting subtest: hang
[383.271538] Subtest hang: SKIP (0.000s)
[383.309022] [061/142] (653s left) gem_exec_await (wide-contexts)
[383.852564] Starting subtest: wide-contexts
[399.889413] Subtest wide-contexts: FAIL (16.000s)
[504.686763] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[504.797751] Closing watchdogs
[504.800571] Initializing watchdogs
[504.800616]   /dev/watchdog0
[504.810073] [062/142] (532s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[625.050586] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[625.422095] Closing watchdogs
[625.423093] Initializing watchdogs
[625.423131]   /dev/watchdog0
[625.432687] [063/142] (411s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[745.639983] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[745.996341] Closing watchdogs
[745.997385] Initializing watchdogs
[745.997429]   /dev/watchdog0
[746.006953] [064/142] (290s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[866.211339] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[866.538483] Closing watchdogs
[866.539452] Initializing watchdogs
[866.539492]   /dev/watchdog0
[866.549182] [065/142] (170s left) gem_flink_basic (flink-lifetime)
[986.763539] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[987.098904] Closing watchdogs
[987.099939] Initializing watchdogs
[987.099984]   /dev/watchdog0
[987.109771] [066/142] ( 49s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[1107.325873] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[1107.667180] Overall timeout time exceeded, stopping.
[1107.667316] Closing watchdogs
Done.