[36.145151] Initializing watchdogs
[36.145383]   /dev/watchdog0
[36.180465] [001/137] (960s left) kms_cursor_crc (cursor-random-64x64)
[36.590541] Starting subtest: cursor-random-64x64
[36.609210] Starting dynamic subtest: pipe-A-DP-1
[40.664135] Dynamic subtest pipe-A-DP-1: SUCCESS (4.042s)
[40.745746] Starting dynamic subtest: pipe-B-DP-1
[44.736558] Dynamic subtest pipe-B-DP-1: SUCCESS (4.006s)
[44.801296] Starting dynamic subtest: pipe-C-DP-1
[48.923087] Dynamic subtest pipe-C-DP-1: SUCCESS (4.115s)
[48.945615] Subtest cursor-random-64x64: SUCCESS (12.315s)
[49.305422] [002/137] (947s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[49.756490] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[49.797510] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[49.893241] [003/137] (946s left) kms_big_fb (linear-64bpp-rotate-90)
[50.313202] Starting subtest: linear-64bpp-rotate-90
[50.419339] Subtest linear-64bpp-rotate-90: SKIP (0.101s)
[50.539061] [004/137] (946s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[50.874612] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[50.897435] Starting dynamic subtest: pipe-A-valid-mode
[51.645965] Dynamic subtest pipe-A-valid-mode: SKIP (0.744s)
[51.689326] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.746s)
[52.076139] [005/137] (944s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[52.480570] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[52.520116] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[52.626381] [006/137] (944s left) kms_flip (blocking-wf_vblank)
[52.962802] Starting subtest: blocking-wf_vblank
[52.981588] Starting dynamic subtest: A-DP1
[63.790289] Dynamic subtest A-DP1: SUCCESS (10.823s)
[63.825772] Starting dynamic subtest: B-DP1
[74.749460] Dynamic subtest B-DP1: SUCCESS (10.792s)
[74.782347] Starting dynamic subtest: C-DP1
[85.300856] Dynamic subtest C-DP1: SUCCESS (10.725s)
[85.322258] Subtest blocking-wf_vblank: SUCCESS (32.347s)
[85.709625] [007/137] (911s left) gem_exec_reloc (basic-gtt)
[86.007772] Starting subtest: basic-gtt
[86.028511] Subtest basic-gtt: SUCCESS (0.025s)
[86.241171] [008/137] (911s left) gem_exec_schedule (semaphore-noskip)
[86.677855] Starting subtest: semaphore-noskip
[86.794067] Subtest semaphore-noskip: SUCCESS (0.259s)
[87.150323] [009/137] (910s left) i915_pm_rc6_residency (rc6-idle)
[87.396415] Starting subtest: rc6-idle
[87.415409] Starting dynamic subtest: rcs0
[99.834377] Dynamic subtest rcs0: SUCCESS (12.255s)
[99.868735] Starting dynamic subtest: bcs0
[112.072672] Dynamic subtest bcs0: SUCCESS (12.245s)
[112.106517] Starting dynamic subtest: vcs0
[124.320333] Dynamic subtest vcs0: SUCCESS (12.249s)
[124.353852] Starting dynamic subtest: vecs0
[136.581751] Dynamic subtest vecs0: SUCCESS (12.256s)
[136.602212] Subtest rc6-idle: SUCCESS (49.017s)
[136.702299] [010/137] (860s left) kms_scaling_modes (scaling-mode-full)
[137.035371] Starting subtest: scaling-mode-full
[137.078755] Subtest scaling-mode-full: SKIP (0.001s)
[137.184735] [011/137] (860s left) gem_busy (parallel)
[137.572596] Starting subtest: parallel
[137.591802] Starting dynamic subtest: rcs0
[137.630505] Dynamic subtest rcs0: SUCCESS (0.055s)
[137.663538] Starting dynamic subtest: bcs0
[137.685546] Dynamic subtest bcs0: SUCCESS (0.032s)
[137.723856] Starting dynamic subtest: vcs0
[137.744718] Dynamic subtest vcs0: SUCCESS (0.031s)
[137.777758] Starting dynamic subtest: vecs0
[137.796368] Dynamic subtest vecs0: SUCCESS (0.030s)
[137.814857] Subtest parallel: SUCCESS (0.148s)
[137.971750] [012/137] (859s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[138.207677] Starting subtest: basic-wc-cpu-noreloc
[138.228981] Subtest basic-wc-cpu-noreloc: SUCCESS (0.013s)
[138.441747] [013/137] (859s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[138.863300] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[138.909354] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[139.036678] [014/137] (858s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[139.474858] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[139.521611] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[139.637279] [015/137] (858s left) gem_mmap_gtt (isolation)
[139.887826] Starting subtest: isolation
[139.944201] Subtest isolation: SUCCESS (0.004s)
[140.030803] [016/137] (857s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[140.365769] Starting subtest: cursorB-vs-flipB-atomic
[140.403572] Subtest cursorB-vs-flipB-atomic: SKIP (0.002s)
[140.510463] [017/137] (857s left) sysfs_heartbeat_interval (mixed)
[140.760215] Starting subtest: mixed
[140.778361] Starting dynamic subtest: vecs0
[145.997806] Dynamic subtest vecs0: SUCCESS (5.236s)
[146.030443] Starting dynamic subtest: vcs0
[151.185831] Dynamic subtest vcs0: SUCCESS (5.182s)
[151.220184] Starting dynamic subtest: bcs0
[156.366144] Dynamic subtest bcs0: SUCCESS (5.179s)
[156.399822] Starting dynamic subtest: rcs0
[161.570483] Dynamic subtest rcs0: SUCCESS (5.205s)
[161.591132] Subtest mixed: SUCCESS (20.804s)
[161.671851] [018/137] (836s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_rc_ccs)
[162.031791] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs
[162.074579] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[162.680364] [019/137] (835s left) kms_flip (absolute-wf_vblank-interruptible)
[163.024855] Starting subtest: absolute-wf_vblank-interruptible
[163.046465] Starting dynamic subtest: A-DP1
[173.503957] Dynamic subtest A-DP1: SUCCESS (10.496s)
[173.536135] Starting dynamic subtest: B-DP1
[183.989433] Dynamic subtest B-DP1: SUCCESS (10.435s)
[184.021391] Starting dynamic subtest: C-DP1
[194.564081] Dynamic subtest C-DP1: SUCCESS (10.423s)
[194.585031] Subtest absolute-wf_vblank-interruptible: SUCCESS (31.361s)
[194.801067] [020/137] (803s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[195.234773] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[195.273982] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[195.374547] [021/137] (803s left) kms_plane_lowres (tiling-x)
[195.727868] Starting subtest: tiling-x
[195.748737] Starting dynamic subtest: pipe-A-DP-1
[201.624205] Dynamic subtest pipe-A-DP-1: SUCCESS (5.892s)
[201.758076] Starting dynamic subtest: pipe-B-DP-1
[207.487859] Dynamic subtest pipe-B-DP-1: SUCCESS (5.756s)
[207.633527] Starting dynamic subtest: pipe-C-DP-1
[211.453481] Dynamic subtest pipe-C-DP-1: SUCCESS (3.847s)
[211.536848] Subtest tiling-x: SUCCESS (15.809s)
[211.999853] [022/137] (786s left) tools_test (sysfs_l3_parity)
[212.394777] Starting subtest: sysfs_l3_parity
[212.534240] Subtest sysfs_l3_parity: SKIP (0.249s)
[212.635680] [023/137] (786s left) kms_getfb (getfb2-handle-closed)
[212.891847] Starting subtest: getfb2-handle-closed
[212.910780] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[213.013853] [024/137] (785s left) kms_vblank (pipe-c-wait-forked-busy-hang)
[213.341503] Starting subtest: pipe-C-wait-forked-busy-hang
[220.014141] Subtest pipe-C-wait-forked-busy-hang: SUCCESS (6.537s)
[220.252055] [025/137] (778s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[220.662715] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[222.893912] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (2.300s)
[223.102523] [026/137] (775s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[225.059097] Starting subtest: gem-execbuf-stress-pc8
[225.128772] Subtest gem-execbuf-stress-pc8: SKIP (0.040s)
[225.579137] [027/137] (773s left) gem_mmap_wc (write-read-distinct)
[225.828093] Starting subtest: write-read-distinct
[225.850036] Subtest write-read-distinct: SUCCESS (0.031s)
[225.975288] [028/137] (773s left) gem_exec_params (no-vebox)
[226.231397] Starting subtest: no-vebox
[226.250387] Subtest no-vebox: SUCCESS (0.002s)
[226.375751] [029/137] (772s left) drm_fdinfo (busy-check-all)
[226.620640] Starting subtest: busy-check-all
[226.639559] Starting dynamic subtest: rcs0
[227.156305] Dynamic subtest rcs0: SUCCESS (0.526s)
[227.190801] Starting dynamic subtest: bcs0
[227.678149] Dynamic subtest bcs0: SUCCESS (0.531s)
[227.710950] Starting dynamic subtest: vcs0
[228.214195] Dynamic subtest vcs0: SUCCESS (0.526s)
[228.248816] Starting dynamic subtest: vecs0
[228.735745] Dynamic subtest vecs0: SUCCESS (0.524s)
[228.754532] Subtest busy-check-all: SUCCESS (2.110s)
[228.865473] [030/137] (770s left) gem_exec_schedule (preempt-queue-chain)
[229.295217] Starting subtest: preempt-queue-chain
[229.316154] Starting dynamic subtest: rcs0
[229.617766] Dynamic subtest rcs0: SUCCESS (0.476s)
[229.664324] Starting dynamic subtest: bcs0
[230.133355] Dynamic subtest bcs0: SUCCESS (0.515s)
[230.175941] Starting dynamic subtest: vcs0
[230.655292] Dynamic subtest vcs0: SUCCESS (0.519s)
[230.695483] Starting dynamic subtest: vecs0
[231.215404] Dynamic subtest vecs0: SUCCESS (0.562s)
[231.234128] Subtest preempt-queue-chain: SUCCESS (2.076s)
[231.588302] [031/137] (767s left) v3d/v3d_submit_csd (job-perfmon)
[231.917177] Subtest job-perfmon: SKIP (0.000s)
[231.999300] [032/137] (767s left) gem_exec_gttfill (all-engines)
[232.273812] Starting subtest: all-engines
[274.085825] Subtest all-engines: SUCCESS (41.648s)
[274.180036] [033/137] (725s left) v3d/v3d_perfmon (create-perfmon-invalid-counters)
[274.646866] Subtest create-perfmon-invalid-counters: SKIP (0.000s)
[274.730340] [034/137] (724s left) kms_plane_scaling (plane-scaler-with-pixel-format-unity-scaling)
[275.129307] Starting subtest: plane-scaler-with-pixel-format-unity-scaling
[275.150060] Starting dynamic subtest: pipe-A-DP-1
[277.498745] Dynamic subtest pipe-A-DP-1: SUCCESS (2.369s)
[277.541765] Starting dynamic subtest: pipe-B-DP-1
[280.027743] Dynamic subtest pipe-B-DP-1: SUCCESS (2.546s)
[280.076204] Starting dynamic subtest: pipe-C-DP-1
[280.420108] Dynamic subtest pipe-C-DP-1: SUCCESS (0.369s)
[280.441239] Subtest plane-scaler-with-pixel-format-unity-scaling: SUCCESS (5.293s)
[280.829748] [035/137] (718s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[281.256576] Starting subtest: y-tiled-64bpp-rotate-90
[281.339346] Subtest y-tiled-64bpp-rotate-90: SKIP (0.076s)
[281.449196] [036/137] (718s left) kms_plane (plane-position-hole)
[281.779350] Starting subtest: plane-position-hole
[281.800056] Starting dynamic subtest: pipe-A-planes
[283.232239] Dynamic subtest pipe-A-planes: SUCCESS (1.441s)
[283.271332] Starting dynamic subtest: pipe-B-planes
[284.966496] Dynamic subtest pipe-B-planes: SUCCESS (1.731s)
[284.988459] Subtest plane-position-hole: SUCCESS (3.178s)
[285.325224] [037/137] (714s left) perf (polling-small-buf)
[285.703138] Starting subtest: polling-small-buf
[285.863570] Subtest polling-small-buf: SUCCESS (0.144s)
[286.007181] [038/137] (714s left) kms_psr (sprite_render)
[286.281836] Subtest sprite_render: SKIP (0.000s)
[286.384410] [039/137] (713s left) gem_softpin (evict-prime-sanity-check)
[286.650937] Starting subtest: evict-prime-sanity-check
[286.673416] Starting dynamic subtest: rcs0
[287.756774] Dynamic subtest rcs0: SUCCESS (1.107s)
[287.803920] Starting dynamic subtest: bcs0
[288.861878] Dynamic subtest bcs0: SUCCESS (1.105s)
[288.908691] Starting dynamic subtest: vcs0
[289.968082] Dynamic subtest vcs0: SUCCESS (1.111s)
[290.008146] Starting dynamic subtest: vecs0
[291.081040] Dynamic subtest vecs0: SUCCESS (1.109s)
[291.127175] Starting dynamic subtest: all
[292.202903] Dynamic subtest all: SUCCESS (1.119s)
[292.228740] Subtest evict-prime-sanity-check: SUCCESS (5.556s)
[292.337996] [040/137] (707s left) kms_3d
[292.737180] [041/137] (707s left) kms_vblank (pipe-a-ts-continuation-suspend)
[293.077044] Starting subtest: pipe-A-ts-continuation-suspend
[310.494360] Subtest pipe-A-ts-continuation-suspend: SUCCESS (1.848s)
[316.839569] [042/137] (705s left) core_setmaster (master-drop-set-user)
[318.701484] Starting subtest: master-drop-set-user
[318.746236] Subtest master-drop-set-user: SUCCESS (0.036s)
[318.837042] [043/137] (703s left) gem_lmem_swapping (massive-random)
[319.105952] Subtest massive-random: SKIP (0.000s)
[319.195518] [044/137] (703s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[319.872708] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[319.911174] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.004s)
[320.229284] [045/137] (702s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[320.651060] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[322.693973] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.125s)
[322.855427] [046/137] (699s left) gem_madvise (dontneed-before-pwrite)
[323.096255] Starting subtest: dontneed-before-pwrite
[323.115380] Subtest dontneed-before-pwrite: SUCCESS (0.004s)
[323.208029] [047/137] (699s left) kms_cursor_edge_walk (256x256-top-bottom)
[323.542217] Starting subtest: 256x256-top-bottom
[323.563037] Starting dynamic subtest: pipe-A-DP-1
[327.378665] Dynamic subtest pipe-A-DP-1: SUCCESS (3.856s)
[327.417869] Starting dynamic subtest: pipe-C-DP-1
[330.864898] Dynamic subtest pipe-C-DP-1: SUCCESS (3.488s)
[330.884860] Subtest 256x256-top-bottom: SUCCESS (7.349s)
[331.224618] [048/137] (691s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[331.840502] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[331.883204] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[332.279702] [049/137] (690s left) kms_invalid_mode (zero-vdisplay)
[332.636978] Starting subtest: zero-vdisplay
[332.655758] Starting dynamic subtest: DP-1-pipe-A
[332.722952] Dynamic subtest DP-1-pipe-A: SUCCESS (0.103s)
[332.765947] Starting dynamic subtest: DP-1-pipe-B
[332.788046] Dynamic subtest DP-1-pipe-B: SUCCESS (0.013s)
[332.834614] Starting dynamic subtest: DP-1-pipe-C
[332.855462] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[332.874737] Subtest zero-vdisplay: SUCCESS (0.136s)
[333.121705] [050/137] (689s left) gem_exec_balancer (parallel-bb-first)
[337.344145] Subtest parallel-bb-first: SKIP (0.000s)
[337.435057] [051/137] (685s left) kms_legacy_colorkey (invalid-plane)
[337.741407] Starting subtest: invalid-plane
[337.762733] Starting dynamic subtest: zero-id
[337.783215] Dynamic subtest zero-id: SUCCESS (0.000s)
[337.823392] Starting dynamic subtest: outrange-id
[337.842025] Dynamic subtest outrange-id: SUCCESS (0.000s)
[337.860614] Subtest invalid-plane: SUCCESS (0.002s)
[337.975999] [052/137] (684s left) syncobj_wait (invalid-reset-one-illegal-handle)
[338.225241] Starting subtest: invalid-reset-one-illegal-handle
[338.244253] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.002s)
[338.339792] [053/137] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[338.723304] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[338.763404] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[338.880237] [054/137] (684s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[339.291362] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[339.332556] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[339.439039] [055/137] (683s left) gem_mmap_gtt (cpuset-big-copy-xy)
[339.682498] Starting subtest: cpuset-big-copy-XY
[364.361894] Subtest cpuset-big-copy-XY: SUCCESS (24.673s)
[364.453551] [056/137] (658s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_ccs)
[365.196270] Starting subtest: pipe-B-bad-aux-stride-y_tiled_ccs
[365.216002] Subtest pipe-B-bad-aux-stride-y_tiled_ccs: SUCCESS (0.004s)
[365.643883] [057/137] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[366.057670] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[366.113383] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[366.247386] [058/137] (657s left) v3d/v3d_wait_bo (unused-bo-0ns)
[366.635254] Subtest unused-bo-0ns: SKIP (0.000s)
[366.771375] [059/137] (656s left) vgem_basic (debugfs)
[367.142337] Starting subtest: debugfs
[367.165070] Subtest debugfs: SUCCESS (0.002s)
[367.304282] [060/137] (656s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_rc_ccs)
[367.818733] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs
[367.861004] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[368.333153] [061/137] (655s left) vgem_basic (bad-handle)
[368.598317] Starting subtest: bad-handle
[368.617237] Subtest bad-handle: SUCCESS (0.000s)
[368.729067] [062/137] (655s left) kms_chamelium_hpd (vga-hpd)
[369.159875] Subtest vga-hpd: SKIP (0.000s)
[369.491345] [063/137] (654s left) kms_vblank (pipe-b-query-busy)
[369.821688] Starting subtest: pipe-B-query-busy
[372.652064] Subtest pipe-B-query-busy: SUCCESS (2.833s)
[373.020496] [064/137] (651s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[373.492899] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[373.530188] Subtest pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[374.044865] [065/137] (650s left) syncobj_timeline (single-wait-for-submit-signaled)
[374.291862] Starting subtest: single-wait-for-submit-signaled
[374.314209] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[374.424942] [066/137] (649s left) gem_eio (in-flight-external)
[374.717005] Starting subtest: in-flight-external
[374.778673] Subtest in-flight-external: SUCCESS (0.077s)
[375.011360] [067/137] (649s left) gem_exec_fair (basic-none)
[375.433532] Starting subtest: basic-none
[375.452263] Starting dynamic subtest: rcs0
[380.791799] Dynamic subtest rcs0: SUCCESS (5.522s)
[380.832040] Starting dynamic subtest: bcs0
[380.869283] Dynamic subtest bcs0: SKIP (0.008s)
[380.910387] Starting dynamic subtest: vcs0
[386.839365] Dynamic subtest vcs0: SUCCESS (6.036s)
[386.886163] Starting dynamic subtest: vecs0
[392.752118] Dynamic subtest vecs0: SUCCESS (5.909s)
[392.771269] Subtest basic-none: SUCCESS (17.482s)
[392.973886] [068/137] (631s left) kms_chamelium_color (ctm-max)
[393.324139] Subtest ctm-max: SKIP (0.000s)
[393.437804] [069/137] (631s left) kms_vblank (pipe-a-wait-forked)
[393.762226] Starting subtest: pipe-A-wait-forked
[396.091604] Subtest pipe-A-wait-forked: SUCCESS (2.311s)
[396.452959] [070/137] (628s left) kms_dsc (dsc-basic)
[396.807961] Subtest dsc-basic: SKIP (0.000s)
[396.924127] [071/137] (627s left) gem_spin_batch (resubmit-new)
[397.184349] Starting subtest: resubmit-new
[397.205943] Starting dynamic subtest: rcs0
[397.227415] Dynamic subtest rcs0: SUCCESS (0.009s)
[397.268174] Starting dynamic subtest: bcs0
[397.288778] Dynamic subtest bcs0: SUCCESS (0.009s)
[397.330423] Starting dynamic subtest: vcs0
[397.349124] Dynamic subtest vcs0: SUCCESS (0.007s)
[397.388263] Starting dynamic subtest: vecs0
[397.406995] Dynamic subtest vecs0: SUCCESS (0.007s)
[397.428337] Subtest resubmit-new: SUCCESS (0.037s)
[397.542674] [072/137] (627s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[398.212310] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[398.543204] [073/137] (626s left) kms_big_joiner (basic)
[398.894246] Subtest basic: SKIP (0.000s)
[399.005461] [074/137] (626s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[399.416265] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[399.453516] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[399.568483] [075/137] (625s left) gem_exec_whisper (basic-queues)
[399.849385] Starting subtest: basic-queues
[406.755296] Subtest basic-queues: SUCCESS (6.912s)
[406.959476] [076/137] (618s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[407.211966] Starting subtest: saturated-hostile-nopreempt
[407.230962] Starting dynamic subtest: rcs0
[407.332881] Dynamic subtest rcs0: SUCCESS (0.116s)
[407.380887] Starting dynamic subtest: bcs0
[407.445960] Dynamic subtest bcs0: SUCCESS (0.118s)
[407.486821] Starting dynamic subtest: vcs0
[407.578384] Dynamic subtest vcs0: SUCCESS (0.121s)
[407.624301] Starting dynamic subtest: vecs0
[407.687272] Dynamic subtest vecs0: SUCCESS (0.119s)
[407.706032] Subtest saturated-hostile-nopreempt: SUCCESS (0.479s)
[407.826117] [077/137] (617s left) kms_vblank (pipe-b-ts-continuation-idle)
[408.152560] Starting subtest: pipe-B-ts-continuation-idle
[408.768486] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.609s)
[409.131363] [078/137] (616s left) kms_addfb_basic (basic-y-tiled-legacy)
[409.710707] Starting subtest: basic-y-tiled-legacy
[409.729216] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[409.857282] [079/137] (615s left) i915_pm_rps (engine-order)
[410.104670] Starting subtest: engine-order
[425.559117] Subtest engine-order: FAIL (15.032s)
[425.750056] [080/137] (600s left) kms_vblank (pipe-b-query-forked-busy-hang)
[426.069520] Starting subtest: pipe-B-query-forked-busy-hang
[433.228239] Subtest pipe-B-query-forked-busy-hang: SUCCESS (7.039s)
[433.493249] [081/137] (592s left) gem_wait (busy)
[433.769123] Starting subtest: busy
[433.788048] Starting dynamic subtest: all-engines
[434.308816] Dynamic subtest all-engines: SUCCESS (0.525s)
[434.353576] Starting dynamic subtest: rcs0
[434.821187] Dynamic subtest rcs0: SUCCESS (0.516s)
[434.859657] Starting dynamic subtest: bcs0
[435.342728] Dynamic subtest bcs0: SUCCESS (0.513s)
[435.383122] Starting dynamic subtest: vcs0
[435.851956] Dynamic subtest vcs0: SUCCESS (0.518s)
[435.890646] Starting dynamic subtest: vecs0
[436.376659] Dynamic subtest vecs0: SUCCESS (0.515s)
[436.397390] Subtest busy: SUCCESS (2.593s)
[436.617662] [082/137] (589s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[436.898678] Starting subtest: y-tiled-ccs-to-x-tiled
[436.916948] Starting dynamic subtest: smem
[437.204379] Dynamic subtest smem: SUCCESS (0.328s)
[437.225948] Subtest y-tiled-ccs-to-x-tiled: SUCCESS (0.330s)
[437.435458] [083/137] (588s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[437.700671] Starting subtest: x-tiled-to-vebox-yf-tiled
[437.738282] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[437.908658] [084/137] (588s left) syncobj_timeline (multi-wait-all-available-submitted)
[438.145890] Starting subtest: multi-wait-all-available-submitted
[438.167498] Subtest multi-wait-all-available-submitted: SUCCESS (0.002s)
[438.263463] [085/137] (587s left) kms_properties (crtc-properties-legacy)
[438.605276] Starting subtest: crtc-properties-legacy
[440.106508] Subtest crtc-properties-legacy: SUCCESS (1.501s)
[440.475650] [086/137] (585s left) kms_big_fb (linear-16bpp-rotate-270)
[440.857896] Starting subtest: linear-16bpp-rotate-270
[440.944669] Subtest linear-16bpp-rotate-270: SKIP (0.075s)
[441.068174] [087/137] (585s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[441.468549] Starting subtest: yf-tiled-16bpp-rotate-90
[441.543855] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.080s)
[441.666764] [088/137] (584s left) kms_panel_fitting (legacy)
[442.003806] Starting subtest: legacy
[442.041130] Subtest legacy: SKIP (0.000s)
[442.161543] [089/137] (584s left) gem_ctx_param (invalid-param-set)
[442.398434] Starting subtest: invalid-param-set
[442.420627] Subtest invalid-param-set: SUCCESS (0.000s)
[442.536416] [090/137] (584s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[442.878349] Starting subtest: 2x-flip-vs-modeset-vs-hang
[442.916180] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.003s)
[443.037373] [091/137] (583s left) kms_dsc (dsc-with-formats)
[443.369553] Subtest dsc-with-formats: SKIP (0.000s)
[443.485501] [092/137] (583s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[443.855509] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[444.900888] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.069s)
[445.263751] [093/137] (581s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[445.826417] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[445.866863] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.005s)
[446.295837] [094/137] (580s left) gem_ctx_persistence (heartbeat-close)
[446.533040] Starting subtest: heartbeat-close
[446.683160] Subtest heartbeat-close: SUCCESS (0.152s)
[446.824385] [095/137] (580s left) i915_hangman (engine-engine-hang)
[447.094157] Starting subtest: engine-engine-hang
[447.115885] Starting dynamic subtest: rcs0
[447.685583] Dynamic subtest rcs0: SUCCESS (0.585s)
[447.728128] Starting dynamic subtest: bcs0
[448.273622] Dynamic subtest bcs0: SUCCESS (0.591s)
[448.318888] Starting dynamic subtest: vcs0
[448.862533] Dynamic subtest vcs0: SUCCESS (0.586s)
[448.909264] Starting dynamic subtest: vecs0
[449.451181] Dynamic subtest vecs0: SUCCESS (0.595s)
[449.472540] Subtest engine-engine-hang: SUCCESS (2.361s)
[449.589533] [096/137] (577s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[449.979068] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[451.985184] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.067s)
[452.138198] [097/137] (575s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[452.580867] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[454.776646] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (2.295s)
[454.946063] [098/137] (572s left) gem_exec_flush (basic-wb-ro-before-default)
[455.265654] Starting subtest: basic-wb-ro-before-default
[460.885938] Subtest basic-wb-ro-before-default: SUCCESS (5.454s)
[461.015239] [099/137] (566s left) kms_universal_plane (cursor-fb-leak-pipe-d)
[461.398094] Subtest cursor-fb-leak-pipe-D: SKIP (0.000s)
[461.583675] [100/137] (566s left) api_intel_bb (misplaced-blitter)
[461.866032] Starting subtest: misplaced-blitter
[461.888339] Subtest misplaced-blitter: SUCCESS (0.018s)
[462.016344] [101/137] (565s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[462.276954] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[462.298801] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[462.405407] [102/137] (565s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[462.790754] Starting subtest: 4-tiled-8bpp-rotate-180
[462.835193] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[462.958380] [103/137] (565s left) kms_chamelium_color (degamma)
[463.311041] Subtest degamma: SKIP (0.000s)
[463.422725] [104/137] (564s left) gem_ctx_param (basic-default)
[463.663655] Starting subtest: basic-default
[463.683731] Subtest basic-default: SUCCESS (0.000s)
[463.776499] [105/137] (564s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-cpu)
[464.190278] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-cpu
[464.227452] Subtest psr-1p-offscren-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[464.345050] [106/137] (563s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[464.585926] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[464.607512] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[464.702007] [107/137] (563s left) gem_pxp (create-valid-protected-context)
[467.634336] Subtest create-valid-protected-context: SKIP (0.000s)
[467.787672] [108/137] (560s left) syncobj_timeline (invalid-signal-zero-handles)
[468.028302] Starting subtest: invalid-signal-zero-handles
[468.048562] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[468.146057] [109/137] (560s left) gem_ctx_freq (sysfs)
[468.387409] Starting subtest: sysfs
[473.355335] Subtest sysfs: SUCCESS (4.962s)
[473.446095] [110/137] (555s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[474.264043] Starting subtest: syncobj-timeline-multiple-ext-nodes
[474.283112] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s)
[474.523459] [111/137] (554s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[474.940502] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[474.981936] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[475.095965] [112/137] (553s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[475.678750] Starting subtest: pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[475.721071] Subtest pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[476.119388] [113/137] (552s left) kms_flip (basic-flip-vs-modeset)
[476.445455] Starting subtest: basic-flip-vs-modeset
[476.464101] Starting dynamic subtest: A-DP1
[477.696057] Dynamic subtest A-DP1: SUCCESS (1.265s)
[477.736090] Starting dynamic subtest: B-DP1
[478.833314] Dynamic subtest B-DP1: SUCCESS (1.119s)
[478.875842] Starting dynamic subtest: C-DP1
[479.961217] Dynamic subtest C-DP1: SUCCESS (1.128s)
[479.980375] Subtest basic-flip-vs-modeset: SUCCESS (3.521s)
[480.335462] [114/137] (548s left) i915_module_load (reload)
[480.589400] Starting subtest: reload
[483.762974] Subtest reload: SUCCESS (3.183s)
[483.949117] [115/137] (545s left) kms_draw_crc (fill-fb)
[484.396075] Starting subtest: fill-fb
[484.842938] Subtest fill-fb: SUCCESS (0.457s)
[484.999644] [116/137] (544s left) gem_exec_reloc (basic-range)
[485.278037] Starting subtest: basic-range
[485.353183] Subtest basic-range: SUCCESS (0.089s)
[485.586699] [117/137] (543s left) gem_exec_balancer (full-pulse)
[485.884714] Starting subtest: full-pulse
[490.015871] Subtest full-pulse: SUCCESS (3.951s)
[493.847680] [118/137] (535s left) syncobj_wait (invalid-signal-zero-handles)
[494.086084] Starting subtest: invalid-signal-zero-handles
[494.108063] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[494.200554] [119/137] (535s left) perf_pmu (busy-double-start)
[494.453933] Starting subtest: busy-double-start
[494.475429] Starting dynamic subtest: rcs0
[498.012427] Dynamic subtest rcs0: SUCCESS (3.551s)
[498.051245] Starting dynamic subtest: bcs0
[501.570087] Dynamic subtest bcs0: SUCCESS (3.553s)
[501.609063] Starting dynamic subtest: vcs0
[505.117543] Dynamic subtest vcs0: SUCCESS (3.546s)
[505.156516] Starting dynamic subtest: vecs0
[508.665826] Dynamic subtest vecs0: SUCCESS (3.543s)
[508.686602] Subtest busy-double-start: SUCCESS (14.200s)
[508.777112] [120/137] (521s left) fbdev (read)
[509.029475] Starting subtest: read
[510.431510] Subtest read: SUCCESS (1.410s)
[510.542700] [121/137] (519s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_rc_ccs)
[511.214491] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[511.542880] [122/137] (518s left) kms_prop_blob (invalid-get-prop)
[511.799233] Starting subtest: invalid-get-prop
[511.820006] Subtest invalid-get-prop: SUCCESS (0.000s)
[511.926749] [123/137] (518s left) kms_psr (psr2_cursor_render)
[512.194106] Subtest psr2_cursor_render: SKIP (0.000s)
[512.306178] [124/137] (517s left) kms_vblank (invalid)
[512.636898] Starting subtest: invalid
[512.904368] Subtest invalid: SUCCESS (0.252s)
[513.267136] [125/137] (517s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[513.677596] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[513.719434] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[513.827730] [126/137] (516s left) kms_hdmi_inject (inject-audio)
[514.097641] Subtest inject-audio: SKIP (0.000s)
[514.208247] [127/137] (516s left) kms_color (ctm-max)
[514.541817] Starting subtest: ctm-max
[514.562497] Starting dynamic subtest: pipe-A-DP-1
[515.054226] Dynamic subtest pipe-A-DP-1: SUCCESS (0.503s)
[515.105879] Starting dynamic subtest: pipe-B-DP-1
[515.849821] Dynamic subtest pipe-B-DP-1: SUCCESS (0.793s)
[515.896235] Starting dynamic subtest: pipe-C-DP-1
[516.771060] Dynamic subtest pipe-C-DP-1: SUCCESS (0.924s)
[516.792575] Subtest ctm-max: SUCCESS (2.232s)
[517.141683] [128/137] (513s left) kms_vblank (pipe-d-wait-forked-busy-hang)
[517.521194] Subtest pipe-D-wait-forked-busy-hang: SKIP (0.000s)
[517.668706] [129/137] (513s left) syncobj_wait (single-wait-all-submitted)
[517.914674] Starting subtest: single-wait-all-submitted
[518.016997] Subtest single-wait-all-submitted: SUCCESS (0.106s)
[518.142665] [130/137] (512s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[518.531098] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[518.571006] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[518.684752] [131/137] (512s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[518.927430] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[519.021021] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.105s)
[519.153606] [132/137] (511s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[519.565715] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[519.603048] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[519.719605] [133/137] (511s left) kms_cursor_crc (cursor-offscreen-512x512)
[520.079760] Starting subtest: cursor-offscreen-512x512
[520.116926] Subtest cursor-offscreen-512x512: SKIP (0.003s)
[520.232843] [134/137] (510s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[520.569779] Starting subtest: cursorB-vs-flipB-varying-size
[520.607147] Subtest cursorB-vs-flipB-varying-size: SKIP (0.002s)
[520.725418] [135/137] (510s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[521.110139] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[521.149895] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[521.266266] [136/137] (510s left) vc4/vc4_perfmon (get-values-invalid-perfmon)
[521.590681] Subtest get-values-invalid-perfmon: SKIP (0.000s)
[521.697585] [137/137] (509s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[522.098830] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[523.858029] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (1.840s)
[524.000553] Closing watchdogs
Done.