[50.425901] Initializing watchdogs
[50.426562]   /dev/watchdog0
[50.457618] [001/142] (960s left) gem_ctx_persistence (process)
[50.905382] Starting subtest: process
[51.132691] Subtest process: SUCCESS (0.231s)
[51.326664] [002/142] (959s left) syncobj_wait (multi-wait-all-submitted)
[51.697886] Starting subtest: multi-wait-all-submitted
[51.807649] Subtest multi-wait-all-submitted: SUCCESS (0.108s)
[51.962807] [003/142] (959s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[52.478602] Starting subtest: psr-2p-primscrn-spr-indfb-move
[52.484127] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[52.632391] [004/142] (958s left) kms_draw_crc (draw-method-blt)
[53.112275] Starting subtest: draw-method-blt
[53.114558] Starting dynamic subtest: xrgb8888-untiled
[53.829724] Dynamic subtest xrgb8888-untiled: SUCCESS (0.716s)
[53.856200] Starting dynamic subtest: rgb565-untiled
[54.274244] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[54.305175] Starting dynamic subtest: xrgb2101010-untiled
[54.707892] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.433s)
[54.742207] Starting dynamic subtest: xrgb8888-xtiled
[54.941636] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.233s)
[54.977300] Starting dynamic subtest: rgb565-xtiled
[55.197502] Dynamic subtest rgb565-xtiled: SUCCESS (0.255s)
[55.228454] Starting dynamic subtest: xrgb2101010-xtiled
[55.463331] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.265s)
[55.495240] Starting dynamic subtest: xrgb8888-4tiled
[55.685402] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.221s)
[55.718963] Starting dynamic subtest: rgb565-4tiled
[55.930272] Dynamic subtest rgb565-4tiled: SUCCESS (0.244s)
[55.964305] Starting dynamic subtest: xrgb2101010-4tiled
[56.174531] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.244s)
[56.177643] Subtest draw-method-blt: SUCCESS (3.062s)
[56.328478] [005/142] (954s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[56.601143] Starting subtest: single-wait-all-for-submit-signaled
[56.603867] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.002s)
[56.699954] [006/142] (954s left) debugfs_test (read_all_entries_display_off)
[57.183200] Starting subtest: read_all_entries_display_off
[59.058648] Subtest read_all_entries_display_off: SUCCESS (1.876s)
[59.597708] [007/142] (951s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[60.398628] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[61.607955] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.213s)
[62.815694] [008/142] (948s left) gem_barrier_race (remote-request)
[63.264623] Starting subtest: remote-request
[63.267111] Starting dynamic subtest: rcs0
[69.099546] Dynamic subtest rcs0: SUCCESS (5.834s)
[69.101330] Subtest remote-request: SUCCESS (5.835s)
[69.293928] [009/142] (942s left) i915_pm_rpm (legacy-planes-dpms)
[75.649659] Starting subtest: legacy-planes-dpms
[75.652201] Starting dynamic subtest: plane-40
[100.282661] Dynamic subtest plane-40: SUCCESS (24.630s)
[100.317880] Starting dynamic subtest: plane-49
[125.107502] Dynamic subtest plane-49: SUCCESS (24.826s)
[125.143656] Starting dynamic subtest: plane-58
[149.887811] Dynamic subtest plane-58: SUCCESS (24.779s)
[149.922831] Starting dynamic subtest: plane-67
[174.603794] Dynamic subtest plane-67: SUCCESS (24.714s)
[174.606365] Subtest legacy-planes-dpms: SUCCESS (98.953s)
[175.798576] [010/142] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[176.305567] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[176.310142] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[176.463243] [011/142] (835s left) gem_exec_nop (basic-sequential)
[176.782377] Starting subtest: basic-sequential
[194.508151] Subtest basic-sequential: SUCCESS (17.725s)
[194.820627] [012/142] (816s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[196.383146] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[196.387613] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[197.264978] [013/142] (814s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[197.765576] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[197.770107] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[197.921112] [014/142] (813s left) gem_exec_balancer (hog)
[198.341090] Starting subtest: hog
[198.344604] Subtest hog: SKIP (0.000s)
[199.312156] [015/142] (812s left) syncobj_basic (bad-pad-fd-to-handle)
[199.676393] Starting subtest: bad-pad-fd-to-handle
[199.678692] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[199.806699] [016/142] (812s left) gem_userptr_blits (access-control)
[200.308712] Starting subtest: access-control
[200.313357] Subtest access-control: SKIP (0.000s)
[200.475789] [017/142] (811s left) kms_flip (basic-flip-vs-wf_vblank)
[200.870082] Starting subtest: basic-flip-vs-wf_vblank
[200.895520] Starting dynamic subtest: A-eDP1
[203.421654] Dynamic subtest A-eDP1: SUCCESS (2.525s)
[203.460200] Starting dynamic subtest: B-eDP1
[205.762755] Dynamic subtest B-eDP1: SUCCESS (2.337s)
[205.798871] Starting dynamic subtest: C-eDP1
[207.983184] Dynamic subtest C-eDP1: SUCCESS (2.220s)
[208.015556] Starting dynamic subtest: D-eDP1
[210.287834] Dynamic subtest D-eDP1: SUCCESS (2.302s)
[210.313204] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.448s)
[211.906093] [018/142] (800s left) dmabuf_sync_file (import-multiple-read-only)
[212.405248] Starting subtest: import-multiple-read-only
[212.424186] Subtest import-multiple-read-only: SUCCESS (0.021s)
[212.535498] [019/142] (799s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[213.014056] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[213.022288] Starting dynamic subtest: pipe-A-default-mode
[213.430513] Dynamic subtest pipe-A-default-mode: SKIP (0.405s)
[213.435144] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.416s)
[214.355397] [020/142] (797s left) gem_exec_schedule (semaphore-user)
[214.801563] Starting subtest: semaphore-user
[214.848817] Subtest semaphore-user: SUCCESS (0.047s)
[216.653417] [021/142] (795s left) gem_exec_flush (basic-batch-kernel-default-wb)
[217.076603] Starting subtest: basic-batch-kernel-default-wb
[225.369862] Subtest basic-batch-kernel-default-wb: SUCCESS (8.295s)
[225.651666] [022/142] (786s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_mc_ccs)
[227.241867] Starting subtest: pipe-D-crc-primary-basic-4_tiled_dg2_mc_ccs
[227.245910] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[227.768060] [023/142] (784s left) kms_chamelium_frames (hdmi-frame-dump)
[228.619936] Subtest hdmi-frame-dump: SKIP (0.000s)
[229.486555] [024/142] (783s left) drm_read (empty-block)
[230.284128] Starting subtest: empty-block
[231.285706] Subtest empty-block: SUCCESS (1.002s)
[231.497120] [025/142] (781s left) gem_exec_params (invalid-fence-in)
[231.888202] Starting subtest: invalid-fence-in
[231.893362] Subtest invalid-fence-in: SUCCESS (0.003s)
[232.055096] [026/142] (780s left) gem_exec_schedule (preempt-queue-contexts-chain)
[232.489538] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[232.904725] [027/142] (779s left) device_reset (unbind-reset-rebind)
[233.717389] Starting subtest: unbind-reset-rebind
[238.315000] Subtest unbind-reset-rebind: SUCCESS (4.598s)
[238.473780] [028/142] (774s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[239.016370] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[242.924256] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (3.908s)
[243.495785] [029/142] (769s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[244.001224] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[257.002217] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (13.002s)
[257.266109] [030/142] (755s left) kms_psr (cursor_mmap_cpu)
[257.752293] Starting subtest: cursor_mmap_cpu
[260.537574] Subtest cursor_mmap_cpu: SUCCESS (2.783s)
[261.483877] [031/142] (751s left) kms_invalid_mode (uint-max-clock)
[261.985822] Starting subtest: uint-max-clock
[261.991792] Starting dynamic subtest: eDP-1-pipe-A
[262.405232] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.412s)
[262.449735] Starting dynamic subtest: eDP-1-pipe-B
[262.452458] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.041s)
[262.501280] Starting dynamic subtest: eDP-1-pipe-C
[262.503660] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.040s)
[262.552169] Starting dynamic subtest: eDP-1-pipe-D
[262.554764] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.041s)
[262.557677] Subtest uint-max-clock: SUCCESS (0.567s)
[263.315876] [032/142] (749s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[264.525751] Starting subtest: cursor-rapid-movement-512x512
[264.531187] Subtest cursor-rapid-movement-512x512: SKIP (0.007s)
[264.941898] [033/142] (748s left) prime_self_import (basic-with_two_bos)
[265.315150] Starting subtest: basic-with_two_bos
[265.328640] Subtest basic-with_two_bos: SUCCESS (0.017s)
[265.498526] [034/142] (747s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[266.003744] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[266.008611] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[266.169699] [035/142] (747s left) kms_frontbuffer_tracking (fbc-tiling-4)
[266.678661] Starting subtest: fbc-tiling-4
[276.831408] Subtest fbc-tiling-4: SUCCESS (10.154s)
[277.095511] [036/142] (736s left) gem_exec_whisper (basic-fds-priority)
[277.540276] Starting subtest: basic-fds-priority
[304.799105] Subtest basic-fds-priority: SUCCESS (27.256s)
[305.091327] [037/142] (708s left) kms_ccs (pipe-b-missing-ccs-buffer-yf_tiled_ccs)
[306.105419] Starting subtest: pipe-B-missing-ccs-buffer-yf_tiled_ccs
[306.109968] Subtest pipe-B-missing-ccs-buffer-yf_tiled_ccs: SKIP (0.000s)
[306.913533] [038/142] (706s left) syncobj_timeline (wait-any-interrupted)
[307.280195] Starting subtest: wait-any-interrupted
[307.391996] Subtest wait-any-interrupted: SUCCESS (0.110s)
[307.531788] [039/142] (706s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[310.435819] Subtest verify-pxp-stale-buf-optout-execution: SKIP (0.000s)
[310.608625] [040/142] (703s left) gem_mmap (big-bo)
[310.989970] Subtest big-bo: SKIP (0.000s)
[311.119149] [041/142] (702s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[311.616991] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[311.621816] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[311.793130] [042/142] (702s left) gem_partial_pwrite_pread (writes-after-reads-display)
[312.175656] Subtest writes-after-reads-display: SKIP (0.000s)
[312.321573] [043/142] (701s left) kms_cursor_crc (cursor-dpms)
[312.860088] Starting subtest: cursor-dpms
[312.867315] Starting dynamic subtest: pipe-A-eDP-1
[317.066306] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.195s)
[317.109053] Starting dynamic subtest: pipe-D-eDP-1
[321.641323] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.572s)
[321.643228] Subtest cursor-dpms: SUCCESS (8.781s)
[323.071483] [044/142] (691s left) gem_softpin (full)
[323.461065] Starting subtest: full
[323.511333] Subtest full: SUCCESS (0.050s)
[323.697975] [045/142] (690s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[324.228601] Starting subtest: x-tiled-8bpp-rotate-0
[328.351344] Subtest x-tiled-8bpp-rotate-0: SUCCESS (4.119s)
[328.657448] [046/142] (685s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[329.033774] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[329.036385] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.003s)
[329.187097] [047/142] (685s left) kms_psr2_su (page_flip-nv12)
[329.560075] Subtest page_flip-NV12: SKIP (0.000s)
[329.683993] [048/142] (684s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_mc_ccs)
[330.802814] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs
[330.807321] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[332.114870] [049/142] (682s left) prime_self_import (basic-with_one_bo)
[332.475865] Starting subtest: basic-with_one_bo
[332.494400] Subtest basic-with_one_bo: SUCCESS (0.019s)
[332.655647] [050/142] (681s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[333.196241] Starting subtest: x-tiled-16bpp-rotate-0
[337.767433] Subtest x-tiled-16bpp-rotate-0: SUCCESS (4.567s)
[338.052971] [051/142] (676s left) gem_eio (execbuf)
[339.981117] Starting subtest: execbuf
[343.085692] Subtest execbuf: SUCCESS (3.103s)
[344.662848] [052/142] (670s left) syncobj_timeline (etime-multi-wait-submitted)
[345.052234] Starting subtest: etime-multi-wait-submitted
[345.157896] Subtest etime-multi-wait-submitted: SUCCESS (0.107s)
[345.313190] [053/142] (669s left) kms_vblank (pipe-b-query-busy-hang)
[345.802238] Starting subtest: pipe-B-query-busy-hang
[349.019963] Subtest pipe-B-query-busy-hang: SUCCESS (3.217s)
[349.987185] [054/142] (664s left) kms_vblank (pipe-b-query-forked-busy-hang)
[350.469513] Starting subtest: pipe-B-query-forked-busy-hang
[354.036565] Subtest pipe-B-query-forked-busy-hang: SUCCESS (3.565s)
[355.034127] [055/142] (660s left) gem_softpin (safe-alignment)
[355.444797] Starting subtest: safe-alignment
[355.539696] Subtest safe-alignment: SUCCESS (0.095s)
[356.696733] [056/142] (658s left) v3d/v3d_submit_cl (bad-multisync-pad)
[357.200204] Subtest bad-multisync-pad: SKIP (0.000s)
[357.322208] [057/142] (657s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[358.514951] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[367.025250] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (8.510s)
[368.470455] [058/142] (646s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[369.508121] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[369.514562] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[370.864084] [059/142] (644s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[371.751111] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[372.630041] [060/142] (642s left) kms_psr (psr2_sprite_plane_move)
[373.114693] Starting subtest: psr2_sprite_plane_move
[376.089783] Subtest psr2_sprite_plane_move: SUCCESS (2.978s)
[377.062840] [061/142] (638s left) i915_selftest (perf)
[381.035443] Starting subtest: perf
[381.037906] Starting dynamic subtest: engine_cs
[385.347645] Dynamic subtest engine_cs: SUCCESS (4.312s)
[385.375801] Starting dynamic subtest: request
[391.948818] Dynamic subtest request: SUCCESS (6.597s)
[391.977121] Starting dynamic subtest: migrate
[396.172693] Dynamic subtest migrate: SUCCESS (4.220s)
[396.200558] Starting dynamic subtest: region
[405.418439] Dynamic subtest region: SUCCESS (9.241s)
[405.420875] Subtest perf: SUCCESS (24.387s)
[405.490956] [062/142] (610s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[408.755525] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[411.891593] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: FAIL (2.630s)
[411.950711] [063/142] (603s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[412.436498] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[412.567444] [064/142] (603s left) kms_vblank (pipe-c-ts-continuation-suspend)
[413.047586] Starting subtest: pipe-C-ts-continuation-suspend
[432.043139] Subtest pipe-C-ts-continuation-suspend: SUCCESS (18.996s)
[433.002554] [065/142] (582s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[433.505107] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[433.509547] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[433.679357] [066/142] (582s left) kms_flip (2x-dpms-vs-vblank-race)
[434.076585] Starting subtest: 2x-dpms-vs-vblank-race
[434.246124] Subtest 2x-dpms-vs-vblank-race: SKIP (0.165s)
[434.444964] [067/142] (581s left) gem_mmap_gtt (cpuset-medium-copy)
[434.818894] Subtest cpuset-medium-copy: SKIP (0.000s)
[434.936395] [068/142] (581s left) v3d/v3d_submit_csd (single-out-sync)
[435.407349] Subtest single-out-sync: SKIP (0.000s)
[435.544348] [069/142] (580s left) kms_pipe_crc_basic (hang-read-crc)
[436.022591] Starting subtest: hang-read-crc
[436.028700] Starting dynamic subtest: pipe-A-eDP-1
[439.489599] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.458s)
[439.546233] Starting dynamic subtest: pipe-B-eDP-1
[443.991815] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.490s)
[444.037719] Starting dynamic subtest: pipe-C-eDP-1
[448.064557] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.063s)
[448.123995] Starting dynamic subtest: pipe-D-eDP-1
[453.523206] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.447s)
[453.525504] Subtest hang-read-crc: SUCCESS (17.503s)
[454.482234] [070/142] (561s left) gem_exec_balancer (semaphore)
[454.944476] Starting subtest: semaphore
[462.546112] Subtest semaphore: SUCCESS (7.599s)
[463.563940] [071/142] (552s left) perf (global-sseu-config)
[464.411862] Subtest global-sseu-config: SKIP (0.000s)
[464.517398] [072/142] (551s left) gem_ctx_param (invalid-set-ringsize)
[464.902862] Starting subtest: invalid-set-ringsize
[464.905856] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[465.093837] [073/142] (551s left) gem_mmap (short-mmap)
[465.476739] Subtest short-mmap: SKIP (0.000s)
[465.617388] [074/142] (551s left) i915_pm_rc6_residency (rc6-accuracy)
[467.243727] Subtest rc6-accuracy: SKIP (0.000s)
[467.419806] [075/142] (549s left) api_intel_allocator (standalone)
[467.797913] Starting subtest: standalone
[467.910282] Subtest standalone: SUCCESS (0.112s)
[468.141270] [076/142] (548s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[469.840273] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[471.169877] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SUCCESS (1.330s)
[472.601575] [077/142] (544s left) kms_rmfb (rmfb-ioctl)
[473.094586] Starting subtest: rmfb-ioctl
[473.098658] Starting dynamic subtest: pipe-A-eDP-1
[473.411958] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.313s)
[473.466672] Starting dynamic subtest: pipe-B-eDP-1
[475.104166] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.683s)
[475.162661] Starting dynamic subtest: pipe-C-eDP-1
[476.839255] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.726s)
[476.894282] Starting dynamic subtest: pipe-D-eDP-1
[478.486505] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.636s)
[478.489811] Subtest rmfb-ioctl: SUCCESS (5.396s)
[479.848814] [078/142] (537s left) gem_wait (write-busy)
[480.303170] Starting subtest: write-busy
[480.305938] Starting dynamic subtest: all-engines
[480.913869] Dynamic subtest all-engines: SUCCESS (0.613s)
[480.983876] Starting dynamic subtest: rcs0
[481.489967] Dynamic subtest rcs0: SUCCESS (0.571s)
[481.558758] Starting dynamic subtest: bcs0
[482.067769] Dynamic subtest bcs0: SUCCESS (0.573s)
[482.141362] Starting dynamic subtest: vcs0
[483.528209] Dynamic subtest vcs0: SUCCESS (1.454s)
[483.592660] Starting dynamic subtest: vcs1
[485.512840] Dynamic subtest vcs1: SUCCESS (1.979s)
[485.573312] Starting dynamic subtest: vecs0
[487.560449] Dynamic subtest vecs0: SUCCESS (2.043s)
[487.619812] Starting dynamic subtest: ccs0
[488.133361] Dynamic subtest ccs0: SUCCESS (0.571s)
[488.135592] Subtest write-busy: SUCCESS (7.837s)
[488.530592] [079/142] (528s left) vc4/vc4_wait_bo (used-bo-0ns)
[489.033599] Subtest used-bo-0ns: SKIP (0.000s)
[489.182292] [080/142] (528s left) gem_ctx_param (vm)
[489.561260] Starting subtest: vm
[489.728079] Subtest vm: SUCCESS (0.165s)
[489.965477] [081/142] (527s left) gem_eio (in-flight-internal-1us)
[491.284085] Starting subtest: in-flight-internal-1us
[493.918192] Subtest in-flight-internal-1us: SUCCESS (2.634s)
[495.720498] [082/142] (521s left) gem_lmem_swapping (heavy-multi)
[496.126351] Subtest heavy-multi: SKIP (0.000s)
[496.246660] [083/142] (521s left) gem_mmap_gtt (big-copy-xy)
[496.523749] Subtest big-copy-XY: SKIP (0.000s)
[496.628117] [084/142] (520s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[496.958441] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[496.966648] Starting dynamic subtest: pipe-A-default-mode
[497.284822] Dynamic subtest pipe-A-default-mode: SKIP (0.314s)
[497.290165] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.322s)
[498.279564] [085/142] (519s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[498.788579] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[508.527447] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (9.740s)
[508.817330] [086/142] (508s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[509.312542] Starting subtest: plane-use-after-nonblocking-unbind
[509.320182] Starting dynamic subtest: pipe-A-eDP-1
[509.567074] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.247s)
[510.016666] Starting dynamic subtest: pipe-B-eDP-1
[510.962990] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.993s)
[511.405661] Subtest plane-use-after-nonblocking-unbind: SUCCESS (2.090s)
[512.361712] [087/142] (505s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs)
[514.058314] Starting subtest: pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs
[514.064864] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[514.761350] [088/142] (503s left) kms_sequence (get-forked-busy)
[515.264061] Starting subtest: get-forked-busy
[515.270442] Starting dynamic subtest: eDP-1-pipe-A
[517.874152] Dynamic subtest eDP-1-pipe-A: SUCCESS (2.602s)
[517.931483] Starting dynamic subtest: eDP-1-pipe-B
[521.173155] Dynamic subtest eDP-1-pipe-B: SUCCESS (3.289s)
[521.226051] Starting dynamic subtest: eDP-1-pipe-C
[524.701362] Dynamic subtest eDP-1-pipe-C: SUCCESS (3.521s)
[524.757247] Starting dynamic subtest: eDP-1-pipe-D
[528.634403] Dynamic subtest eDP-1-pipe-D: SUCCESS (3.925s)
[528.638353] Subtest get-forked-busy: SUCCESS (13.376s)
[529.594979] [089/142] (488s left) kms_chamelium_color (ctm-limited-range)
[530.088149] Subtest ctm-limited-range: SKIP (0.000s)
[530.211292] [090/142] (487s left) kms_properties (plane-properties-atomic)
[530.691929] Starting subtest: plane-properties-atomic
[535.291532] Subtest plane-properties-atomic: SUCCESS (4.599s)
[536.251525] [091/142] (481s left) i915_pm_backlight (bad-brightness)
[536.732860] Starting subtest: bad-brightness
[537.081722] Starting dynamic subtest: eDP-1
[537.557673] Dynamic subtest eDP-1: SUCCESS (0.476s)
[537.560268] Subtest bad-brightness: SUCCESS (0.824s)
[538.500513] [092/142] (479s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[539.012635] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[539.017443] Subtest fbc-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[539.186271] [093/142] (479s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_dg2_mc_ccs)
[539.679121] Starting subtest: pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs
[539.683484] Subtest pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[541.583224] [094/142] (476s left) gem_exec_fence (submit3)
[542.174393] Starting subtest: submit3
[542.178561] Subtest submit3: SKIP (0.000s)
[543.908491] [095/142] (474s left) prime_busy (before)
[544.308295] Starting subtest: before
[544.338673] Starting dynamic subtest: rcs0
[544.364372] Dynamic subtest rcs0: SUCCESS (0.025s)
[544.421919] Starting dynamic subtest: bcs0
[544.424461] Dynamic subtest bcs0: SUCCESS (0.050s)
[544.479275] Starting dynamic subtest: vcs0
[544.481585] Dynamic subtest vcs0: SUCCESS (0.045s)
[544.532747] Starting dynamic subtest: vcs1
[544.535123] Dynamic subtest vcs1: SUCCESS (0.027s)
[544.585163] Starting dynamic subtest: vecs0
[544.587594] Dynamic subtest vecs0: SUCCESS (0.027s)
[544.636457] Starting dynamic subtest: ccs0
[544.638865] Dynamic subtest ccs0: SUCCESS (0.028s)
[544.696397] Subtest before: SUCCESS (0.388s)
[544.888116] [096/142] (473s left) gem_exec_schedule (implicit-read-write)
[545.345232] Starting subtest: implicit-read-write
[545.347882] Starting dynamic subtest: rcs0
[545.856204] Dynamic subtest rcs0: SUCCESS (0.509s)
[545.905436] Starting dynamic subtest: bcs0
[546.234683] Dynamic subtest bcs0: SUCCESS (0.374s)
[546.294188] Starting dynamic subtest: vcs0
[546.536629] Dynamic subtest vcs0: SUCCESS (0.297s)
[546.591716] Starting dynamic subtest: vcs1
[546.836582] Dynamic subtest vcs1: SUCCESS (0.294s)
[546.882183] Starting dynamic subtest: vecs0
[547.110195] Dynamic subtest vecs0: SUCCESS (0.269s)
[547.156749] Starting dynamic subtest: ccs0
[547.507475] Dynamic subtest ccs0: SUCCESS (0.393s)
[547.510065] Subtest implicit-read-write: SUCCESS (2.164s)
[548.052545] [097/142] (470s left) perf_pmu (most-busy-check-all)
[548.454599] Starting subtest: most-busy-check-all
[548.457316] Starting dynamic subtest: rcs0
[549.576196] Dynamic subtest rcs0: SUCCESS (1.118s)
[549.632101] Starting dynamic subtest: bcs0
[551.560917] Dynamic subtest bcs0: SUCCESS (1.979s)
[551.614904] Starting dynamic subtest: vcs0
[553.544394] Dynamic subtest vcs0: SUCCESS (1.978s)
[553.595967] Starting dynamic subtest: vcs1
[555.528482] Dynamic subtest vcs1: SUCCESS (1.979s)
[555.578299] Starting dynamic subtest: vecs0
[557.576345] Dynamic subtest vecs0: SUCCESS (2.043s)
[557.627229] Starting dynamic subtest: ccs0
[559.560763] Dynamic subtest ccs0: SUCCESS (1.980s)
[559.563503] Subtest most-busy-check-all: SUCCESS (11.106s)
[559.762509] [098/142] (459s left) gem_exec_flush (basic-uc-ro-default)
[560.205105] Starting subtest: basic-uc-ro-default
[566.026857] Subtest basic-uc-ro-default: SUCCESS (5.824s)
[566.294395] [099/142] (452s left) gem_mmap_gtt (big-bo)
[566.681045] Subtest big-bo: SKIP (0.000s)
[566.821548] [100/142] (452s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[567.340698] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[567.345272] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[567.532942] [101/142] (451s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs)
[569.056523] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs
[569.060531] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[569.888879] [102/142] (449s left) gem_pread (bench)
[570.271676] Subtest bench: SKIP (0.000s)
[570.414145] [103/142] (448s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[570.986822] Starting subtest: y-tiled-8bpp-rotate-90
[570.991845] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[571.149655] [104/142] (448s left) gem_softpin (evict-snoop)
[571.557269] Starting subtest: evict-snoop
[571.563248] Subtest evict-snoop: SKIP (0.000s)
[571.743633] [105/142] (447s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs)
[573.527833] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs
[573.535257] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs: SUCCESS (0.004s)
[574.165991] [106/142] (445s left) kms_vblank (pipe-c-ts-continuation-idle)
[574.655119] Starting subtest: pipe-C-ts-continuation-idle
[576.841977] Subtest pipe-C-ts-continuation-idle: SUCCESS (2.188s)
[577.802383] [107/142] (441s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[578.179139] Starting subtest: multi-wait-all-for-submit-signaled
[578.181568] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.002s)
[578.337664] [108/142] (441s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[579.907818] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[579.924184] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.014s)
[580.773870] [109/142] (439s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[581.285693] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[581.292134] Starting dynamic subtest: pipe-A-eDP-1
[581.390137] Dynamic subtest pipe-A-eDP-1: SKIP (0.093s)
[581.446846] Starting dynamic subtest: pipe-B-eDP-1
[581.591118] Dynamic subtest pipe-B-eDP-1: SKIP (0.189s)
[581.647080] Starting dynamic subtest: pipe-C-eDP-1
[581.791117] Dynamic subtest pipe-C-eDP-1: SKIP (0.189s)
[581.846672] Starting dynamic subtest: pipe-D-eDP-1
[581.990186] Dynamic subtest pipe-D-eDP-1: SKIP (0.190s)
[581.994640] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.706s)
[582.164920] [110/142] (437s left) kms_plane_alpha_blend (alpha-7efc)
[582.652417] Starting subtest: alpha-7efc
[584.439570] Starting dynamic subtest: pipe-A-eDP-1
[585.670107] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.229s)
[585.864764] Starting dynamic subtest: pipe-D-eDP-1
[588.131962] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.314s)
[588.134336] Subtest alpha-7efc: SUCCESS (5.482s)
[589.093264] [111/142] (430s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[589.607625] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[589.610697] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[589.789845] [112/142] (430s left) syncobj_wait (multi-wait-all-submitted-signaled)
[590.163940] Starting subtest: multi-wait-all-submitted-signaled
[590.270704] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.105s)
[590.426481] [113/142] (429s left) gen9_exec_parse (batch-invalid-length)
[590.817114] Subtest batch-invalid-length: SKIP (0.000s)
[590.943388] [114/142] (429s left) gem_ccs (block-multicopy-inplace)
[591.332975] Starting subtest: block-multicopy-inplace
[591.337774] Subtest block-multicopy-inplace: SKIP (0.000s)
[591.514744] [115/142] (428s left) kms_flip (2x-nonexisting-fb)
[591.905239] Starting subtest: 2x-nonexisting-fb
[592.065839] Subtest 2x-nonexisting-fb: SKIP (0.158s)
[592.266446] [116/142] (428s left) kms_fbcon_fbt (fbc-suspend)
[592.669137] Starting subtest: fbc-suspend
[646.900872] Subtest fbc-suspend: SUCCESS (54.234s)
[647.108328] [117/142] (373s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[647.488563] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[647.490860] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.002s)
[647.646633] [118/142] (373s left) kms_atomic (plane-primary-legacy)
[648.452111] Starting subtest: plane-primary-legacy
[648.749579] Subtest plane-primary-legacy: SUCCESS (0.297s)
[650.150884] [119/142] (370s left) kms_prop_blob (basic)
[650.522056] Starting subtest: basic
[650.524373] Subtest basic: SUCCESS (0.000s)
[650.682416] [120/142] (370s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[651.186430] Starting subtest: fbc-rgb101010-draw-blt
[656.511863] Subtest fbc-rgb101010-draw-blt: SUCCESS (5.325s)
[656.797369] [121/142] (364s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[657.311614] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[679.120622] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (21.812s)
[679.380888] [122/142] (341s left) api_intel_allocator (reopen)
[679.749149] Starting subtest: reopen
[679.762464] Subtest reopen: SUCCESS (0.016s)
[679.957099] [123/142] (341s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-5)
[680.439269] Starting subtest: plane-downscale-with-rotation-factor-0-5
[680.445733] Starting dynamic subtest: pipe-A-eDP-1
[680.591297] Dynamic subtest pipe-A-eDP-1: SKIP (0.142s)
[680.649888] Starting dynamic subtest: pipe-B-eDP-1
[680.837576] Dynamic subtest pipe-B-eDP-1: SKIP (0.233s)
[680.892789] Starting dynamic subtest: pipe-C-eDP-1
[681.080654] Dynamic subtest pipe-C-eDP-1: SKIP (0.234s)
[681.136178] Starting dynamic subtest: pipe-D-eDP-1
[681.323579] Dynamic subtest pipe-D-eDP-1: SKIP (0.234s)
[681.327542] Subtest plane-downscale-with-rotation-factor-0-5: SKIP (0.886s)
[681.533523] [124/142] (339s left) v3d/v3d_perfmon (destroy-invalid-perfmon)
[682.008282] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[682.152488] [125/142] (339s left) perf (gen8-unprivileged-single-ctx-counters)
[682.782646] Starting subtest: gen8-unprivileged-single-ctx-counters
[682.787691] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[682.898302] [126/142] (338s left) kms_cursor_crc (cursor-offscreen-32x10)
[683.601563] Starting subtest: cursor-offscreen-32x10
[683.898197] Subtest cursor-offscreen-32x10: SKIP (0.294s)
[684.781278] [127/142] (336s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[685.176588] Starting subtest: multi-wait-all-for-submit-available-signaled
[685.186335] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.004s)
[685.344278] [128/142] (336s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[685.910419] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[687.518048] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.605s)
[688.476396] [129/142] (333s left) vgem_basic (second-client)
[688.865173] Starting subtest: second-client
[688.886623] Subtest second-client: SUCCESS (0.020s)
[689.029132] [130/142] (332s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[689.571250] Starting subtest: yf-tiled-addfb-size-offset-overflow
[689.575686] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[689.754767] [131/142] (332s left) kms_cursor_edge_walk (256x256-left-edge)
[690.233498] Starting subtest: 256x256-left-edge
[690.238668] Starting dynamic subtest: pipe-A-eDP-1
[694.142094] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.902s)
[694.205971] Starting dynamic subtest: pipe-D-eDP-1
[697.604416] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.451s)
[697.606605] Subtest 256x256-left-edge: SUCCESS (7.375s)
[698.554753] [132/142] (323s left) kms_vblank (pipe-d-ts-continuation-idle-hang)
[699.041322] Starting subtest: pipe-D-ts-continuation-idle-hang
[715.827619] Subtest pipe-D-ts-continuation-idle-hang: SUCCESS (16.787s)
[716.786287] [133/142] (305s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_mtl_mc_ccs)
[717.916489] Starting subtest: pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs
[720.978783] Subtest pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs: SUCCESS (3.058s)
[722.613243] [134/142] (299s left) gem_mmap_gtt (zero-extend)
[722.984858] Subtest zero-extend: SKIP (0.000s)
[723.109687] [135/142] (299s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[723.606722] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[723.610501] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[723.795564] [136/142] (298s left) i915_pm_rps (thresholds-park)
[724.193058] Starting subtest: thresholds-park
[724.195193] Starting dynamic subtest: gt0
[724.209994] Dynamic subtest gt0: SKIP (0.013s)
[724.270639] Starting dynamic subtest: gt1
[724.274545] Dynamic subtest gt1: SKIP (0.003s)
[724.278284] Subtest thresholds-park: SKIP (0.025s)
[724.367836] [137/142] (298s left) kms_ccs (pipe-a-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[725.395197] Starting subtest: pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[725.397361] Subtest pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[726.829265] [138/142] (295s left) gem_userptr_blits (readonly-unsync)
[727.223135] Subtest readonly-unsync: SKIP (0.000s)
[727.513334] [139/142] (295s left) gem_exec_fence (long-history)
[729.366413] Starting subtest: long-history
[735.305726] Subtest long-history: SUCCESS (5.938s)
[736.615499] [140/142] (286s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[737.147545] Starting subtest: fbcpsr-rgb101010-draw-blt
[745.225167] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (8.076s)
[745.494169] [141/142] (277s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[746.001144] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[746.005529] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[746.185717] [142/142] (276s left) kms_addfb_basic (unused-handle)
[746.561201] Starting subtest: unused-handle
[746.563644] Subtest unused-handle: SUCCESS (0.000s)
[746.840902] Closing watchdogs
Done.