[41.017377] Initializing watchdogs
[41.017612]   /dev/watchdog0
[41.033716] [001/142] (960s left) gem_ctx_persistence (process)
[41.284337] Starting subtest: process
[41.432719] Subtest process: SUCCESS (0.148s)
[41.523702] [002/142] (960s left) syncobj_wait (multi-wait-all-submitted)
[41.730680] Starting subtest: multi-wait-all-submitted
[41.839635] Subtest multi-wait-all-submitted: SUCCESS (0.108s)
[41.921134] [003/142] (959s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[42.238055] Starting subtest: psr-2p-primscrn-spr-indfb-move
[42.240615] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[42.322081] [004/142] (959s left) kms_draw_crc (draw-method-blt)
[42.635427] Starting subtest: draw-method-blt
[42.636666] Starting dynamic subtest: xrgb8888-untiled
[42.988872] Dynamic subtest xrgb8888-untiled: SUCCESS (0.354s)
[43.002958] Starting dynamic subtest: rgb565-untiled
[43.287726] Dynamic subtest rgb565-untiled: SUCCESS (0.300s)
[43.301135] Starting dynamic subtest: xrgb2101010-untiled
[43.607541] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.316s)
[43.621905] Starting dynamic subtest: xrgb8888-xtiled
[43.788475] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.183s)
[43.804416] Starting dynamic subtest: rgb565-xtiled
[43.958223] Dynamic subtest rgb565-xtiled: SUCCESS (0.167s)
[43.992752] Starting dynamic subtest: xrgb2101010-xtiled
[44.154440] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.199s)
[44.170955] Starting dynamic subtest: xrgb8888-ytiled
[44.354585] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.200s)
[44.370554] Starting dynamic subtest: rgb565-ytiled
[44.539537] Dynamic subtest rgb565-ytiled: SUCCESS (0.185s)
[44.558975] Starting dynamic subtest: xrgb2101010-ytiled
[44.724497] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.183s)
[44.726148] Subtest draw-method-blt: SUCCESS (2.090s)
[44.839778] [005/142] (956s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[45.049379] Starting subtest: single-wait-all-for-submit-signaled
[45.052233] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[45.121876] [006/142] (956s left) debugfs_test (read_all_entries_display_off)
[45.474042] Starting subtest: read_all_entries_display_off
[45.641079] Subtest read_all_entries_display_off: SUCCESS (0.168s)
[45.945843] [007/142] (955s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[46.353997] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[46.356735] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.001s)
[46.931942] [008/142] (954s left) gem_barrier_race (remote-request)
[47.182470] Starting subtest: remote-request
[47.184083] Starting dynamic subtest: rcs0
[52.351074] Dynamic subtest rcs0: SUCCESS (5.169s)
[52.352486] Subtest remote-request: SUCCESS (5.169s)
[52.468155] [009/142] (949s left) i915_pm_rpm (legacy-planes-dpms)
[54.617689] Starting subtest: legacy-planes-dpms
[54.620321] Starting dynamic subtest: plane-39
[57.487406] Dynamic subtest plane-39: SUCCESS (2.869s)
[57.509567] Starting dynamic subtest: plane-47
[60.368273] Dynamic subtest plane-47: SUCCESS (2.881s)
[60.370975] Subtest legacy-planes-dpms: SUCCESS (5.751s)
[60.797272] [010/142] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[61.114357] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[61.116921] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[61.211237] [011/142] (940s left) gem_exec_nop (basic-sequential)
[61.440763] Starting subtest: basic-sequential
[64.557207] Subtest basic-sequential: SUCCESS (3.116s)
[64.778429] [012/142] (937s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[65.448649] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[65.451889] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[65.808373] [013/142] (936s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[66.125796] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[66.128344] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[66.225562] [014/142] (935s left) gem_exec_balancer (hog)
[66.455447] Starting subtest: hog
[70.176302] Subtest hog: SUCCESS (3.720s)
[74.140991] [015/142] (927s left) syncobj_basic (bad-pad-fd-to-handle)
[74.343917] Starting subtest: bad-pad-fd-to-handle
[74.345349] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[74.423619] [016/142] (927s left) gem_userptr_blits (access-control)
[74.698774] Starting subtest: access-control
[74.701637] Subtest access-control: SKIP (0.000s)
[74.807945] [017/142] (927s left) kms_flip (basic-flip-vs-wf_vblank)
[75.096847] Starting subtest: basic-flip-vs-wf_vblank
[75.098463] Starting dynamic subtest: A-DP1
[76.591667] Dynamic subtest A-DP1: SUCCESS (1.496s)
[76.607400] Starting dynamic subtest: B-DP1
[78.020173] Dynamic subtest B-DP1: SUCCESS (1.426s)
[78.035815] Starting dynamic subtest: C-DP1
[79.431395] Dynamic subtest C-DP1: SUCCESS (1.410s)
[79.432643] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.340s)
[79.873538] [018/142] (922s left) dmabuf_sync_file (import-multiple-read-only)
[80.168386] Starting subtest: import-multiple-read-only
[80.179096] Subtest import-multiple-read-only: SUCCESS (0.011s)
[80.253900] [019/142] (921s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[80.556758] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[80.558122] Starting dynamic subtest: pipe-A-valid-mode
[80.651047] Dynamic subtest pipe-A-valid-mode: SKIP (0.093s)
[80.654080] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.095s)
[80.962474] [020/142] (921s left) gem_exec_schedule (semaphore-user)
[81.203398] Starting subtest: semaphore-user
[81.221718] Subtest semaphore-user: SUCCESS (0.018s)
[81.563774] [021/142] (920s left) gem_exec_flush (basic-batch-kernel-default-wb)
[81.790046] Starting subtest: basic-batch-kernel-default-wb
[87.540770] Subtest basic-batch-kernel-default-wb: SUCCESS (5.750s)
[87.745562] [022/142] (914s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_mc_ccs)
[88.416440] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[88.731102] [023/142] (913s left) kms_chamelium_frames (hdmi-frame-dump)
[89.128232] Subtest hdmi-frame-dump: SKIP (0.000s)
[89.399093] [024/142] (912s left) drm_read (empty-block)
[89.826054] Starting subtest: empty-block
[90.828376] Subtest empty-block: SUCCESS (1.001s)
[90.946616] [025/142] (911s left) gem_exec_params (invalid-fence-in)
[91.159311] Starting subtest: invalid-fence-in
[91.161275] Subtest invalid-fence-in: SUCCESS (0.001s)
[91.280129] [026/142] (911s left) gem_exec_schedule (preempt-queue-contexts-chain)
[91.515936] Starting subtest: preempt-queue-contexts-chain
[91.517390] Starting dynamic subtest: rcs0
[92.570541] Dynamic subtest rcs0: SUCCESS (1.054s)
[92.589758] Starting dynamic subtest: bcs0
[93.683519] Dynamic subtest bcs0: SUCCESS (1.111s)
[93.704323] Starting dynamic subtest: vcs0
[94.789702] Dynamic subtest vcs0: SUCCESS (1.105s)
[94.816446] Starting dynamic subtest: vecs0
[95.877438] Dynamic subtest vecs0: SUCCESS (1.087s)
[95.878851] Subtest preempt-queue-contexts-chain: SUCCESS (4.361s)
[96.228483] [027/142] (906s left) device_reset (unbind-reset-rebind)
[96.474540] Starting subtest: unbind-reset-rebind
[97.324468] Subtest unbind-reset-rebind: SUCCESS (0.847s)
[97.872106] [028/142] (904s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[98.216699] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[98.221621] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.003s)
[98.331144] [029/142] (904s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[98.646196] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[98.648900] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[98.747876] [030/142] (903s left) kms_psr (cursor_mmap_cpu)
[98.951734] Subtest cursor_mmap_cpu: SKIP (0.000s)
[98.996428] [031/142] (903s left) kms_invalid_mode (uint-max-clock)
[99.294199] Starting subtest: uint-max-clock
[99.295520] Starting dynamic subtest: DP-1-pipe-A
[99.400138] Dynamic subtest DP-1-pipe-A: SUCCESS (0.107s)
[99.422046] Starting dynamic subtest: DP-1-pipe-B
[99.423624] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[99.448245] Starting dynamic subtest: DP-1-pipe-C
[99.449462] Dynamic subtest DP-1-pipe-C: SUCCESS (0.024s)
[99.450657] Subtest uint-max-clock: SUCCESS (0.153s)
[99.783128] [032/142] (902s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[100.139955] Starting subtest: cursor-rapid-movement-512x512
[100.146268] Subtest cursor-rapid-movement-512x512: SKIP (0.005s)
[100.268258] [033/142] (902s left) prime_self_import (basic-with_two_bos)
[100.475093] Starting subtest: basic-with_two_bos
[100.482415] Subtest basic-with_two_bos: SUCCESS (0.007s)
[100.581389] [034/142] (902s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[100.894992] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[100.897553] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[100.982577] [035/142] (901s left) kms_frontbuffer_tracking (fbc-tiling-4)
[101.306127] Starting subtest: fbc-tiling-4
[101.308931] Subtest fbc-tiling-4: SKIP (0.000s)
[101.398735] [036/142] (901s left) gem_exec_whisper (basic-fds-priority)
[101.628207] Starting subtest: basic-fds-priority
[121.320617] Subtest basic-fds-priority: SUCCESS (19.690s)
[121.532835] [037/142] (881s left) kms_ccs (pipe-b-missing-ccs-buffer-yf_tiled_ccs)
[122.059976] Starting subtest: pipe-B-missing-ccs-buffer-yf_tiled_ccs
[122.061652] Subtest pipe-B-missing-ccs-buffer-yf_tiled_ccs: SUCCESS (0.001s)
[122.513225] [038/142] (880s left) syncobj_timeline (wait-any-interrupted)
[122.719953] Starting subtest: wait-any-interrupted
[122.823800] Subtest wait-any-interrupted: SUCCESS (0.103s)
[122.913273] [039/142] (879s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[125.643349] Subtest verify-pxp-stale-buf-optout-execution: SKIP (0.000s)
[125.732817] [040/142] (877s left) gem_mmap (big-bo)
[125.939500] Starting subtest: big-bo
[128.722915] Subtest big-bo: SUCCESS (2.783s)
[128.839540] [041/142] (874s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[129.284682] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[129.287320] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[129.381371] [042/142] (873s left) gem_partial_pwrite_pread (writes-after-reads-display)
[129.599051] Starting subtest: writes-after-reads-display
[150.404348] Subtest writes-after-reads-display: SUCCESS (20.805s)
[150.515521] [043/142] (852s left) kms_cursor_crc (cursor-dpms)
[150.822507] Starting subtest: cursor-dpms
[150.825929] Starting dynamic subtest: pipe-A-DP-1
[152.539399] Dynamic subtest pipe-A-DP-1: SUCCESS (1.713s)
[152.569056] Starting dynamic subtest: pipe-C-DP-1
[154.600189] Dynamic subtest pipe-C-DP-1: SUCCESS (2.054s)
[154.601753] Subtest cursor-dpms: SUCCESS (3.775s)
[154.951185] [044/142] (848s left) gem_softpin (full)
[155.165347] Starting subtest: full
[155.183366] Subtest full: SUCCESS (0.018s)
[155.277820] [045/142] (847s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[155.605606] Starting subtest: x-tiled-8bpp-rotate-0
[158.668418] Subtest x-tiled-8bpp-rotate-0: SUCCESS (3.062s)
[158.897374] [046/142] (844s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[159.120419] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[159.122077] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[159.211177] [047/142] (843s left) kms_psr2_su (page_flip-nv12)
[159.414863] Subtest page_flip-NV12: SKIP (0.000s)
[159.475727] [048/142] (843s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_mc_ccs)
[159.950439] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs
[159.952873] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[160.465498] [049/142] (842s left) prime_self_import (basic-with_one_bo)
[160.666578] Starting subtest: basic-with_one_bo
[160.671753] Subtest basic-with_one_bo: SUCCESS (0.008s)
[160.763304] [050/142] (842s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[161.082501] Starting subtest: x-tiled-16bpp-rotate-0
[164.688462] Subtest x-tiled-16bpp-rotate-0: SUCCESS (3.606s)
[165.033997] [051/142] (838s left) gem_eio (execbuf)
[165.285133] Starting subtest: execbuf
[165.326323] Subtest execbuf: SUCCESS (0.055s)
[165.566911] [052/142] (837s left) syncobj_timeline (etime-multi-wait-submitted)
[165.770936] Starting subtest: etime-multi-wait-submitted
[165.879151] Subtest etime-multi-wait-submitted: SUCCESS (0.108s)
[165.963338] [053/142] (837s left) kms_vblank (pipe-b-query-busy-hang)
[166.269586] Starting subtest: pipe-B-query-busy-hang
[169.124988] Subtest pipe-B-query-busy-hang: SUCCESS (2.855s)
[169.468183] [054/142] (834s left) kms_vblank (pipe-b-query-forked-busy-hang)
[169.774723] Starting subtest: pipe-B-query-forked-busy-hang
[172.641247] Subtest pipe-B-query-forked-busy-hang: SUCCESS (2.869s)
[172.989559] [055/142] (830s left) gem_softpin (safe-alignment)
[173.204467] Starting subtest: safe-alignment
[173.235549] Subtest safe-alignment: SUCCESS (0.031s)
[173.355746] [056/142] (830s left) v3d/v3d_submit_cl (bad-multisync-pad)
[173.614755] Subtest bad-multisync-pad: SKIP (0.000s)
[173.688733] [057/142] (829s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[174.202907] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[174.205626] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SKIP (0.002s)
[174.683897] [058/142] (828s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[175.123362] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[175.126245] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[175.659378] [059/142] (828s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[176.049735] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[176.340613] [060/142] (827s left) kms_psr (psr2_sprite_plane_move)
[176.544146] Subtest psr2_sprite_plane_move: SKIP (0.000s)
[176.601143] [061/142] (827s left) i915_selftest (perf)
[178.317056] Starting subtest: perf
[178.318390] Starting dynamic subtest: engine_cs
[180.111441] Dynamic subtest engine_cs: SUCCESS (1.793s)
[180.127818] Starting dynamic subtest: request
[184.557539] Dynamic subtest request: SUCCESS (4.443s)
[184.574150] Starting dynamic subtest: migrate
[186.030415] Dynamic subtest migrate: SUCCESS (1.470s)
[186.047284] Starting dynamic subtest: region
[188.720963] Dynamic subtest region: SUCCESS (2.687s)
[188.722567] Subtest perf: SUCCESS (10.405s)
[188.758495] [062/142] (815s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[190.437309] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[190.440608] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[190.589804] [063/142] (813s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[190.895578] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[190.966922] [064/142] (812s left) kms_vblank (pipe-c-ts-continuation-suspend)
[191.277654] Starting subtest: pipe-C-ts-continuation-suspend
[208.249579] Subtest pipe-C-ts-continuation-suspend: SUCCESS (2.110s)
[214.613615] [065/142] (810s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[214.925460] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[214.928590] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[215.021824] [066/142] (809s left) kms_flip (2x-dpms-vs-vblank-race)
[215.307954] Starting subtest: 2x-dpms-vs-vblank-race
[215.310568] Subtest 2x-dpms-vs-vblank-race: SKIP (0.003s)
[215.422367] [067/142] (809s left) gem_mmap_gtt (cpuset-medium-copy)
[215.626162] Starting subtest: cpuset-medium-copy
[226.869570] Subtest cpuset-medium-copy: SUCCESS (11.243s)
[227.145595] [068/142] (797s left) v3d/v3d_submit_csd (single-out-sync)
[227.548014] Subtest single-out-sync: SKIP (0.000s)
[227.620727] [069/142] (797s left) kms_pipe_crc_basic (hang-read-crc)
[227.937541] Starting subtest: hang-read-crc
[227.939250] Starting dynamic subtest: pipe-A-DP-1
[229.254369] Dynamic subtest pipe-A-DP-1: SUCCESS (1.318s)
[229.281977] Starting dynamic subtest: pipe-B-DP-1
[230.944181] Dynamic subtest pipe-B-DP-1: SUCCESS (1.684s)
[230.967885] Starting dynamic subtest: pipe-C-DP-1
[232.869324] Dynamic subtest pipe-C-DP-1: SUCCESS (1.921s)
[232.870633] Subtest hang-read-crc: SUCCESS (4.937s)
[233.195551] [070/142] (791s left) gem_exec_balancer (semaphore)
[233.440385] Starting subtest: semaphore
[237.674812] Subtest semaphore: SUCCESS (4.234s)
[241.630462] [071/142] (783s left) perf (global-sseu-config)
[241.971896] Starting subtest: global-sseu-config
[241.974142] Starting dynamic subtest: 0-rcs0
[242.099471] Dynamic subtest 0-rcs0: SUCCESS (0.125s)
[242.101123] Subtest global-sseu-config: SUCCESS (0.128s)
[242.161910] [072/142] (782s left) gem_ctx_param (invalid-set-ringsize)
[242.365617] Starting subtest: invalid-set-ringsize
[242.367307] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[242.460870] [073/142] (782s left) gem_mmap (short-mmap)
[242.665618] Starting subtest: short-mmap
[242.667420] Subtest short-mmap: SUCCESS (0.000s)
[242.777973] [074/142] (782s left) i915_pm_rc6_residency (rc6-accuracy)
[243.154274] Starting subtest: rc6-accuracy
[246.158450] Subtest rc6-accuracy: SUCCESS (3.002s)
[246.244346] [075/142] (779s left) api_intel_allocator (standalone)
[246.445829] Starting subtest: standalone
[246.506766] Subtest standalone: SUCCESS (0.060s)
[246.595958] [076/142] (778s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[247.273029] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[247.582724] [077/142] (777s left) kms_rmfb (rmfb-ioctl)
[247.884638] Starting subtest: rmfb-ioctl
[247.885972] Starting dynamic subtest: pipe-A-DP-1
[248.020890] Dynamic subtest pipe-A-DP-1: SUCCESS (0.137s)
[248.057920] Starting dynamic subtest: pipe-B-DP-1
[248.481908] Dynamic subtest pipe-B-DP-1: SUCCESS (0.456s)
[248.507642] Starting dynamic subtest: pipe-C-DP-1
[248.897647] Dynamic subtest pipe-C-DP-1: SUCCESS (0.412s)
[248.898934] Subtest rmfb-ioctl: SUCCESS (1.018s)
[249.297987] [078/142] (776s left) gem_wait (write-busy)
[249.541867] Starting subtest: write-busy
[249.543701] Starting dynamic subtest: all-engines
[250.087916] Dynamic subtest all-engines: SUCCESS (0.545s)
[250.119795] Starting dynamic subtest: rcs0
[250.608895] Dynamic subtest rcs0: SUCCESS (0.518s)
[250.637567] Starting dynamic subtest: bcs0
[251.131036] Dynamic subtest bcs0: SUCCESS (0.520s)
[251.158665] Starting dynamic subtest: vcs0
[251.653808] Dynamic subtest vcs0: SUCCESS (0.521s)
[251.680804] Starting dynamic subtest: vecs0
[252.173040] Dynamic subtest vecs0: SUCCESS (0.517s)
[252.174944] Subtest write-busy: SUCCESS (2.633s)
[252.432019] [079/142] (772s left) vc4/vc4_wait_bo (used-bo-0ns)
[252.687525] Subtest used-bo-0ns: SKIP (0.000s)
[252.764024] [080/142] (772s left) gem_ctx_param (vm)
[252.967718] Starting subtest: vm
[253.039069] Subtest vm: SUCCESS (0.072s)
[253.165108] [081/142] (772s left) gem_eio (in-flight-internal-1us)
[253.396669] Starting subtest: in-flight-internal-1us
[253.514275] Subtest in-flight-internal-1us: SUCCESS (0.118s)
[253.717770] [082/142] (771s left) gem_lmem_swapping (heavy-multi)
[253.930571] Subtest heavy-multi: SKIP (0.000s)
[253.997481] [083/142] (771s left) gem_mmap_gtt (big-copy-xy)
[254.199136] Starting subtest: big-copy-XY
[266.409999] Subtest big-copy-XY: SUCCESS (12.212s)
[266.565499] [084/142] (759s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[266.999049] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[267.000521] Starting dynamic subtest: pipe-A-valid-mode
[267.690285] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.688s)
[267.691469] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SUCCESS (0.692s)
[268.044719] [085/142] (757s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[268.362752] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[268.365879] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[268.482239] [086/142] (757s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[268.802869] Starting subtest: plane-use-after-nonblocking-unbind
[268.807114] Starting dynamic subtest: pipe-A-DP-1
[269.013607] Dynamic subtest pipe-A-DP-1: SUCCESS (0.207s)
[269.155315] Starting dynamic subtest: pipe-B-DP-1
[269.776620] Dynamic subtest pipe-B-DP-1: SUCCESS (0.649s)
[269.877529] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.075s)
[270.219899] [087/142] (755s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs)
[270.877391] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[271.203411] [088/142] (754s left) kms_sequence (get-forked-busy)
[271.512845] Starting subtest: get-forked-busy
[271.514414] Starting dynamic subtest: DP-1-pipe-A
[273.972026] Dynamic subtest DP-1-pipe-A: SUCCESS (2.456s)
[274.006221] Starting dynamic subtest: DP-1-pipe-B
[276.769916] Dynamic subtest DP-1-pipe-B: SUCCESS (2.796s)
[276.803740] Starting dynamic subtest: DP-1-pipe-C
[279.684785] Dynamic subtest DP-1-pipe-C: SUCCESS (2.909s)
[279.686367] Subtest get-forked-busy: SUCCESS (8.174s)
[280.016096] [089/142] (745s left) kms_chamelium_color (ctm-limited-range)
[280.325827] Subtest ctm-limited-range: SKIP (0.000s)
[280.380949] [090/142] (745s left) kms_properties (plane-properties-atomic)
[280.685465] Starting subtest: plane-properties-atomic
[281.999156] Subtest plane-properties-atomic: SUCCESS (1.317s)
[282.340617] [091/142] (743s left) i915_pm_backlight (bad-brightness)
[282.657746] Subtest bad-brightness: SKIP (0.000s)
[282.720081] [092/142] (743s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[283.033518] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[285.690560] Subtest fbc-rgb101010-draw-mmap-gtt: SUCCESS (2.658s)
[285.854503] [093/142] (740s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_dg2_mc_ccs)
[286.159465] Starting subtest: pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs
[286.163507] Subtest pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[286.882485] [094/142] (739s left) gem_exec_fence (submit3)
[287.246018] Starting subtest: submit3
[287.251286] Starting dynamic subtest: rcs0
[287.267873] Dynamic subtest rcs0: SUCCESS (0.016s)
[287.312531] Starting dynamic subtest: bcs0
[287.314352] Dynamic subtest bcs0: SUCCESS (0.015s)
[287.339265] Starting dynamic subtest: vcs0
[287.340540] Dynamic subtest vcs0: SUCCESS (0.011s)
[287.363560] Starting dynamic subtest: vecs0
[287.364822] Dynamic subtest vecs0: SUCCESS (0.013s)
[287.366030] Subtest submit3: SUCCESS (0.070s)
[287.983077] [095/142] (738s left) prime_busy (before)
[288.196508] Starting subtest: before
[288.216723] Starting dynamic subtest: rcs0
[288.226453] Dynamic subtest rcs0: SUCCESS (0.011s)
[288.258326] Starting dynamic subtest: bcs0
[288.259681] Dynamic subtest bcs0: SUCCESS (0.017s)
[288.286981] Starting dynamic subtest: vcs0
[288.288319] Dynamic subtest vcs0: SUCCESS (0.015s)
[288.311361] Starting dynamic subtest: vecs0
[288.312597] Dynamic subtest vecs0: SUCCESS (0.015s)
[288.395587] Subtest before: SUCCESS (0.199s)
[288.499794] [096/142] (737s left) gem_exec_schedule (implicit-read-write)
[288.742162] Starting subtest: implicit-read-write
[288.743876] Starting dynamic subtest: rcs0
[288.888288] Dynamic subtest rcs0: SUCCESS (0.144s)
[288.927755] Starting dynamic subtest: bcs0
[289.033844] Dynamic subtest bcs0: SUCCESS (0.143s)
[289.067389] Starting dynamic subtest: vcs0
[289.169109] Dynamic subtest vcs0: SUCCESS (0.131s)
[289.199501] Starting dynamic subtest: vecs0
[289.299958] Dynamic subtest vecs0: SUCCESS (0.128s)
[289.303922] Subtest implicit-read-write: SUCCESS (0.558s)
[289.669661] [097/142] (736s left) perf_pmu (most-busy-check-all)
[289.881878] Starting subtest: most-busy-check-all
[289.883718] Starting dynamic subtest: rcs0
[290.419696] Dynamic subtest rcs0: SUCCESS (0.537s)
[290.453491] Starting dynamic subtest: bcs0
[290.947935] Dynamic subtest bcs0: SUCCESS (0.525s)
[290.982654] Starting dynamic subtest: vcs0
[291.474970] Dynamic subtest vcs0: SUCCESS (0.524s)
[291.508872] Starting dynamic subtest: vecs0
[291.996983] Dynamic subtest vecs0: SUCCESS (0.519s)
[291.998849] Subtest most-busy-check-all: SUCCESS (2.117s)
[292.099414] [098/142] (734s left) gem_exec_flush (basic-uc-ro-default)
[292.331734] Starting subtest: basic-uc-ro-default
[297.807913] Subtest basic-uc-ro-default: SUCCESS (5.476s)
[298.019033] [099/142] (728s left) gem_mmap_gtt (big-bo)
[298.223636] Starting subtest: big-bo
[299.086152] Subtest big-bo: SUCCESS (0.862s)
[299.309474] [100/142] (727s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[299.757453] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[299.760153] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[299.867352] [101/142] (726s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs)
[300.506525] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs
[300.509245] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[300.845142] [102/142] (725s left) gem_pread (bench)
[301.048879] Starting subtest: bench
[305.575112] Subtest bench: SUCCESS (4.524s)
[305.676978] [103/142] (720s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[306.009855] Starting subtest: y-tiled-8bpp-rotate-90
[306.090507] Subtest y-tiled-8bpp-rotate-90: SKIP (0.077s)
[306.211507] [104/142] (720s left) gem_softpin (evict-snoop)
[306.430878] Starting subtest: evict-snoop
[306.434108] Subtest evict-snoop: SKIP (0.000s)
[306.545535] [105/142] (720s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs)
[307.220207] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[307.545808] [106/142] (719s left) kms_vblank (pipe-c-ts-continuation-idle)
[307.847680] Starting subtest: pipe-C-ts-continuation-idle
[308.606075] Subtest pipe-C-ts-continuation-idle: SUCCESS (0.760s)
[308.953778] [107/142] (717s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[309.158628] Starting subtest: multi-wait-all-for-submit-signaled
[309.160429] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[309.255312] [108/142] (717s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[309.941401] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[309.944597] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.004s)
[310.282017] [109/142] (716s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[310.584500] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[310.585987] Starting dynamic subtest: pipe-A-DP-1
[310.616358] Dynamic subtest pipe-A-DP-1: SKIP (0.029s)
[310.646340] Starting dynamic subtest: pipe-B-DP-1
[310.699773] Dynamic subtest pipe-B-DP-1: SKIP (0.079s)
[310.732590] Starting dynamic subtest: pipe-C-DP-1
[310.782548] Dynamic subtest pipe-C-DP-1: SKIP (0.078s)
[310.785386] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.202s)
[310.899166] [110/142] (715s left) kms_plane_alpha_blend (alpha-7efc)
[311.198937] Starting subtest: alpha-7efc
[311.513604] Starting dynamic subtest: pipe-A-DP-1
[311.981865] Dynamic subtest pipe-A-DP-1: SUCCESS (0.468s)
[312.069785] Starting dynamic subtest: pipe-C-DP-1
[312.898508] Dynamic subtest pipe-C-DP-1: SUCCESS (0.852s)
[312.900598] Subtest alpha-7efc: SUCCESS (1.700s)
[313.261743] [111/142] (713s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[313.570132] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[313.573483] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[313.678044] [112/142] (713s left) syncobj_wait (multi-wait-all-submitted-signaled)
[313.880249] Starting subtest: multi-wait-all-submitted-signaled
[313.984449] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.104s)
[314.077749] [113/142] (712s left) gen9_exec_parse (batch-invalid-length)
[314.303611] Starting subtest: batch-invalid-length
[314.318740] Subtest batch-invalid-length: SUCCESS (0.014s)
[314.545058] [114/142] (712s left) gem_ccs (block-multicopy-inplace)
[314.754589] Subtest block-multicopy-inplace: SKIP (0.000s)
[314.809935] [115/142] (712s left) kms_flip (2x-nonexisting-fb)
[315.099039] Starting subtest: 2x-nonexisting-fb
[315.101830] Subtest 2x-nonexisting-fb: SKIP (0.003s)
[315.228726] [116/142] (711s left) kms_fbcon_fbt (fbc-suspend)
[315.438848] Starting subtest: fbc-suspend
[364.485989] Subtest fbc-suspend: SUCCESS (17.022s)
[365.548496] [117/142] (694s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[365.752333] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[365.753787] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[365.849174] [118/142] (694s left) kms_atomic (plane-primary-legacy)
[366.504458] Starting subtest: plane-primary-legacy
[366.687219] Subtest plane-primary-legacy: SUCCESS (0.182s)
[367.136396] [119/142] (693s left) kms_prop_blob (basic)
[367.338184] Starting subtest: basic
[367.340099] Subtest basic: SUCCESS (0.000s)
[367.436430] [120/142] (692s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[367.749958] Starting subtest: fbc-rgb101010-draw-blt
[370.403527] Subtest fbc-rgb101010-draw-blt: SUCCESS (2.654s)
[370.533361] [121/142] (689s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[370.848854] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[370.851579] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[370.968247] [122/142] (689s left) api_intel_allocator (reopen)
[371.169582] Starting subtest: reopen
[371.172231] Subtest reopen: SUCCESS (0.005s)
[371.284070] [123/142] (689s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-5)
[371.583627] Starting subtest: plane-downscale-with-rotation-factor-0-5
[371.585116] Starting dynamic subtest: pipe-A-DP-1
[371.901691] Dynamic subtest pipe-A-DP-1: SKIP (0.316s)
[371.927239] Starting dynamic subtest: pipe-B-DP-1
[372.292581] Dynamic subtest pipe-B-DP-1: SKIP (0.384s)
[372.330229] Starting dynamic subtest: pipe-C-DP-1
[372.677123] Dynamic subtest pipe-C-DP-1: SKIP (0.384s)
[372.680187] Subtest plane-downscale-with-rotation-factor-0-5: SKIP (1.100s)
[373.086115] [124/142] (687s left) v3d/v3d_perfmon (destroy-invalid-perfmon)
[373.355894] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[373.435578] [125/142] (687s left) perf (gen8-unprivileged-single-ctx-counters)
[373.761517] Starting subtest: gen8-unprivileged-single-ctx-counters
[373.965865] Subtest gen8-unprivileged-single-ctx-counters: SUCCESS (0.204s)
[374.035919] [126/142] (686s left) kms_cursor_crc (cursor-offscreen-32x10)
[374.332891] Starting subtest: cursor-offscreen-32x10
[374.452101] Subtest cursor-offscreen-32x10: SKIP (0.117s)
[374.569315] [127/142] (686s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[374.772365] Starting subtest: multi-wait-all-for-submit-available-signaled
[374.776944] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.002s)
[374.868427] [128/142] (685s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[375.172532] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[376.310273] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.139s)
[376.650592] [129/142] (684s left) vgem_basic (second-client)
[376.867405] Starting subtest: second-client
[376.900657] Subtest second-client: SUCCESS (0.032s)
[376.980967] [130/142] (683s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[377.297335] Starting subtest: yf-tiled-addfb-size-offset-overflow
[377.300067] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[377.415329] [131/142] (683s left) kms_cursor_edge_walk (256x256-left-edge)
[377.722315] Starting subtest: 256x256-left-edge
[377.723932] Starting dynamic subtest: pipe-A-DP-1
[381.372626] Dynamic subtest pipe-A-DP-1: SUCCESS (3.649s)
[381.400696] Starting dynamic subtest: pipe-C-DP-1
[384.829083] Dynamic subtest pipe-C-DP-1: SUCCESS (3.452s)
[384.830462] Subtest 256x256-left-edge: SUCCESS (7.111s)
[385.159937] [132/142] (675s left) kms_vblank (pipe-d-ts-continuation-idle-hang)
[385.480940] Subtest pipe-D-ts-continuation-idle-hang: SKIP (0.000s)
[385.575770] [133/142] (675s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_mtl_mc_ccs)
[386.068572] Starting subtest: pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs
[386.071715] Subtest pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[386.580565] [134/142] (674s left) gem_mmap_gtt (zero-extend)
[386.782550] Starting subtest: zero-extend
[386.784450] Subtest zero-extend: SUCCESS (0.000s)
[386.895688] [135/142] (674s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[387.200032] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[387.202911] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[387.313105] [136/142] (673s left) i915_pm_rps (thresholds-park)
[387.528615] Starting subtest: thresholds-park
[387.531119] Starting dynamic subtest: gt0
[387.534550] Dynamic subtest gt0: SKIP (0.004s)
[387.537699] Subtest thresholds-park: SKIP (0.007s)
[387.663752] [137/142] (673s left) kms_ccs (pipe-a-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[388.106059] Starting subtest: pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[388.109108] Subtest pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[388.670194] [138/142] (672s left) gem_userptr_blits (readonly-unsync)
[388.884500] Subtest readonly-unsync: SKIP (0.000s)
[389.054423] [139/142] (672s left) gem_exec_fence (long-history)
[389.713151] Starting subtest: long-history
[395.461214] Subtest long-history: SUCCESS (5.748s)
[396.104504] [140/142] (665s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[396.419605] Starting subtest: fbcpsr-rgb101010-draw-blt
[396.422986] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s)
[396.520970] [141/142] (664s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[396.836581] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[396.839812] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[396.937737] [142/142] (664s left) kms_addfb_basic (unused-handle)
[397.140654] Starting subtest: unused-handle
[397.142381] Subtest unused-handle: SUCCESS (0.000s)
[397.350532] Closing watchdogs
Done.