[36.341971] Initializing watchdogs
[36.342061]   /dev/watchdog0
[36.349295] [001/137] (960s left) kms_cursor_crc (cursor-random-64x64)
[38.406951] Starting subtest: cursor-random-64x64
[38.418585] Starting dynamic subtest: pipe-A-HDMI-A-4
[43.081430] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.663s)
[43.104902] Starting dynamic subtest: pipe-B-HDMI-A-4
[47.861992] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (4.764s)
[47.888446] Starting dynamic subtest: pipe-C-HDMI-A-4
[53.002883] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (5.119s)
[53.030471] Starting dynamic subtest: pipe-D-HDMI-A-4
[58.496929] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (5.473s)
[58.498646] Subtest cursor-random-64x64: SUCCESS (20.088s)
[58.879193] [002/137] (937s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[60.862793] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[60.865878] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[60.925684] [003/137] (935s left) kms_big_fb (linear-64bpp-rotate-90)
[62.914129] Starting subtest: linear-64bpp-rotate-90
[62.979680] Subtest linear-64bpp-rotate-90: SKIP (0.059s)
[63.059221] [004/137] (933s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[65.025154] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[65.026962] Starting dynamic subtest: pipe-A-valid-mode
[66.870780] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.844s)
[66.872418] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SUCCESS (1.845s)
[66.980415] [005/137] (929s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[68.949117] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[68.952502] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[69.012612] [006/137] (927s left) kms_flip (blocking-wf_vblank)
[70.036360] Starting subtest: blocking-wf_vblank
[70.045750] Starting dynamic subtest: A-HDMI-A4
[78.240264] Dynamic subtest A-HDMI-A4: SUCCESS (8.194s)
[78.245860] Starting dynamic subtest: B-HDMI-A4
[86.365487] Dynamic subtest B-HDMI-A4: SUCCESS (8.125s)
[86.371412] Starting dynamic subtest: C-HDMI-A4
[94.484579] Dynamic subtest C-HDMI-A4: SUCCESS (8.119s)
[94.489985] Starting dynamic subtest: D-HDMI-A4
[102.599189] Dynamic subtest D-HDMI-A4: SUCCESS (8.114s)
[102.600793] Subtest blocking-wf_vblank: SUCCESS (32.564s)
[102.738598] [007/137] (894s left) gem_exec_reloc (basic-gtt)
[102.808382] Subtest basic-gtt: SKIP (0.000s)
[102.847896] [008/137] (894s left) gem_exec_schedule (semaphore-noskip)
[102.924463] Starting subtest: semaphore-noskip
[103.392669] Subtest semaphore-noskip: SUCCESS (0.465s)
[103.704461] [009/137] (893s left) i915_pm_rc6_residency (rc6-idle)
[103.771316] Starting subtest: rc6-idle
[103.775869] Starting dynamic subtest: rcs0
[115.990480] Dynamic subtest rcs0: SUCCESS (12.216s)
[115.996274] Starting dynamic subtest: bcs0
[128.321668] Dynamic subtest bcs0: FAIL (12.284s)
[128.331883] Starting dynamic subtest: vcs0
[140.525825] Dynamic subtest vcs0: SUCCESS (12.251s)
[140.531956] Starting dynamic subtest: vecs0
[152.775811] Dynamic subtest vecs0: SUCCESS (12.250s)
[152.777669] Subtest rc6-idle: FAIL (49.005s)
[153.083165] [010/137] (843s left) kms_scaling_modes (scaling-mode-full)
[155.048359] Starting subtest: scaling-mode-full
[155.051957] Subtest scaling-mode-full: SKIP (0.003s)
[155.112747] [011/137] (841s left) gem_busy (parallel)
[155.365964] Starting subtest: parallel
[155.368684] Starting dynamic subtest: rcs0
[155.457381] Dynamic subtest rcs0: SUCCESS (0.089s)
[155.470528] Starting dynamic subtest: bcs0
[155.531754] Dynamic subtest bcs0: SUCCESS (0.061s)
[155.567135] Starting dynamic subtest: vcs0
[155.615338] Dynamic subtest vcs0: SUCCESS (0.079s)
[155.639341] Starting dynamic subtest: vcs1
[155.671131] Dynamic subtest vcs1: SUCCESS (0.059s)
[155.699666] Starting dynamic subtest: vecs0
[155.741420] Dynamic subtest vecs0: SUCCESS (0.067s)
[155.760475] Subtest parallel: SUCCESS (0.358s)
[156.017389] [012/137] (841s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[156.125276] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[156.172776] [013/137] (840s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[158.139712] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[158.141609] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[158.212549] [014/137] (838s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[160.191834] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[160.193783] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[160.262421] [015/137] (836s left) gem_mmap_gtt (isolation)
[160.332225] Subtest isolation: SKIP (0.000s)
[160.356311] [016/137] (836s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[162.329426] Starting subtest: cursorB-vs-flipB-atomic
[162.331485] Subtest cursorB-vs-flipB-atomic: SKIP (0.002s)
[162.395422] [017/137] (834s left) sysfs_heartbeat_interval (mixed)
[162.466968] Starting subtest: mixed
[162.468113] Starting dynamic subtest: vecs0
[167.759680] Dynamic subtest vecs0: SUCCESS (5.292s)
[167.767828] Starting dynamic subtest: vcs0
[172.833136] Dynamic subtest vcs0: SUCCESS (5.073s)
[172.842150] Starting dynamic subtest: bcs0
[178.113532] Dynamic subtest bcs0: SUCCESS (5.280s)
[178.120303] Starting dynamic subtest: rcs0
[183.181199] Dynamic subtest rcs0: SUCCESS (5.067s)
[183.190111] Starting dynamic subtest: vcs1
[188.247171] Dynamic subtest vcs1: SUCCESS (5.065s)
[188.248637] Subtest mixed: SUCCESS (25.780s)
[188.329070] [018/137] (808s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_rc_ccs)
[190.299127] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs
[190.300085] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs: SUCCESS (0.000s)
[190.749342] [019/137] (806s left) kms_flip (absolute-wf_vblank-interruptible)
[191.755439] Starting subtest: absolute-wf_vblank-interruptible
[191.766550] Starting dynamic subtest: A-HDMI-A4
[199.643756] Dynamic subtest A-HDMI-A4: SUCCESS (7.877s)
[199.649035] Starting dynamic subtest: B-HDMI-A4
[207.469492] Dynamic subtest B-HDMI-A4: SUCCESS (7.825s)
[207.474832] Starting dynamic subtest: C-HDMI-A4
[215.273627] Dynamic subtest C-HDMI-A4: SUCCESS (7.804s)
[215.278850] Starting dynamic subtest: D-HDMI-A4
[223.070142] Dynamic subtest D-HDMI-A4: SUCCESS (7.796s)
[223.070996] Subtest absolute-wf_vblank-interruptible: SUCCESS (31.315s)
[223.206952] [020/137] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[225.176317] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[225.178508] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[225.256698] [021/137] (771s left) kms_plane_lowres (tiling-x)
[227.238396] Starting subtest: tiling-x
[227.241430] Starting dynamic subtest: pipe-A-HDMI-A-4
[235.608424] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (8.369s)
[235.651704] Starting dynamic subtest: pipe-B-HDMI-A-4
[243.868261] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (8.221s)
[243.914242] Starting dynamic subtest: pipe-C-HDMI-A-4
[252.116282] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (8.206s)
[252.161194] Starting dynamic subtest: pipe-D-HDMI-A-4
[260.353335] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (8.197s)
[260.391900] Subtest tiling-x: SUCCESS (33.153s)
[260.517157] [022/137] (736s left) tools_test (sysfs_l3_parity)
[260.582929] Starting subtest: sysfs_l3_parity
[260.670027] Subtest sysfs_l3_parity: SKIP (0.092s)
[260.717208] [023/137] (736s left) kms_getfb (getfb2-handle-closed)
[260.778921] Starting subtest: getfb2-handle-closed
[260.779765] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[260.860113] [024/137] (736s left) kms_vblank (pipe-c-wait-forked-busy-hang)
[262.829118] Starting subtest: pipe-C-wait-forked-busy-hang
[269.871430] Subtest pipe-C-wait-forked-busy-hang: SUCCESS (7.044s)
[269.985567] [025/137] (727s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[271.956458] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[274.737680] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (2.783s)
[274.871418] [026/137] (722s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[279.931526] Starting subtest: gem-execbuf-stress-pc8
[280.021335] Subtest gem-execbuf-stress-pc8: SKIP (0.089s)
[281.096840] [027/137] (716s left) gem_mmap_wc (write-read-distinct)
[281.157935] Subtest write-read-distinct: SKIP (0.000s)
[281.189734] [028/137] (716s left) gem_exec_params (no-vebox)
[281.254767] Starting subtest: no-vebox
[281.257079] Subtest no-vebox: SUCCESS (0.001s)
[281.363435] [029/137] (715s left) drm_fdinfo (busy-check-all)
[281.446172] Subtest busy-check-all: SKIP (0.000s)
[281.472603] [030/137] (715s left) gem_exec_schedule (preempt-queue-chain)
[281.545880] Subtest preempt-queue-chain: SKIP (0.000s)
[281.888825] [031/137] (715s left) v3d/v3d_submit_csd (job-perfmon)
[281.998749] Subtest job-perfmon: SKIP (0.000s)
[282.048091] [032/137] (715s left) gem_exec_gttfill (all-engines)
[282.120808] Starting subtest: all-engines
[308.728024] Subtest all-engines: SUCCESS (26.607s)
[308.976022] [033/137] (688s left) v3d/v3d_perfmon (create-perfmon-invalid-counters)
[309.111975] Subtest create-perfmon-invalid-counters: SKIP (0.000s)
[309.158871] [034/137] (688s left) kms_plane_scaling (plane-scaler-with-pixel-format-unity-scaling)
[311.127899] Starting subtest: plane-scaler-with-pixel-format-unity-scaling
[311.130848] Starting dynamic subtest: pipe-A-HDMI-A-4
[332.524566] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (21.395s)
[332.532147] Starting dynamic subtest: pipe-B-HDMI-A-4
[353.744459] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (21.218s)
[353.751772] Starting dynamic subtest: pipe-C-HDMI-A-4
[354.033283] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.287s)
[354.040726] Starting dynamic subtest: pipe-D-HDMI-A-4
[354.322253] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.287s)
[354.323121] Subtest plane-scaler-with-pixel-format-unity-scaling: SUCCESS (43.195s)
[354.461141] [035/137] (642s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[356.442312] Starting subtest: y-tiled-64bpp-rotate-90
[356.504581] Subtest y-tiled-64bpp-rotate-90: SKIP (0.061s)
[356.592934] [036/137] (640s left) kms_plane (plane-position-hole)
[358.561187] Starting subtest: plane-position-hole
[358.562335] Starting dynamic subtest: pipe-A-planes
[360.992340] Dynamic subtest pipe-A-planes: SUCCESS (2.431s)
[360.999813] Starting dynamic subtest: pipe-B-planes
[363.621916] Dynamic subtest pipe-B-planes: SUCCESS (2.626s)
[363.622943] Subtest plane-position-hole: SUCCESS (5.062s)
[363.762816] [037/137] (633s left) perf (polling-small-buf)
[363.876130] Starting subtest: polling-small-buf
[363.972663] Subtest polling-small-buf: SUCCESS (0.097s)
[363.994248] [038/137] (633s left) kms_psr (sprite_render)
[364.056401] Subtest sprite_render: SKIP (0.000s)
[364.089400] [039/137] (633s left) gem_softpin (evict-prime-sanity-check)
[364.154799] Starting subtest: evict-prime-sanity-check
[364.155682] Starting dynamic subtest: rcs0
[365.280209] Dynamic subtest rcs0: SUCCESS (1.125s)
[365.286463] Starting dynamic subtest: bcs0
[366.391228] Dynamic subtest bcs0: SUCCESS (1.111s)
[366.397470] Starting dynamic subtest: vcs0
[367.499304] Dynamic subtest vcs0: SUCCESS (1.107s)
[367.505874] Starting dynamic subtest: vcs1
[368.607280] Dynamic subtest vcs1: SUCCESS (1.107s)
[368.613398] Starting dynamic subtest: vecs0
[369.720921] Dynamic subtest vecs0: SUCCESS (1.113s)
[369.730407] Starting dynamic subtest: all
[370.830300] Dynamic subtest all: SUCCESS (1.108s)
[370.831218] Subtest evict-prime-sanity-check: SUCCESS (6.675s)
[370.920357] [040/137] (626s left) kms_3d
[373.702226] [041/137] (623s left) kms_vblank (pipe-a-ts-continuation-suspend)
[375.666862] Starting subtest: pipe-A-ts-continuation-suspend
[396.193104] Subtest pipe-A-ts-continuation-suspend: SUCCESS (9.255s)
[400.317421] [042/137] (611s left) core_setmaster (master-drop-set-user)
[401.001688] Starting subtest: master-drop-set-user
[401.025535] Subtest master-drop-set-user: SUCCESS (0.024s)
[401.040800] [043/137] (610s left) gem_lmem_swapping (massive-random)
[404.800290] Starting subtest: massive-random
[404.801088] Starting dynamic subtest: lmem0
[405.237907] Dynamic subtest lmem0: SUCCESS (0.436s)
[405.238794] Subtest massive-random: SUCCESS (0.437s)
[406.748946] [044/137] (605s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[412.166432] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[412.168311] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.003s)
[412.302126] [045/137] (599s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[414.275858] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[416.822683] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.548s)
[416.937656] [046/137] (594s left) gem_madvise (dontneed-before-pwrite)
[417.003111] Starting subtest: dontneed-before-pwrite
[417.006848] Subtest dontneed-before-pwrite: SKIP (0.002s)
[417.047945] [047/137] (594s left) kms_cursor_edge_walk (256x256-top-bottom)
[419.017430] Starting subtest: 256x256-top-bottom
[419.018517] Starting dynamic subtest: pipe-A-HDMI-A-4
[422.410782] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.394s)
[422.421252] Starting dynamic subtest: pipe-D-HDMI-A-4
[425.625645] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.213s)
[425.626583] Subtest 256x256-top-bottom: SUCCESS (6.610s)
[425.744890] [048/137] (586s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[427.921344] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[427.923298] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[428.173082] [049/137] (583s left) kms_invalid_mode (zero-vdisplay)
[430.140311] Starting subtest: zero-vdisplay
[430.141305] Starting dynamic subtest: HDMI-A-4-pipe-A
[430.196876] Dynamic subtest HDMI-A-4-pipe-A: SUCCESS (0.057s)
[430.210345] Starting dynamic subtest: HDMI-A-4-pipe-B
[430.222214] Dynamic subtest HDMI-A-4-pipe-B: SUCCESS (0.024s)
[430.234663] Starting dynamic subtest: HDMI-A-4-pipe-C
[430.248181] Dynamic subtest HDMI-A-4-pipe-C: SUCCESS (0.024s)
[430.258162] Starting dynamic subtest: HDMI-A-4-pipe-D
[430.273863] Dynamic subtest HDMI-A-4-pipe-D: SUCCESS (0.024s)
[430.274725] Subtest zero-vdisplay: SUCCESS (0.135s)
[430.373489] [050/137] (581s left) gem_exec_balancer (parallel-bb-first)
[430.671085] Starting subtest: parallel-bb-first
[431.954253] Subtest parallel-bb-first: SUCCESS (1.283s)
[432.027107] [051/137] (579s left) kms_legacy_colorkey (invalid-plane)
[433.993363] Starting subtest: invalid-plane
[433.994449] Starting dynamic subtest: zero-id
[433.995491] Dynamic subtest zero-id: SUCCESS (0.000s)
[434.003101] Starting dynamic subtest: outrange-id
[434.004295] Dynamic subtest outrange-id: SUCCESS (0.000s)
[434.005398] Subtest invalid-plane: SUCCESS (0.001s)
[434.056420] [052/137] (577s left) syncobj_wait (invalid-reset-one-illegal-handle)
[434.118581] Starting subtest: invalid-reset-one-illegal-handle
[434.119591] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.001s)
[434.175975] [053/137] (577s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[436.159037] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[436.160828] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[436.239105] [054/137] (575s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[438.217622] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[438.219655] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[438.289571] [055/137] (573s left) gem_mmap_gtt (cpuset-big-copy-xy)
[438.364991] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[438.398974] [056/137] (573s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_ccs)
[440.594080] Starting subtest: pipe-B-bad-aux-stride-y_tiled_ccs
[440.596093] Subtest pipe-B-bad-aux-stride-y_tiled_ccs: SKIP (0.000s)
[440.873788] [057/137] (571s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[442.846882] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[442.848669] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[442.940000] [058/137] (569s left) v3d/v3d_wait_bo (unused-bo-0ns)
[443.057276] Subtest unused-bo-0ns: SKIP (0.000s)
[443.107407] [059/137] (568s left) vgem_basic (debugfs)
[443.223166] Starting subtest: debugfs
[443.224105] Subtest debugfs: SUCCESS (0.000s)
[443.277584] [060/137] (568s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_rc_ccs)
[445.384146] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs
[445.386404] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[445.720000] [061/137] (566s left) vgem_basic (bad-handle)
[445.786676] Starting subtest: bad-handle
[445.787685] Subtest bad-handle: SUCCESS (0.000s)
[445.836746] [062/137] (566s left) kms_chamelium_hpd (vga-hpd)
[447.844361] Subtest vga-hpd: SKIP (0.000s)
[447.901286] [063/137] (564s left) kms_vblank (pipe-b-query-busy)
[449.867032] Starting subtest: pipe-B-query-busy
[452.679129] Subtest pipe-B-query-busy: SUCCESS (2.813s)
[452.787128] [064/137] (559s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[454.843022] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[454.844162] Subtest pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SUCCESS (0.000s)
[455.215985] [065/137] (556s left) syncobj_timeline (single-wait-for-submit-signaled)
[455.277829] Starting subtest: single-wait-for-submit-signaled
[455.278768] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[455.332644] [066/137] (556s left) gem_eio (in-flight-external)
[455.505445] Starting subtest: in-flight-external
[457.026601] Subtest in-flight-external: SUCCESS (1.521s)
[459.058687] [067/137] (553s left) gem_exec_fair (basic-none)
[459.128363] Subtest basic-none: SKIP (0.000s)
[459.177948] [068/137] (553s left) kms_chamelium_color (ctm-max)
[461.156864] Subtest ctm-max: SKIP (0.000s)
[461.192216] [069/137] (551s left) kms_vblank (pipe-a-wait-forked)
[463.158903] Starting subtest: pipe-A-wait-forked
[465.471073] Subtest pipe-A-wait-forked: SUCCESS (2.312s)
[465.568307] [070/137] (546s left) kms_dsc (dsc-basic)
[467.537041] Starting subtest: dsc-basic
[467.539059] Subtest dsc-basic: SKIP (0.000s)
[467.601708] [071/137] (544s left) gem_spin_batch (resubmit-new)
[467.670466] Starting subtest: resubmit-new
[467.671519] Starting dynamic subtest: rcs0
[467.688342] Dynamic subtest rcs0: SUCCESS (0.017s)
[467.696348] Starting dynamic subtest: bcs0
[467.697302] Dynamic subtest bcs0: SUCCESS (0.003s)
[467.705326] Starting dynamic subtest: vcs0
[467.706386] Dynamic subtest vcs0: SUCCESS (0.003s)
[467.714507] Starting dynamic subtest: vcs1
[467.715657] Dynamic subtest vcs1: SUCCESS (0.003s)
[467.723751] Starting dynamic subtest: vecs0
[467.724811] Dynamic subtest vecs0: SUCCESS (0.003s)
[467.725877] Subtest resubmit-new: SUCCESS (0.033s)
[467.988992] [072/137] (544s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[470.347565] Starting subtest: pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[472.113316] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SUCCESS (1.765s)
[472.280909] [073/137] (539s left) kms_big_joiner (basic)
[474.248816] Subtest basic: SKIP (0.000s)
[474.273926] [074/137] (538s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[476.242090] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[476.243979] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[476.393260] [075/137] (535s left) gem_exec_whisper (basic-queues)
[476.502681] Starting subtest: basic-queues
[481.150202] Subtest basic-queues: SUCCESS (4.647s)
[481.363384] [076/137] (531s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[481.458642] Starting subtest: saturated-hostile-nopreempt
[481.459578] Starting dynamic subtest: rcs0
[481.549566] Dynamic subtest rcs0: SUCCESS (0.090s)
[481.570466] Starting dynamic subtest: bcs0
[481.640485] Dynamic subtest bcs0: SUCCESS (0.089s)
[481.680891] Starting dynamic subtest: vcs0
[481.730314] Dynamic subtest vcs0: SUCCESS (0.088s)
[481.757763] Starting dynamic subtest: vcs1
[481.819140] Dynamic subtest vcs1: SUCCESS (0.082s)
[481.856724] Starting dynamic subtest: vecs0
[481.914768] Dynamic subtest vecs0: SUCCESS (0.098s)
[481.916651] Subtest saturated-hostile-nopreempt: SUCCESS (0.456s)
[482.045092] [077/137] (530s left) kms_vblank (pipe-b-ts-continuation-idle)
[484.039302] Starting subtest: pipe-B-ts-continuation-idle
[484.655422] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.617s)
[484.771026] [078/137] (527s left) kms_addfb_basic (basic-y-tiled-legacy)
[490.546291] Starting subtest: basic-y-tiled-legacy
[490.548281] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[490.637468] [079/137] (521s left) i915_pm_rps (engine-order)
[490.725784] Starting subtest: engine-order
[498.731561] Subtest engine-order: SUCCESS (8.000s)
[498.884824] [080/137] (513s left) kms_vblank (pipe-b-query-forked-busy-hang)
[500.852082] Starting subtest: pipe-B-query-forked-busy-hang
[514.610819] Subtest pipe-B-query-forked-busy-hang: SUCCESS (13.756s)
[514.719957] [081/137] (497s left) gem_wait (busy)
[514.791770] Starting subtest: busy
[514.792640] Starting dynamic subtest: all-engines
[515.349484] Dynamic subtest all-engines: SUCCESS (0.557s)
[515.357732] Starting dynamic subtest: rcs0
[515.887946] Dynamic subtest rcs0: SUCCESS (0.538s)
[515.896284] Starting dynamic subtest: bcs0
[516.426360] Dynamic subtest bcs0: SUCCESS (0.538s)
[516.436995] Starting dynamic subtest: vcs0
[516.966385] Dynamic subtest vcs0: SUCCESS (0.539s)
[516.977535] Starting dynamic subtest: vcs1
[517.506404] Dynamic subtest vcs1: SUCCESS (0.539s)
[517.517527] Starting dynamic subtest: vecs0
[518.046411] Dynamic subtest vecs0: SUCCESS (0.539s)
[518.047741] Subtest busy: SUCCESS (3.254s)
[518.256482] [082/137] (494s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[518.323683] Starting subtest: y-tiled-ccs-to-x-tiled
[518.324683] Starting dynamic subtest: smem
[518.403985] Dynamic subtest smem: SUCCESS (0.080s)
[518.412041] Starting dynamic subtest: lmem0
[519.332276] Dynamic subtest lmem0: SUCCESS (0.925s)
[519.333311] Subtest y-tiled-ccs-to-x-tiled: SUCCESS (1.006s)
[519.562683] [083/137] (492s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[519.627698] Starting subtest: x-tiled-to-vebox-yf-tiled
[519.628673] Starting dynamic subtest: smem
[519.702437] Dynamic subtest smem: SUCCESS (0.074s)
[519.710468] Starting dynamic subtest: lmem0
[520.459520] Dynamic subtest lmem0: SUCCESS (0.753s)
[520.460536] Subtest x-tiled-to-vebox-yf-tiled: SUCCESS (0.829s)
[520.680924] [084/137] (491s left) syncobj_timeline (multi-wait-all-available-submitted)
[520.740956] Starting subtest: multi-wait-all-available-submitted
[520.741814] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[520.806676] [085/137] (491s left) kms_properties (crtc-properties-legacy)
[522.787897] Starting subtest: crtc-properties-legacy
[523.835805] Subtest crtc-properties-legacy: SUCCESS (1.047s)
[523.967308] [086/137] (488s left) kms_big_fb (linear-16bpp-rotate-270)
[525.945007] Starting subtest: linear-16bpp-rotate-270
[526.009874] Subtest linear-16bpp-rotate-270: SKIP (0.064s)
[526.117190] [087/137] (486s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[528.090405] Starting subtest: yf-tiled-16bpp-rotate-90
[528.092446] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[528.185469] [088/137] (484s left) kms_panel_fitting (legacy)
[530.189923] Starting subtest: legacy
[530.191888] Subtest legacy: SKIP (0.000s)
[530.249427] [089/137] (482s left) gem_ctx_param (invalid-param-set)
[530.313278] Starting subtest: invalid-param-set
[530.314304] Subtest invalid-param-set: SUCCESS (0.000s)
[530.369537] [090/137] (482s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[531.398656] Starting subtest: 2x-flip-vs-modeset-vs-hang
[531.423538] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.025s)
[531.500712] [091/137] (481s left) kms_dsc (dsc-with-formats)
[533.499056] Starting subtest: dsc-with-formats
[533.500981] Subtest dsc-with-formats: SKIP (0.001s)
[533.565619] [092/137] (479s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[535.554101] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[536.080833] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.527s)
[536.202497] [093/137] (476s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[538.355867] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[538.359963] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.003s)
[538.632312] [094/137] (474s left) gem_ctx_persistence (heartbeat-close)
[538.697638] Starting subtest: heartbeat-close
[540.791895] Subtest heartbeat-close: FAIL (2.071s)
[540.891395] [095/137] (471s left) i915_hangman (engine-engine-hang)
[540.963558] Starting subtest: engine-engine-hang
[540.964501] Starting dynamic subtest: rcs0
[541.645556] Dynamic subtest rcs0: SUCCESS (0.681s)
[541.655140] Starting dynamic subtest: bcs0
[542.295289] Dynamic subtest bcs0: SUCCESS (0.649s)
[542.304061] Starting dynamic subtest: vcs0
[542.954380] Dynamic subtest vcs0: SUCCESS (0.658s)
[542.963421] Starting dynamic subtest: vcs1
[543.599310] Dynamic subtest vcs1: SUCCESS (0.644s)
[543.607910] Starting dynamic subtest: vecs0
[544.259417] Dynamic subtest vecs0: SUCCESS (0.659s)
[544.260398] Subtest engine-engine-hang: SUCCESS (3.296s)
[544.370928] [096/137] (468s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[546.341148] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[548.681306] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.341s)
[548.817274] [097/137] (463s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[550.789859] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[553.281710] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (2.494s)
[553.413959] [098/137] (459s left) gem_exec_flush (basic-wb-ro-before-default)
[553.483014] Subtest basic-wb-ro-before-default: SKIP (0.000s)
[553.523941] [099/137] (459s left) kms_universal_plane (cursor-fb-leak-pipe-d)
[555.498103] Starting subtest: cursor-fb-leak-pipe-D
[557.124968] Subtest cursor-fb-leak-pipe-D: SUCCESS (1.628s)
[557.236245] [100/137] (455s left) api_intel_bb (misplaced-blitter)
[557.298961] Starting subtest: misplaced-blitter
[557.316026] Subtest misplaced-blitter: SUCCESS (0.018s)
[557.418523] [101/137] (455s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[557.478962] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[557.479850] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[557.535184] [102/137] (455s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[559.512332] Starting subtest: 4-tiled-8bpp-rotate-180
[559.514170] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[559.603839] [103/137] (453s left) kms_chamelium_color (degamma)
[561.602544] Subtest degamma: SKIP (0.000s)
[561.629029] [104/137] (451s left) gem_ctx_param (basic-default)
[561.690721] Starting subtest: basic-default
[561.691738] Subtest basic-default: SUCCESS (0.000s)
[561.756920] [105/137] (451s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-cpu)
[563.754971] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-cpu
[563.756714] Subtest psr-1p-offscren-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[563.834496] [106/137] (449s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[563.905206] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[563.906162] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[563.989787] [107/137] (448s left) gem_pxp (create-valid-protected-context)
[566.586144] Subtest create-valid-protected-context: SKIP (0.000s)
[566.665380] [108/137] (446s left) syncobj_timeline (invalid-signal-zero-handles)
[566.726053] Starting subtest: invalid-signal-zero-handles
[566.727139] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[566.789834] [109/137] (446s left) gem_ctx_freq (sysfs)
[566.869586] Starting subtest: sysfs
[572.432306] Subtest sysfs: SUCCESS (5.562s)
[572.460991] [110/137] (440s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[573.010102] Starting subtest: syncobj-timeline-multiple-ext-nodes
[573.011340] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.002s)
[573.260685] [111/137] (439s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[575.232296] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[575.234254] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[575.317175] [112/137] (437s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[577.499682] Starting subtest: pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[577.500640] Subtest pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SUCCESS (0.000s)
[577.761318] [113/137] (435s left) kms_flip (basic-flip-vs-modeset)
[578.778752] Starting subtest: basic-flip-vs-modeset
[578.787858] Starting dynamic subtest: A-HDMI-A4
[579.584164] Dynamic subtest A-HDMI-A4: SUCCESS (0.796s)
[579.592869] Starting dynamic subtest: B-HDMI-A4
[580.201586] Dynamic subtest B-HDMI-A4: SUCCESS (0.617s)
[580.210353] Starting dynamic subtest: C-HDMI-A4
[580.898629] Dynamic subtest C-HDMI-A4: SUCCESS (0.696s)
[580.907042] Starting dynamic subtest: D-HDMI-A4
[581.553891] Dynamic subtest D-HDMI-A4: SUCCESS (0.654s)
[581.555037] Subtest basic-flip-vs-modeset: SUCCESS (2.776s)
[581.671906] [114/137] (431s left) i915_module_load (reload)
[581.733422] Starting subtest: reload
[585.145119] Subtest reload: SUCCESS (3.412s)
[585.162139] [115/137] (427s left) kms_draw_crc (fill-fb)
[587.185082] Starting subtest: fill-fb
[587.611459] Subtest fill-fb: SUCCESS (0.427s)
[587.695410] [116/137] (425s left) gem_exec_reloc (basic-range)
[587.766350] Subtest basic-range: SKIP (0.000s)
[587.821758] [117/137] (425s left) gem_exec_balancer (full-pulse)
[587.891936] Starting subtest: full-pulse
[589.427727] Subtest full-pulse: SUCCESS (1.536s)
[589.764808] [118/137] (423s left) syncobj_wait (invalid-signal-zero-handles)
[589.830285] Starting subtest: invalid-signal-zero-handles
[589.831266] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[589.900909] [119/137] (423s left) perf_pmu (busy-double-start)
[589.980273] Starting subtest: busy-double-start
[589.981197] Starting dynamic subtest: rcs0
[593.572755] Dynamic subtest rcs0: FAIL (3.576s)
[593.581026] Starting dynamic subtest: bcs0
[597.129275] Dynamic subtest bcs0: SUCCESS (3.572s)
[597.137350] Starting dynamic subtest: vcs0
[600.695222] Dynamic subtest vcs0: SUCCESS (3.565s)
[600.702774] Starting dynamic subtest: vcs1
[604.283354] Dynamic subtest vcs1: SUCCESS (3.587s)
[604.292689] Starting dynamic subtest: vecs0
[607.845246] Dynamic subtest vecs0: SUCCESS (3.561s)
[607.846049] Subtest busy-double-start: FAIL (17.865s)
[607.953138] [120/137] (405s left) fbdev (read)
[608.026624] Starting subtest: read
[609.468711] Subtest read: SUCCESS (1.439s)
[609.508543] [121/137] (403s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_rc_ccs)
[611.761509] Starting subtest: pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs
[611.763318] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[611.935553] [122/137] (401s left) kms_prop_blob (invalid-get-prop)
[611.996678] Starting subtest: invalid-get-prop
[611.997576] Subtest invalid-get-prop: SUCCESS (0.000s)
[612.052473] [123/137] (401s left) kms_psr (psr2_cursor_render)
[612.112584] Subtest psr2_cursor_render: SKIP (0.000s)
[612.146062] [124/137] (401s left) kms_vblank (invalid)
[614.113169] Starting subtest: invalid
[614.379424] Subtest invalid: SUCCESS (0.267s)
[614.505788] [125/137] (398s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[616.472567] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[616.474674] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[616.552844] [126/137] (396s left) kms_hdmi_inject (inject-audio)
[616.677702] Starting subtest: inject-audio
[616.779035] Subtest inject-audio: SUCCESS (0.101s)
[617.883606] [127/137] (395s left) kms_color (ctm-max)
[619.850080] Starting subtest: ctm-max
[619.851024] Starting dynamic subtest: pipe-A-HDMI-A-4
[620.455325] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.605s)
[620.468946] Starting dynamic subtest: pipe-B-HDMI-A-4
[621.253718] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.796s)
[621.265825] Starting dynamic subtest: pipe-C-HDMI-A-4
[622.428963] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (1.173s)
[622.439624] Starting dynamic subtest: pipe-D-HDMI-A-4
[623.972749] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (1.539s)
[623.974564] Subtest ctm-max: SUCCESS (4.123s)
[624.099074] [128/137] (389s left) kms_vblank (pipe-d-wait-forked-busy-hang)
[626.066266] Starting subtest: pipe-D-wait-forked-busy-hang
[640.628522] Subtest pipe-D-wait-forked-busy-hang: SUCCESS (14.561s)
[641.712132] [129/137] (371s left) syncobj_wait (single-wait-all-submitted)
[641.774096] Starting subtest: single-wait-all-submitted
[641.884780] Subtest single-wait-all-submitted: SUCCESS (0.106s)
[641.991715] [130/137] (371s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[643.986768] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[643.988836] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[644.062234] [131/137] (369s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[644.137007] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[644.242741] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.104s)
[644.340456] [132/137] (369s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[646.340733] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[646.342375] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[646.411167] [133/137] (367s left) kms_cursor_crc (cursor-offscreen-512x512)
[648.558768] Starting subtest: cursor-offscreen-512x512
[648.562266] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[648.763691] [134/137] (364s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[650.742914] Starting subtest: cursorB-vs-flipB-varying-size
[650.744907] Subtest cursorB-vs-flipB-varying-size: SKIP (0.002s)
[650.810634] [135/137] (362s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[652.789345] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[652.791384] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[652.877365] [136/137] (360s left) vc4/vc4_perfmon (get-values-invalid-perfmon)
[652.995106] Subtest get-values-invalid-perfmon: SKIP (0.000s)
[653.045312] [137/137] (360s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[655.014469] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[657.115293] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (2.101s)
[657.247034] Closing watchdogs
Done.