[45.313304] Initializing watchdogs
[45.313388]   /dev/watchdog0
[45.319452] [001/142] (960s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[45.394015] Starting subtest: invalid-single-wait-all-available-unsubmitted
[45.394866] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[45.460757] [002/142] (960s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_ccs)
[47.496605] Starting subtest: pipe-A-random-ccs-data-y_tiled_ccs
[47.499043] Subtest pipe-A-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[47.894154] [003/142] (957s left) perf_pmu (rc6)
[47.969300] Starting subtest: rc6
[47.970285] Starting dynamic subtest: gt0
[54.310354] Dynamic subtest gt0: SUCCESS (6.336s)
[54.315292] Starting dynamic subtest: runtime-pm-gt0
[62.066206] Dynamic subtest runtime-pm-gt0: SUCCESS (7.760s)
[62.071663] Starting dynamic subtest: runtime-pm-long-gt0
[74.777927] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.712s)
[74.783271] Starting dynamic subtest: other-idle-gt0
[74.784988] Dynamic subtest other-idle-gt0: SKIP (0.000s)
[74.785924] Subtest rc6: SUCCESS (26.809s)
[74.839757] [004/142] (931s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[76.818091] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[76.820021] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[76.851685] [005/142] (929s left) i915_pm_rpm (pm-tiling)
[81.483913] Starting subtest: pm-tiling
[81.486531] Subtest pm-tiling: SKIP (0.000s)
[82.591271] [006/142] (923s left) gem_ppgtt (blt-vs-render-ctxn)
[82.663572] Starting subtest: blt-vs-render-ctxN
[115.473169] Subtest blt-vs-render-ctxN: SUCCESS (32.809s)
[115.519494] [007/142] (890s left) perf (stress-open-close)
[115.696035] Starting subtest: stress-open-close
[115.696952] Starting dynamic subtest: 0-rcs0
[117.899708] Dynamic subtest 0-rcs0: SUCCESS (2.204s)
[117.900497] Subtest stress-open-close: SUCCESS (2.204s)
[117.919722] [008/142] (887s left) drm_fdinfo (busy-idle-check-all)
[117.989709] Starting subtest: busy-idle-check-all
[117.990537] Starting dynamic subtest: rcs0
[118.002894] Dynamic subtest rcs0: SKIP (0.012s)
[118.009243] Starting dynamic subtest: bcs0
[118.011226] Dynamic subtest bcs0: SKIP (0.000s)
[118.017522] Starting dynamic subtest: vcs0
[118.019629] Dynamic subtest vcs0: SKIP (0.000s)
[118.025704] Starting dynamic subtest: vcs1
[118.027637] Dynamic subtest vcs1: SKIP (0.000s)
[118.033880] Starting dynamic subtest: vecs0
[118.035566] Dynamic subtest vecs0: SKIP (0.000s)
[118.037194] Subtest busy-idle-check-all: SKIP (0.014s)
[118.046478] [009/142] (887s left) i915_hangman (detector)
[118.162916] Starting subtest: detector
[118.163845] Starting dynamic subtest: rcs0
[127.492292] Dynamic subtest rcs0: SUCCESS (9.329s)
[127.499128] Starting dynamic subtest: bcs0
[137.486490] Dynamic subtest bcs0: SUCCESS (9.994s)
[137.495130] Starting dynamic subtest: vcs0
[147.451331] Dynamic subtest vcs0: SUCCESS (9.965s)
[147.459421] Starting dynamic subtest: vcs1
[157.461854] Dynamic subtest vcs1: SUCCESS (10.010s)
[157.472403] Starting dynamic subtest: vecs0
[167.502883] Dynamic subtest vecs0: SUCCESS (10.041s)
[167.504294] Subtest detector: SUCCESS (49.340s)
[167.607546] [010/142] (838s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[169.593391] Starting subtest: 4-tiled-64bpp-rotate-0
[169.595250] Subtest 4-tiled-64bpp-rotate-0: SKIP (0.001s)
[169.646167] [011/142] (836s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[171.625925] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[171.627880] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[171.663586] [012/142] (834s left) sw_sync (timeline_closed_signaled)
[171.731048] Starting subtest: timeline_closed_signaled
[171.732053] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[171.746532] [013/142] (834s left) core_setmaster (master-drop-set-shared-fd)
[172.602240] Starting subtest: master-drop-set-shared-fd
[172.632297] Subtest master-drop-set-shared-fd: SUCCESS (0.030s)
[172.646349] [014/142] (833s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[172.717620] Starting subtest: invalid-bsd1-flag-on-blt
[172.718569] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[172.763058] [015/142] (833s left) kms_color (ctm-0-50)
[174.739694] Starting subtest: ctm-0-50
[174.740583] Starting dynamic subtest: pipe-A
[176.171214] Dynamic subtest pipe-A: SUCCESS (1.432s)
[176.181693] Starting dynamic subtest: pipe-B
[177.765209] Dynamic subtest pipe-B: SUCCESS (1.594s)
[177.781420] Starting dynamic subtest: pipe-C
[179.735278] Dynamic subtest pipe-C: SUCCESS (1.970s)
[179.747950] Starting dynamic subtest: pipe-D
[182.077435] Dynamic subtest pipe-D: SUCCESS (2.343s)
[182.078573] Subtest ctm-0-50: SUCCESS (7.339s)
[182.159616] [016/142] (823s left) syncobj_basic (bad-pad-fd-to-handle)
[182.230962] Starting subtest: bad-pad-fd-to-handle
[182.231838] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[182.258315] [017/142] (823s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[184.243898] Starting subtest: y-tiled-16bpp-rotate-90
[185.998856] Subtest y-tiled-16bpp-rotate-90: SUCCESS (1.751s)
[186.076758] [018/142] (819s left) vc4/vc4_wait_bo (used-bo)
[186.178112] Subtest used-bo: SKIP (0.000s)
[186.191473] [019/142] (819s left) i915_pm_backlight (fade)
[188.161370] Subtest fade: SKIP (0.000s)
[188.199023] [020/142] (817s left) gem_exec_reloc (basic-scanout)
[188.271870] Subtest basic-scanout: SKIP (0.000s)
[188.320615] [021/142] (817s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[190.608462] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[190.610247] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[190.742908] [022/142] (815s left) kms_vblank (pipe-b-wait-idle)
[192.717730] Starting subtest: pipe-B-wait-idle
[195.354311] Subtest pipe-B-wait-idle: SUCCESS (2.638s)
[195.430922] [023/142] (810s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[197.419656] Starting subtest: 4-tiled-16bpp-rotate-270
[197.421549] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[197.463247] [024/142] (808s left) kms_addfb_basic (bo-too-small)
[199.431519] Starting subtest: bo-too-small
[199.432451] Subtest bo-too-small: SUCCESS (0.000s)
[199.479521] [025/142] (806s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_rc_ccs)
[201.591020] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs
[201.728395] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs: SUCCESS (0.137s)
[202.090846] [026/142] (804s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[204.077467] Starting subtest: yf-tiled-8bpp-rotate-270
[204.079451] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[204.138292] [027/142] (802s left) gem_exec_balancer (hang)
[204.524566] Starting subtest: hang
[206.669092] Subtest hang: SUCCESS (2.150s)
[206.744502] [028/142] (799s left) syncobj_wait (wait-for-submit-snapshot)
[206.815210] Starting subtest: wait-for-submit-snapshot
[206.897959] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[206.940845] [029/142] (799s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs)
[209.072837] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs
[209.074522] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.000s)
[209.364069] [030/142] (796s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[209.433703] Starting subtest: etime-multi-wait-all-submitted-signaled
[209.544091] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[209.563250] [031/142] (796s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[209.665314] Subtest destroy-valid-perfmon: SKIP (0.000s)
[209.680198] [032/142] (796s left) kms_ccs (pipe-d-random-ccs-data-yf_tiled_ccs)
[211.980068] Starting subtest: pipe-D-random-ccs-data-yf_tiled_ccs
[211.982461] Subtest pipe-D-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[212.085806] [033/142] (794s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[214.323523] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[214.324422] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SUCCESS (0.000s)
[214.508834] [034/142] (791s left) kms_psr (cursor_render)
[214.578886] Subtest cursor_render: SKIP (0.000s)
[214.602292] [035/142] (791s left) kms_rotation_crc (primary-rotation-90)
[216.576872] Starting subtest: primary-rotation-90
[226.937845] Subtest primary-rotation-90: SUCCESS (10.361s)
[227.084654] [036/142] (779s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[229.065353] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[229.066961] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[229.099479] [037/142] (777s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[231.072601] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[231.074370] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[231.116302] [038/142] (775s left) gem_exec_fair (basic-none-rrul)
[231.194125] Subtest basic-none-rrul: SKIP (0.000s)
[231.252696] [039/142] (775s left) kms_cursor_crc (cursor-random-32x32)
[233.308774] Starting subtest: cursor-random-32x32
[233.350848] Subtest cursor-random-32x32: SKIP (0.040s)
[233.615363] [040/142] (772s left) gem_ctx_sseu (mmap-args)
[233.687612] Subtest mmap-args: SKIP (0.000s)
[233.709151] [041/142] (772s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[235.689322] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[235.691616] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[235.748378] [042/142] (770s left) gem_exec_params (batch-first)
[235.820563] Starting subtest: batch-first
[235.842476] Subtest batch-first: SUCCESS (0.022s)
[235.913543] [043/142] (770s left) vgem_basic (create)
[236.052560] Starting subtest: create
[236.054066] Subtest create: SUCCESS (0.000s)
[236.081462] [044/142] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[238.085848] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[238.087756] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[238.115036] [045/142] (768s left) gem_pxp (create-regular-context-1)
[240.706346] Subtest create-regular-context-1: SKIP (0.000s)
[240.733691] [046/142] (765s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[242.739108] Starting subtest: fbcpsr-tiling-linear
[242.743781] Subtest fbcpsr-tiling-linear: SKIP (0.000s)
[242.807869] [047/142] (763s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[244.795164] Starting subtest: x-tiled-8bpp-rotate-90
[244.885134] Subtest x-tiled-8bpp-rotate-90: SKIP (0.089s)
[244.948129] [048/142] (761s left) gem_spin_batch (legacy)
[245.025235] Starting subtest: legacy
[245.026221] Starting dynamic subtest: default
[248.273675] Dynamic subtest default: SUCCESS (3.244s)
[248.284725] Starting dynamic subtest: render
[251.508567] Dynamic subtest render: SUCCESS (3.226s)
[251.516137] Starting dynamic subtest: bsd
[254.838961] Dynamic subtest bsd: SUCCESS (3.326s)
[254.846496] Starting dynamic subtest: bsd1
[258.169025] Dynamic subtest bsd1: SUCCESS (3.326s)
[258.176784] Starting dynamic subtest: bsd2
[261.499209] Dynamic subtest bsd2: SUCCESS (3.325s)
[261.506770] Starting dynamic subtest: blt
[264.730091] Dynamic subtest blt: SUCCESS (3.226s)
[264.737741] Starting dynamic subtest: vebox
[268.061503] Dynamic subtest vebox: SUCCESS (3.327s)
[268.062718] Subtest legacy: SUCCESS (23.031s)
[268.229566] [049/142] (738s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_mc_ccs)
[270.576497] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs
[270.578302] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[270.654453] [050/142] (735s left) gem_exec_schedule (preempt-engines)
[270.738156] Starting subtest: preempt-engines
[270.739133] Starting dynamic subtest: rcs0
[273.032135] Dynamic subtest rcs0: SUCCESS (2.289s)
[273.043910] Starting dynamic subtest: bcs0
[275.216659] Dynamic subtest bcs0: SUCCESS (2.181s)
[275.228231] Starting dynamic subtest: vcs0
[277.464228] Dynamic subtest vcs0: SUCCESS (2.243s)
[277.476048] Starting dynamic subtest: vcs1
[279.711187] Dynamic subtest vcs1: SUCCESS (2.243s)
[279.718648] Starting dynamic subtest: vecs0
[281.940674] Dynamic subtest vecs0: SUCCESS (2.226s)
[281.942346] Subtest preempt-engines: SUCCESS (11.199s)
[282.273335] [051/142] (724s left) gem_mmap_gtt (big-copy-xy)
[282.344190] Subtest big-copy-XY: SKIP (0.000s)
[282.361424] [052/142] (724s left) kms_chamelium_color (ctm-red-to-blue)
[284.327255] Subtest ctm-red-to-blue: SKIP (0.000s)
[284.346021] [053/142] (722s left) kms_cursor_crc (cursor-onscreen-512x170)
[286.517259] Starting subtest: cursor-onscreen-512x170
[286.520375] Subtest cursor-onscreen-512x170: SKIP (0.002s)
[286.622694] [054/142] (720s left) kms_vblank (pipe-b-ts-continuation-idle)
[288.614148] Starting subtest: pipe-B-ts-continuation-idle
[289.248647] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.638s)
[289.315362] [055/142] (717s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[291.464992] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[291.467050] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[291.736878] [056/142] (715s left) gem_exec_suspend (basic-s0)
[291.817134] Starting subtest: basic-S0
[291.818143] Starting dynamic subtest: smem
[310.749935] Dynamic subtest smem: SUCCESS (4.918s)
[310.761000] Starting dynamic subtest: lmem0
[330.719822] Dynamic subtest lmem0: SUCCESS (4.506s)
[330.720854] Subtest basic-S0: SUCCESS (9.427s)
[332.895277] [057/142] (705s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs)
[335.188020] Starting subtest: pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs
[335.190163] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[335.314816] [058/142] (702s left) v3d/v3d_submit_cl (multisync-out-syncs)
[335.415020] Subtest multisync-out-syncs: SKIP (0.000s)
[335.432091] [059/142] (702s left) vc4/vc4_label_bo (set-bad-handle)
[335.531577] Subtest set-bad-handle: SKIP (0.000s)
[335.547835] [060/142] (702s left) gem_ctx_persistence (hang)
[335.621848] Starting subtest: hang
[335.623751] Subtest hang: SKIP (0.000s)
[335.647343] [061/142] (702s left) gem_exec_await (wide-contexts)
[335.772927] Starting subtest: wide-contexts
[357.836980] Subtest wide-contexts: SUCCESS (22.064s)
[358.047945] [062/142] (680s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[360.080450] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[360.082353] Subtest psr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[360.110984] [063/142] (678s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[360.181731] Subtest primary-plane-update-sf-dmg-area: SKIP (0.000s)
[360.205229] [064/142] (678s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[362.189720] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[362.191633] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[362.244388] [065/142] (676s left) gem_flink_basic (flink-lifetime)
[362.314058] Starting subtest: flink-lifetime
[362.315190] Subtest flink-lifetime: SUCCESS (0.002s)
[362.379139] [066/142] (676s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[364.366930] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[364.368693] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[364.410408] [067/142] (674s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[366.383899] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[366.385873] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[366.410264] [068/142] (672s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[368.501638] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[368.505983] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.003s)
[368.834107] [069/142] (669s left) kms_setmode (basic-clone-single-crtc)
[368.903426] Starting subtest: basic-clone-single-crtc
[368.906160] Subtest basic-clone-single-crtc: SKIP (0.000s)
[368.932842] [070/142] (669s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_mc_ccs)
[371.129571] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs
[371.131379] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[371.354607] [071/142] (667s left) kms_invalid_mode (zero-clock)
[373.328574] Starting subtest: zero-clock
[373.329687] Starting dynamic subtest: HDMI-A-3-pipe-A
[373.393785] Dynamic subtest HDMI-A-3-pipe-A: SUCCESS (0.064s)
[373.405976] Starting dynamic subtest: HDMI-A-3-pipe-B
[373.420158] Dynamic subtest HDMI-A-3-pipe-B: SUCCESS (0.024s)
[373.434151] Starting dynamic subtest: HDMI-A-3-pipe-C
[373.445941] Dynamic subtest HDMI-A-3-pipe-C: SUCCESS (0.024s)
[373.455565] Starting dynamic subtest: HDMI-A-3-pipe-D
[373.471114] Dynamic subtest HDMI-A-3-pipe-D: SUCCESS (0.024s)
[373.472044] Subtest zero-clock: SUCCESS (0.145s)
[373.546779] [072/142] (664s left) gem_reg_read (timestamp-monotonic)
[373.616420] Starting subtest: timestamp-monotonic
[377.914291] Subtest timestamp-monotonic: SUCCESS (4.294s)
[377.943637] [073/142] (660s left) kms_color (ctm-signed)
[379.918134] Starting subtest: ctm-signed
[379.919141] Starting dynamic subtest: pipe-A
[380.971154] Dynamic subtest pipe-A: SUCCESS (1.051s)
[380.988519] Starting dynamic subtest: pipe-B
[382.175881] Dynamic subtest pipe-B: SUCCESS (1.205s)
[382.186660] Starting dynamic subtest: pipe-C
[383.761367] Dynamic subtest pipe-C: SUCCESS (1.583s)
[383.776422] Starting dynamic subtest: pipe-D
[385.724254] Dynamic subtest pipe-D: SUCCESS (1.962s)
[385.725768] Subtest ctm-signed: SUCCESS (5.806s)
[385.812243] [074/142] (652s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[387.792830] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[387.794565] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[387.843208] [075/142] (650s left) kms_ccs (pipe-a-bad-pixel-format-yf_tiled_ccs)
[389.819147] Starting subtest: pipe-A-bad-pixel-format-yf_tiled_ccs
[389.821120] Subtest pipe-A-bad-pixel-format-yf_tiled_ccs: SKIP (0.000s)
[390.264047] [076/142] (648s left) kms_rotation_crc (sprite-rotation-180)
[392.237621] Starting subtest: sprite-rotation-180
[399.891917] Subtest sprite-rotation-180: SUCCESS (7.656s)
[399.991083] [077/142] (638s left) gen9_exec_parse (basic-rejected)
[400.066033] Subtest basic-rejected: SKIP (0.000s)
[400.084150] [078/142] (638s left) gem_exec_balancer (parallel)
[400.456727] Starting subtest: parallel
[401.769221] Subtest parallel: SUCCESS (1.312s)
[401.789397] [079/142] (636s left) gem_exec_params (no-vebox)
[401.863617] Starting subtest: no-vebox
[401.866062] Subtest no-vebox: SUCCESS (0.001s)
[401.929547] [080/142] (636s left) gem_busy (close-race)
[402.027497] Starting subtest: close-race
[423.886159] Subtest close-race: SUCCESS (21.858s)
[424.378479] [081/142] (614s left) gem_ctx_create (basic)
[424.526447] Starting subtest: basic
[424.527408] Subtest basic: SUCCESS (0.000s)
[424.686994] [082/142] (614s left) fbdev (nullptr)
[424.770987] Starting subtest: nullptr
[424.771847] Subtest nullptr: SUCCESS (0.000s)
[424.794268] [083/142] (613s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[424.873265] Starting subtest: single-wait-for-submit-available-submitted
[424.874209] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[424.917014] [084/142] (613s left) v3d/v3d_get_param (get-bad-param)
[425.021061] Subtest get-bad-param: SKIP (0.000s)
[425.036898] [085/142] (613s left) api_intel_allocator (default-alignment)
[425.105406] Starting subtest: default-alignment
[425.110850] Subtest default-alignment: SUCCESS (0.006s)
[425.170506] [086/142] (613s left) i915_query (query-regions-sanity-check)
[425.244088] Starting subtest: query-regions-sanity-check
[425.273493] Subtest query-regions-sanity-check: SUCCESS (0.030s)
[425.344085] [087/142] (613s left) gem_ctx_isolation (dirty-switch)
[425.440277] Starting subtest: dirty-switch
[425.441248] Starting dynamic subtest: rcs0
[426.095628] Dynamic subtest rcs0: SUCCESS (0.655s)
[426.106183] Starting dynamic subtest: bcs0
[426.798262] Dynamic subtest bcs0: SUCCESS (0.701s)
[426.807222] Starting dynamic subtest: vcs0
[427.512062] Dynamic subtest vcs0: SUCCESS (0.713s)
[427.520828] Starting dynamic subtest: vcs1
[428.233324] Dynamic subtest vcs1: SUCCESS (0.720s)
[428.243326] Starting dynamic subtest: vecs0
[428.962610] Dynamic subtest vecs0: SUCCESS (0.728s)
[428.963597] Subtest dirty-switch: SUCCESS (3.523s)
[429.153437] [088/142] (609s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[431.142033] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[431.143716] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[431.186002] [089/142] (607s left) syncobj_timeline (wait-delayed-signal)
[431.256451] Starting subtest: wait-delayed-signal
[431.360980] Subtest wait-delayed-signal: SUCCESS (0.102s)
[431.443324] [090/142] (607s left) gem_exec_reloc (basic-gtt-cpu)
[431.530694] Subtest basic-gtt-cpu: SKIP (0.000s)
[431.579793] [091/142] (607s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_mc_ccs)
[433.653167] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs
[433.655357] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[433.992408] [092/142] (604s left) kms_flip (2x-nonexisting-fb)
[435.020204] Starting subtest: 2x-nonexisting-fb
[435.047389] Subtest 2x-nonexisting-fb: SKIP (0.027s)
[435.090435] [093/142] (603s left) kms_flip (2x-plain-flip-fb-recreate)
[436.116306] Starting subtest: 2x-plain-flip-fb-recreate
[436.141962] Subtest 2x-plain-flip-fb-recreate: SKIP (0.027s)
[436.174363] [094/142] (602s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[438.147952] Starting subtest: cursorA-vs-flipB-varying-size
[438.151611] Subtest cursorA-vs-flipB-varying-size: SKIP (0.002s)
[438.173739] [095/142] (600s left) api_intel_bb (intel-bb-blit-none)
[438.245328] Starting subtest: intel-bb-blit-none
[438.973182] Subtest intel-bb-blit-none: SUCCESS (0.728s)
[439.042320] [096/142] (599s left) i915_pm_rc6_residency (rc6-idle)
[439.113215] Starting subtest: rc6-idle
[439.118444] Starting dynamic subtest: rcs0
[451.342784] Dynamic subtest rcs0: SUCCESS (12.226s)
[451.351814] Starting dynamic subtest: bcs0
[463.645955] Dynamic subtest bcs0: FAIL (12.276s)
[463.657754] Starting dynamic subtest: vcs0
[475.880742] Dynamic subtest vcs0: SUCCESS (12.259s)
[475.889611] Starting dynamic subtest: vecs0
[488.140801] Dynamic subtest vecs0: SUCCESS (12.259s)
[488.141794] Subtest rc6-idle: FAIL (49.029s)
[488.428855] [097/142] (550s left) kms_lease (multimaster-lease)
[490.404096] Starting subtest: multimaster-lease
[490.407523] Subtest multimaster-lease: SUCCESS (0.003s)
[490.434733] [098/142] (548s left) gem_exec_schedule (u-submit-early-slice)
[490.519002] Starting subtest: u-submit-early-slice
[490.519966] Starting dynamic subtest: rcs0
[490.571554] Dynamic subtest rcs0: SUCCESS (0.051s)
[490.595377] Starting dynamic subtest: bcs0
[490.625317] Dynamic subtest bcs0: SUCCESS (0.050s)
[490.635890] Starting dynamic subtest: vcs0
[490.674347] Dynamic subtest vcs0: SUCCESS (0.048s)
[490.686194] Starting dynamic subtest: vcs1
[490.724569] Dynamic subtest vcs1: SUCCESS (0.049s)
[490.738856] Starting dynamic subtest: vecs0
[490.765591] Dynamic subtest vecs0: SUCCESS (0.039s)
[490.766640] Subtest u-submit-early-slice: SUCCESS (0.247s)
[491.131244] [099/142] (547s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[491.199751] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[491.311199] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.106s)
[491.334351] [100/142] (547s left) perf (short-reads)
[491.442922] Starting subtest: short-reads
[491.465473] Subtest short-reads: SUCCESS (0.022s)
[491.501039] [101/142] (547s left) kms_chamelium_hpd (common-hpd-after-suspend)
[493.529212] Subtest common-hpd-after-suspend: SKIP (0.000s)
[493.583730] [102/142] (545s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[495.563202] Starting subtest: psr-2p-primscrn-cur-indfb-move
[495.565066] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[495.604011] [103/142] (543s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[495.675793] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[495.739363] [104/142] (543s left) gem_lmem_swapping (verify-random)
[498.894453] Starting subtest: verify-random
[498.895218] Starting dynamic subtest: lmem0
[507.698482] Dynamic subtest lmem0: SUCCESS (8.806s)
[507.699407] Subtest verify-random: SUCCESS (8.807s)
[510.088611] [105/142] (529s left) syncobj_timeline (reset-during-wait-for-submit)
[512.315715] Starting subtest: reset-during-wait-for-submit
[512.414803] Subtest reset-during-wait-for-submit: SUCCESS (0.101s)
[513.328017] [106/142] (525s left) gem_mmap_wc (write-prefaulted)
[513.406279] Subtest write-prefaulted: SKIP (0.000s)
[514.287485] [107/142] (524s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[514.361327] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[516.308636] [108/142] (522s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-fully-sf)
[516.382045] Subtest cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[516.403971] [109/142] (522s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_mc_ccs)
[518.446156] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs
[518.448436] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[518.835763] [110/142] (520s left) gen3_render_linear_blits
[518.928596] [111/142] (520s left) kms_vblank (pipe-d-ts-continuation-modeset-hang)
[520.899382] Starting subtest: pipe-D-ts-continuation-modeset-hang
[536.312332] Subtest pipe-D-ts-continuation-modeset-hang: SUCCESS (15.415s)
[536.412489] [112/142] (502s left) gem_lmem_swapping (parallel-random-verify-ccs)
[539.107567] Starting subtest: parallel-random-verify-ccs
[539.108511] Starting dynamic subtest: lmem0
[539.110089] Dynamic subtest lmem0: SKIP (0.000s)
[539.111840] Subtest parallel-random-verify-ccs: SKIP (0.002s)
[542.039273] [113/142] (497s left) kms_selftest (drm_cmdline)
[542.107304] Starting subtest: drm_cmdline
[542.110556] Subtest drm_cmdline: SKIP (0.001s)
[542.124552] [114/142] (497s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[543.588455] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[543.590561] Starting dynamic subtest: smem
[543.695099] Dynamic subtest smem: SUCCESS (0.106s)
[543.703289] Starting dynamic subtest: lmem0
[544.714448] Dynamic subtest lmem0: SUCCESS (1.018s)
[544.715219] Subtest mixed-tiled-to-yf-tiled-ccs: SUCCESS (1.126s)
[545.568896] [115/142] (493s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[547.553197] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[547.555120] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[547.585282] [116/142] (491s left) kms_atomic_interruptible (universal-setplane-cursor)
[549.557460] Starting subtest: universal-setplane-cursor
[549.558583] Starting dynamic subtest: HDMI-A-3-pipe-A
[555.753560] Dynamic subtest HDMI-A-3-pipe-A: SUCCESS (6.194s)
[555.755141] Subtest universal-setplane-cursor: SUCCESS (6.197s)
[555.838841] [117/142] (483s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[557.815328] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[557.817260] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[557.869351] [118/142] (481s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[559.845537] Starting subtest: basic-flip-before-cursor-varying-size
[560.928152] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.084s)
[561.039859] [119/142] (478s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[561.518481] Starting subtest: syncobj-stationary-timeline-chain-engines
[561.624174] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.108s)
[561.848278] [120/142] (477s left) syncobj_wait (wait-all-delayed-signal)
[561.925377] Starting subtest: wait-all-delayed-signal
[562.031777] Subtest wait-all-delayed-signal: SUCCESS (0.104s)
[562.078618] [121/142] (477s left) perf_pmu (busy)
[562.172827] Starting subtest: busy
[562.173634] Starting dynamic subtest: rcs0
[562.760726] Dynamic subtest rcs0: SUCCESS (0.586s)
[562.772906] Starting dynamic subtest: bcs0
[563.321756] Dynamic subtest bcs0: SUCCESS (0.555s)
[563.333823] Starting dynamic subtest: vcs0
[563.887044] Dynamic subtest vcs0: SUCCESS (0.561s)
[563.898513] Starting dynamic subtest: vcs1
[564.442078] Dynamic subtest vcs1: SUCCESS (0.553s)
[564.453607] Starting dynamic subtest: vecs0
[565.001949] Dynamic subtest vecs0: SUCCESS (0.558s)
[565.002992] Subtest busy: SUCCESS (2.830s)
[565.039400] [122/142] (474s left) gem_eio (in-flight-internal-1us)
[565.224222] Starting subtest: in-flight-internal-1us
[566.741318] Subtest in-flight-internal-1us: SUCCESS (1.517s)
[568.671969] [123/142] (470s left) kms_vblank (pipe-a-query-idle-hang)
[570.650269] Starting subtest: pipe-A-query-idle-hang
[572.944416] Subtest pipe-A-query-idle-hang: SUCCESS (2.296s)
[573.013583] [124/142] (466s left) gem_ringfill (legacy-basic)
[573.396711] Starting subtest: legacy-basic
[573.401312] Starting dynamic subtest: default
[573.475386] Dynamic subtest default: SUCCESS (0.077s)
[573.490118] Starting dynamic subtest: render
[573.517828] Dynamic subtest render: SUCCESS (0.040s)
[573.550585] Starting dynamic subtest: bsd
[573.568489] Dynamic subtest bsd: SUCCESS (0.047s)
[573.587988] Starting dynamic subtest: bsd1
[573.614206] Dynamic subtest bsd1: SUCCESS (0.043s)
[573.628523] Starting dynamic subtest: bsd2
[573.657324] Dynamic subtest bsd2: SUCCESS (0.041s)
[573.677977] Starting dynamic subtest: blt
[573.702551] Dynamic subtest blt: SUCCESS (0.042s)
[573.723611] Starting dynamic subtest: vebox
[573.748550] Dynamic subtest vebox: SUCCESS (0.043s)
[573.749910] Subtest legacy-basic: SUCCESS (0.353s)
[573.867092] [125/142] (465s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[575.854326] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[575.855347] Starting dynamic subtest: pipe-A-valid-mode
[576.562614] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.708s)
[576.565426] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (0.710s)
[576.674628] [126/142] (462s left) kms_cursor_legacy (torture-bo)
[578.652355] Starting subtest: torture-bo
[578.653445] Starting dynamic subtest: pipe-A
[584.254766] Dynamic subtest pipe-A: SUCCESS (5.599s)
[584.263146] Starting dynamic subtest: pipe-B
[589.919603] Dynamic subtest pipe-B: SUCCESS (5.663s)
[589.928448] Starting dynamic subtest: pipe-C
[595.579504] Dynamic subtest pipe-C: SUCCESS (5.659s)
[595.587519] Starting dynamic subtest: pipe-D
[601.118163] Dynamic subtest pipe-D: SUCCESS (5.538s)
[601.126160] Starting dynamic subtest: all-pipes
[606.585582] Dynamic subtest all-pipes: SUCCESS (5.470s)
[606.586390] Subtest torture-bo: SUCCESS (27.935s)
[606.619362] [127/142] (433s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[608.594225] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[608.596143] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[608.619364] [128/142] (431s left) gem_exec_params (rel-constants-invalid)
[608.693120] Starting subtest: rel-constants-invalid
[608.694015] Subtest rel-constants-invalid: SUCCESS (0.000s)
[608.734965] [129/142] (430s left) kms_big_fb (linear-32bpp-rotate-90)
[610.728951] Starting subtest: linear-32bpp-rotate-90
[610.808125] Subtest linear-32bpp-rotate-90: SKIP (0.077s)
[610.885553] [130/142] (428s left) kms_flip (2x-modeset-vs-vblank-race)
[611.910455] Starting subtest: 2x-modeset-vs-vblank-race
[611.938513] Subtest 2x-modeset-vs-vblank-race: SKIP (0.027s)
[611.974506] [131/142] (427s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[613.003204] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[613.031390] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.027s)
[613.068161] [132/142] (426s left) gem_eio (in-flight-10ms)
[613.245867] Starting subtest: in-flight-10ms
[617.231508] Subtest in-flight-10ms: SUCCESS (3.986s)
[619.168896] [133/142] (420s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[621.155464] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[621.685283] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.529s)
[621.784892] [134/142] (417s left) kms_plane_lowres (tiling-x)
[623.760066] Starting subtest: tiling-x
[623.760995] Starting dynamic subtest: pipe-A-HDMI-A-3
[632.303062] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (8.543s)
[632.356170] Starting dynamic subtest: pipe-B-HDMI-A-3
[640.731736] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (8.386s)
[640.770213] Starting dynamic subtest: pipe-C-HDMI-A-3
[649.141601] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (8.378s)
[649.189008] Starting dynamic subtest: pipe-D-HDMI-A-3
[657.552563] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (8.374s)
[657.578082] Subtest tiling-x: SUCCESS (33.816s)
[657.678892] [135/142] (382s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[659.655320] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[659.656262] Starting dynamic subtest: pipe-A-valid-mode
[659.700030] Dynamic subtest pipe-A-valid-mode: SKIP (0.043s)
[659.701947] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.046s)
[659.760543] [136/142] (380s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[661.738585] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[661.740463] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[661.773975] [137/142] (378s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[663.993549] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[663.999408] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[664.203783] [138/142] (375s left) perf (blocking)
[664.305465] Starting subtest: blocking
[664.306369] Starting dynamic subtest: 0-rcs0
[674.335291] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[674.336223] Subtest blocking: SUCCESS (10.027s)
[674.369728] [139/142] (365s left) kms_sequence (get-forked)
[676.347111] Starting subtest: get-forked
[676.348158] Starting dynamic subtest: HDMI-A-3-pipe-A
[678.740046] Dynamic subtest HDMI-A-3-pipe-A: SUCCESS (2.393s)
[678.751607] Starting dynamic subtest: HDMI-A-3-pipe-B
[681.381442] Dynamic subtest HDMI-A-3-pipe-B: SUCCESS (2.638s)
[681.392940] Starting dynamic subtest: HDMI-A-3-pipe-C
[684.406339] Dynamic subtest HDMI-A-3-pipe-C: SUCCESS (3.024s)
[684.417581] Starting dynamic subtest: HDMI-A-3-pipe-D
[687.814473] Dynamic subtest HDMI-A-3-pipe-D: SUCCESS (3.406s)
[687.815337] Subtest get-forked: SUCCESS (11.470s)
[687.907817] [140/142] (351s left) kms_plane (plane-position-hole-dpms)
[689.885777] Starting subtest: plane-position-hole-dpms
[689.886664] Starting dynamic subtest: pipe-A-planes
[692.721171] Dynamic subtest pipe-A-planes: SUCCESS (2.835s)
[692.731792] Starting dynamic subtest: pipe-B-planes
[695.335723] Dynamic subtest pipe-B-planes: SUCCESS (2.611s)
[695.336551] Subtest plane-position-hole-dpms: SUCCESS (5.452s)
[695.444523] [141/142] (344s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25)
[697.417642] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25
[697.418638] Starting dynamic subtest: pipe-A-HDMI-A-3
[697.433368] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.014s)
[697.442326] Starting dynamic subtest: pipe-B-HDMI-A-3
[697.460771] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.024s)
[697.469614] Starting dynamic subtest: pipe-C-HDMI-A-3
[697.489421] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.024s)
[697.498151] Starting dynamic subtest: pipe-D-HDMI-A-3
[697.513915] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.024s)
[697.515906] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.097s)
[697.560136] [142/142] (342s left) gem_busy (parallel)
[697.765305] Starting subtest: parallel
[697.766181] Starting dynamic subtest: rcs0
[697.852207] Dynamic subtest rcs0: SUCCESS (0.086s)
[697.861861] Starting dynamic subtest: bcs0
[697.904237] Dynamic subtest bcs0: SUCCESS (0.051s)
[697.913875] Starting dynamic subtest: vcs0
[697.953197] Dynamic subtest vcs0: SUCCESS (0.048s)
[697.962890] Starting dynamic subtest: vcs1
[698.002143] Dynamic subtest vcs1: SUCCESS (0.048s)
[698.011985] Starting dynamic subtest: vecs0
[698.053270] Dynamic subtest vecs0: SUCCESS (0.050s)
[698.054244] Subtest parallel: SUCCESS (0.289s)
[698.223575] Closing watchdogs
Done.