[28.155199] Initializing watchdogs
[28.155258]   /dev/watchdog0
[28.159492] [001/142] (960s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_dg2_mc_ccs)
[28.503422] Starting subtest: pipe-D-random-ccs-data-4_tiled_dg2_mc_ccs
[28.505473] Subtest pipe-D-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[28.609818] [002/142] (960s left) gem_ctx_persistence (legacy-engines-mixed)
[28.661841] Starting subtest: legacy-engines-mixed
[28.662711] Starting dynamic subtest: render
[28.702058] Dynamic subtest render: SUCCESS (0.040s)
[28.706794] Starting dynamic subtest: bsd1
[28.739947] Dynamic subtest bsd1: SUCCESS (0.038s)
[28.744445] Starting dynamic subtest: bsd2
[28.784961] Dynamic subtest bsd2: SUCCESS (0.045s)
[28.789361] Starting dynamic subtest: blt
[28.825280] Dynamic subtest blt: SUCCESS (0.040s)
[28.829307] Starting dynamic subtest: vebox
[28.865201] Dynamic subtest vebox: SUCCESS (0.039s)
[28.866577] Subtest legacy-engines-mixed: SUCCESS (0.203s)
[28.915509] [003/142] (959s left) gem_ctx_param (invalid-ctx-get)
[28.976533] Starting subtest: invalid-ctx-get
[28.977426] Subtest invalid-ctx-get: SUCCESS (0.000s)
[29.015266] [004/142] (959s left) gem_userptr_blits (set-cache-level)
[29.077473] Starting subtest: set-cache-level
[29.078206] Subtest set-cache-level: SUCCESS (0.000s)
[29.171414] [005/142] (959s left) kms_flip (2x-modeset-vs-vblank-race)
[29.257482] Starting subtest: 2x-modeset-vs-vblank-race
[29.269497] Subtest 2x-modeset-vs-vblank-race: SKIP (0.012s)
[29.308959] [006/142] (959s left) gem_exec_reloc (basic-write-cpu-active)
[29.359121] Starting subtest: basic-write-cpu-active
[29.484949] Subtest basic-write-cpu-active: SUCCESS (0.117s)
[29.567520] [007/142] (959s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_mc_ccs)
[29.832304] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs
[29.833703] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[30.042549] [008/142] (958s left) kms_content_protection (dp-mst-lic-type-1)
[30.144545] Starting subtest: dp-mst-lic-type-1
[30.146034] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[30.201172] [009/142] (958s left) kms_invalid_mode (bad-vsync-end)
[30.301826] Starting subtest: bad-vsync-end
[30.302576] Starting dynamic subtest: HDMI-A-1-pipe-A
[30.358805] Dynamic subtest HDMI-A-1-pipe-A: SUCCESS (0.057s)
[30.362203] Starting dynamic subtest: HDMI-A-1-pipe-B
[30.367161] Dynamic subtest HDMI-A-1-pipe-B: SUCCESS (0.008s)
[30.371505] Starting dynamic subtest: HDMI-A-1-pipe-C
[30.372232] Dynamic subtest HDMI-A-1-pipe-C: SUCCESS (0.001s)
[30.375577] Starting dynamic subtest: HDMI-A-1-pipe-D
[30.376338] Dynamic subtest HDMI-A-1-pipe-D: SUCCESS (0.001s)
[30.377155] Subtest bad-vsync-end: SUCCESS (0.069s)
[30.468267] [010/142] (958s left) kms_flip (2x-plain-flip-interruptible)
[30.553833] Starting subtest: 2x-plain-flip-interruptible
[30.567247] Subtest 2x-plain-flip-interruptible: SKIP (0.013s)
[30.601622] [011/142] (958s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[30.704858] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[30.706335] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[30.751248] [012/142] (958s left) kms_cursor_crc (cursor-onscreen-512x512)
[30.853340] Starting subtest: cursor-onscreen-512x512
[30.855754] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[30.901158] [013/142] (957s left) gem_exec_schedule (submit-early-slice)
[30.959076] Starting subtest: submit-early-slice
[30.959839] Starting dynamic subtest: rcs0
[30.984753] Dynamic subtest rcs0: SUCCESS (0.026s)
[30.988458] Starting dynamic subtest: bcs0
[31.007903] Dynamic subtest bcs0: SUCCESS (0.023s)
[31.011367] Starting dynamic subtest: vcs0
[31.031554] Dynamic subtest vcs0: SUCCESS (0.024s)
[31.035074] Starting dynamic subtest: vcs1
[31.054543] Dynamic subtest vcs1: SUCCESS (0.023s)
[31.058763] Starting dynamic subtest: vecs0
[31.078977] Dynamic subtest vecs0: SUCCESS (0.024s)
[31.079818] Subtest submit-early-slice: SUCCESS (0.120s)
[31.353222] [014/142] (957s left) kms_vrr (flip-suspend)
[31.465282] Starting subtest: flip-suspend
[31.466858] Subtest flip-suspend: SKIP (0.000s)
[31.501752] [015/142] (957s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[31.604060] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[31.605476] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[31.642854] [016/142] (957s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[31.689772] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[31.690489] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[31.726544] [017/142] (957s left) kms_universal_plane (universal-plane-pipe-b-sanity)
[31.825146] Starting subtest: universal-plane-pipe-B-sanity
[32.159403] Subtest universal-plane-pipe-B-sanity: SUCCESS (0.334s)
[32.230459] [018/142] (956s left) kms_vblank (pipe-c-ts-continuation-dpms-rpm)
[32.326025] Starting subtest: pipe-C-ts-continuation-dpms-rpm
[33.904970] Subtest pipe-C-ts-continuation-dpms-rpm: SUCCESS (1.579s)
[34.000044] [019/142] (954s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[34.190593] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[34.192054] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[34.436976] [020/142] (954s left) gem_exec_params (dr4-dirt)
[34.486838] Starting subtest: DR4-dirt
[34.487562] Subtest DR4-dirt: SUCCESS (0.000s)
[34.536984] [021/142] (954s left) syncobj_wait (invalid-wait-illegal-handle)
[34.586415] Starting subtest: invalid-wait-illegal-handle
[34.587174] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[34.621048] [022/142] (954s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[34.722708] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[34.724096] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[34.778783] [023/142] (954s left) kms_addfb_basic (legacy-format)
[34.826355] Starting subtest: legacy-format
[34.888223] Subtest legacy-format: SUCCESS (0.041s)
[34.962768] [024/142] (953s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[35.066753] Starting subtest: yf-tiled-16bpp-rotate-180
[35.068187] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[35.129670] [025/142] (953s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[35.229234] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[35.230594] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[35.287446] [026/142] (953s left) vc4/vc4_tiling (get-bad-flags)
[35.359280] Subtest get-bad-flags: SKIP (0.000s)
[35.388551] [027/142] (953s left) kms_busy (basic)
[35.488612] Starting subtest: basic
[35.490325] Starting dynamic subtest: flip
[35.825212] Dynamic subtest flip: SUCCESS (0.335s)
[35.829314] Starting dynamic subtest: modeset
[36.304686] Dynamic subtest modeset: SUCCESS (0.478s)
[36.305516] Subtest basic: SUCCESS (0.815s)
[36.424137] [028/142] (952s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[36.535823] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[36.537229] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[36.598633] [029/142] (952s left) i915_pm_rpm (drm-resources-equal)
[38.107395] Starting subtest: drm-resources-equal
[51.748367] Subtest drm-resources-equal: FAIL (13.628s)
[51.871818] [030/142] (937s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[51.969868] Starting subtest: basic-flip-before-cursor-varying-size
[52.491420] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.522s)
[52.593678] [031/142] (936s left) gem_readwrite (read-write)
[52.641249] Starting subtest: read-write
[52.644404] Subtest read-write: SUCCESS (0.000s)
[52.693233] [032/142] (936s left) kms_cursor_legacy (forked-bo)
[52.788637] Starting subtest: forked-bo
[52.789459] Starting dynamic subtest: pipe-A
[58.236097] Dynamic subtest pipe-A: SUCCESS (5.412s)
[58.241189] Starting dynamic subtest: pipe-B
[63.659750] Dynamic subtest pipe-B: SUCCESS (5.424s)
[63.664375] Starting dynamic subtest: pipe-C
[69.061118] Dynamic subtest pipe-C: SUCCESS (5.426s)
[69.064795] Starting dynamic subtest: pipe-D
[74.489606] Dynamic subtest pipe-D: SUCCESS (5.428s)
[74.493622] Starting dynamic subtest: all-pipes
[79.929532] Dynamic subtest all-pipes: SUCCESS (5.438s)
[79.930852] Subtest forked-bo: SUCCESS (27.132s)
[79.971368] [033/142] (909s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_gen12_mc_ccs)
[80.261702] Starting subtest: pipe-C-random-ccs-data-y_tiled_gen12_mc_ccs
[80.264122] Subtest pipe-C-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[80.432546] [034/142] (908s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-25)
[80.529304] Starting subtest: planes-upscale-20x20-downscale-factor-0-25
[80.530031] Starting dynamic subtest: pipe-A-HDMI-A-1
[80.543617] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.012s)
[80.547318] Starting dynamic subtest: pipe-B-HDMI-A-1
[80.572376] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.027s)
[80.576087] Starting dynamic subtest: pipe-C-HDMI-A-1
[80.601250] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.027s)
[80.605019] Starting dynamic subtest: pipe-D-HDMI-A-1
[80.630618] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.027s)
[80.632075] Subtest planes-upscale-20x20-downscale-factor-0-25: SKIP (0.101s)
[80.691163] [035/142] (908s left) kms_concurrent (pipe-b)
[80.789310] Starting subtest: pipe-B
[80.790973] Starting dynamic subtest: HDMI-A-1
[81.126608] Dynamic subtest HDMI-A-1: SUCCESS (0.336s)
[81.127396] Subtest pipe-B: SUCCESS (0.338s)
[81.200343] [036/142] (907s left) gem_exec_basic (basic)
[81.252222] Starting subtest: basic
[81.253011] Starting dynamic subtest: rcs0-smem
[81.254865] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[81.258511] Starting dynamic subtest: bcs0-smem
[81.259230] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[81.263066] Starting dynamic subtest: vcs0-smem
[81.263847] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[81.267293] Starting dynamic subtest: vcs1-smem
[81.268010] Dynamic subtest vcs1-smem: SUCCESS (0.000s)
[81.271413] Starting dynamic subtest: vecs0-smem
[81.272152] Dynamic subtest vecs0-smem: SUCCESS (0.000s)
[81.272848] Subtest basic: SUCCESS (0.005s)
[81.399989] [037/142] (907s left) gem_workarounds (basic-read-fd)
[81.457409] Starting subtest: basic-read-fd
[81.464080] Subtest basic-read-fd: SUCCESS (0.006s)
[81.533147] [038/142] (907s left) kms_draw_crc (draw-method-mmap-wc)
[81.630654] Starting subtest: draw-method-mmap-wc
[81.631385] Starting dynamic subtest: xrgb8888-untiled
[81.778743] Dynamic subtest xrgb8888-untiled: SUCCESS (0.148s)
[81.782433] Starting dynamic subtest: rgb565-untiled
[81.928721] Dynamic subtest rgb565-untiled: SUCCESS (0.149s)
[81.932839] Starting dynamic subtest: xrgb2101010-untiled
[82.053720] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.124s)
[82.057922] Starting dynamic subtest: xrgb8888-xtiled
[82.128718] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.074s)
[82.132993] Starting dynamic subtest: rgb565-xtiled
[82.203791] Dynamic subtest rgb565-xtiled: SUCCESS (0.074s)
[82.207716] Starting dynamic subtest: xrgb2101010-xtiled
[82.270409] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.066s)
[82.274566] Starting dynamic subtest: xrgb8888-ytiled
[82.345465] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.074s)
[82.349597] Starting dynamic subtest: rgb565-ytiled
[82.420459] Dynamic subtest rgb565-ytiled: SUCCESS (0.074s)
[82.424082] Starting dynamic subtest: xrgb2101010-ytiled
[82.495401] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.074s)
[82.496122] Subtest draw-method-mmap-wc: SUCCESS (0.865s)
[82.549754] [039/142] (906s left) kms_atomic_transition (plane-all-transition-fencing)
[82.646182] Starting subtest: plane-all-transition-fencing
[82.646893] Starting dynamic subtest: pipe-A-HDMI-A-1
[84.570770] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.924s)
[84.634397] Starting dynamic subtest: pipe-B-HDMI-A-1
[86.637511] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.007s)
[86.678757] Subtest plane-all-transition-fencing: SUCCESS (4.032s)
[86.764279] [040/142] (902s left) device_reset (unbind-cold-reset-rebind)
[87.136287] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[87.165726] [041/142] (901s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[87.271942] Starting subtest: fbcpsr-1p-rte
[87.273324] Subtest fbcpsr-1p-rte: SKIP (0.000s)
[87.331198] [042/142] (901s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[87.432754] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[87.434285] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[87.481597] [043/142] (901s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[87.582937] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[87.584519] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[87.631408] [044/142] (901s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[88.004613] Starting subtest: pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[88.007857] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.003s)
[88.092070] [045/142] (901s left) syncobj_wait (single-wait-all-for-submit-signaled)
[88.139758] Starting subtest: single-wait-all-for-submit-signaled
[88.140543] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[88.175288] [046/142] (900s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-25)
[88.270543] Starting subtest: plane-downscale-with-rotation-factor-0-25
[88.272504] Starting dynamic subtest: pipe-A-HDMI-A-1
[88.287766] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.014s)
[88.291570] Starting dynamic subtest: pipe-B-HDMI-A-1
[88.317825] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.028s)
[88.321503] Starting dynamic subtest: pipe-C-HDMI-A-1
[88.347680] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.028s)
[88.352378] Starting dynamic subtest: pipe-D-HDMI-A-1
[88.377638] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.028s)
[88.379043] Subtest plane-downscale-with-rotation-factor-0-25: SKIP (0.107s)
[88.433441] [047/142] (900s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[88.529575] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[88.531403] Starting dynamic subtest: pipe-A-valid-mode
[88.579915] Dynamic subtest pipe-A-valid-mode: SKIP (0.048s)
[88.581413] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.050s)
[88.651047] [048/142] (900s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[88.817244] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[88.819428] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[89.105688] [049/142] (900s left) gem_exec_schedule (deep)
[89.279743] Starting subtest: deep
[89.280542] Starting dynamic subtest: rcs0
[96.222312] Dynamic subtest rcs0: SUCCESS (6.943s)
[96.227487] Starting dynamic subtest: bcs0
[103.320848] Dynamic subtest bcs0: SUCCESS (7.098s)
[103.326018] Starting dynamic subtest: vcs0
[110.418367] Dynamic subtest vcs0: SUCCESS (7.097s)
[110.423497] Starting dynamic subtest: vcs1
[117.556604] Dynamic subtest vcs1: SUCCESS (7.137s)
[117.561751] Starting dynamic subtest: vecs0
[124.715123] Dynamic subtest vecs0: SUCCESS (7.157s)
[124.715997] Subtest deep: SUCCESS (35.437s)
[125.166227] [050/142] (864s left) gem_fenced_exec_thrash (no-spare-fences)
[125.226587] Starting subtest: no-spare-fences
[127.379188] Subtest no-spare-fences: SUCCESS (2.151s)
[127.463506] [051/142] (861s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[127.760571] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[127.762158] Subtest pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[127.953372] [052/142] (861s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[128.055779] Starting subtest: psr-shrfb-scaledprimary
[128.057133] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[128.111792] [053/142] (861s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[128.266495] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[128.334175] [054/142] (860s left) gem_exec_fair (basic-none-vip)
[128.389653] Starting subtest: basic-none-vip
[128.390435] Starting dynamic subtest: rcs0
[133.643900] Dynamic subtest rcs0: FAIL (5.229s)
[133.644591] Subtest basic-none-vip: FAIL (5.230s)
[133.774035] [055/142] (855s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[133.883346] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[133.884078] Starting dynamic subtest: pipe-A-valid-mode
[133.937297] Dynamic subtest pipe-A-valid-mode: SKIP (0.052s)
[133.938907] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.053s)
[134.011102] [056/142] (855s left) dmabuf_sync_file (import-basic)
[134.064586] Starting subtest: import-basic
[134.065376] Subtest import-basic: SUCCESS (0.001s)
[134.094017] [057/142] (855s left) gem_ctx_shared (q-independent)
[134.148231] Starting subtest: Q-independent
[134.149066] Starting dynamic subtest: rcs0
[134.164751] Dynamic subtest rcs0: SUCCESS (0.016s)
[134.170087] Starting dynamic subtest: bcs0
[134.181975] Dynamic subtest bcs0: SUCCESS (0.016s)
[134.187221] Starting dynamic subtest: vcs0
[134.200951] Dynamic subtest vcs0: SUCCESS (0.018s)
[134.206569] Starting dynamic subtest: vcs1
[134.220927] Dynamic subtest vcs1: SUCCESS (0.019s)
[134.226142] Starting dynamic subtest: vecs0
[134.235906] Dynamic subtest vecs0: SUCCESS (0.015s)
[134.236864] Subtest Q-independent: SUCCESS (0.088s)
[134.396768] [058/142] (854s left) kms_vblank (pipe-b-wait-forked-hang)
[134.504831] Starting subtest: pipe-B-wait-forked-hang
[148.978400] Subtest pipe-B-wait-forked-hang: SUCCESS (14.474s)
[149.093743] [059/142] (840s left) gem_wait (write-wait)
[149.151805] Starting subtest: write-wait
[149.152572] Starting dynamic subtest: all-engines
[150.180666] Dynamic subtest all-engines: SUCCESS (1.025s)
[150.187115] Starting dynamic subtest: rcs0
[151.207484] Dynamic subtest rcs0: SUCCESS (1.023s)
[151.212415] Starting dynamic subtest: bcs0
[152.235368] Dynamic subtest bcs0: SUCCESS (1.031s)
[152.244238] Starting dynamic subtest: vcs0
[153.273576] Dynamic subtest vcs0: SUCCESS (1.036s)
[153.280987] Starting dynamic subtest: vcs1
[154.306367] Dynamic subtest vcs1: SUCCESS (1.031s)
[154.313769] Starting dynamic subtest: vecs0
[155.336615] Dynamic subtest vecs0: SUCCESS (1.027s)
[155.337753] Subtest write-wait: SUCCESS (6.184s)
[155.510948] [060/142] (833s left) v3d/v3d_get_bo_offset (create-get-offsets)
[155.582363] Subtest create-get-offsets: SKIP (0.000s)
[155.610404] [061/142] (833s left) kms_getfb (getfb2-handle-not-fb)
[155.665893] Starting subtest: getfb2-handle-not-fb
[155.714703] Subtest getfb2-handle-not-fb: SUCCESS (0.049s)
[155.769534] [062/142] (833s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[155.871043] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[155.872420] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[155.919863] [063/142] (833s left) kms_addfb_basic (bad-pitch-256)
[155.967627] Starting subtest: bad-pitch-256
[155.968418] Subtest bad-pitch-256: SUCCESS (0.000s)
[156.086104] [064/142] (833s left) gem_flink_race (flink_name)
[156.131148] Starting subtest: flink_name
[161.511508] Subtest flink_name: SUCCESS (5.371s)
[161.577978] [065/142] (827s left) kms_legacy_colorkey (invalid-plane)
[161.703326] Starting subtest: invalid-plane
[161.704207] Starting dynamic subtest: zero-id
[161.704992] Dynamic subtest zero-id: SUCCESS (0.000s)
[161.708991] Starting dynamic subtest: outrange-id
[161.709794] Dynamic subtest outrange-id: SUCCESS (0.000s)
[161.710558] Subtest invalid-plane: SUCCESS (0.001s)
[161.737126] [066/142] (827s left) gem_exec_params (secure-non-master)
[161.786206] Starting subtest: secure-non-master
[161.787636] Subtest secure-non-master: SKIP (0.000s)
[161.830530] [067/142] (827s left) vc4/vc4_mmap (mmap-bo)
[161.908460] Subtest mmap-bo: SKIP (0.000s)
[161.941011] [068/142] (827s left) kms_sysfs_edid_timing
[162.529853] [069/142] (826s left) i915_pm_rpm (modeset-lpsp-stress)
[164.046547] Starting subtest: modeset-lpsp-stress
[174.994157] Subtest modeset-lpsp-stress: FAIL (10.926s)
[175.090503] [070/142] (814s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[175.234635] Starting subtest: plane-primary-overlay-mutable-zpos
[175.237055] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.001s)
[175.375083] [071/142] (814s left) gem_pwrite (basic-self)
[175.422827] Starting subtest: basic-self
[175.462512] Subtest basic-self: SUCCESS (0.040s)
[175.516380] [072/142] (813s left) v3d/v3d_submit_csd (bad-in-sync)
[175.587440] Subtest bad-in-sync: SKIP (0.000s)
[175.616230] [073/142] (813s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[175.899434] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[175.900959] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[176.066950] [074/142] (813s left) perf_pmu (init-busy)
[176.116959] Starting subtest: init-busy
[176.117818] Starting dynamic subtest: rcs0
[176.118634] Dynamic subtest rcs0: SUCCESS (0.001s)
[176.123664] Starting dynamic subtest: bcs0
[176.124425] Dynamic subtest bcs0: SUCCESS (0.000s)
[176.129205] Starting dynamic subtest: vcs0
[176.129980] Dynamic subtest vcs0: SUCCESS (0.000s)
[176.134674] Starting dynamic subtest: vcs1
[176.135439] Dynamic subtest vcs1: SUCCESS (0.000s)
[176.140339] Starting dynamic subtest: vecs0
[176.141136] Dynamic subtest vecs0: SUCCESS (0.000s)
[176.141941] Subtest init-busy: SUCCESS (0.004s)
[176.160650] [075/142] (813s left) kms_cursor_crc (cursor-random-max-size)
[176.265043] Starting subtest: cursor-random-max-size
[176.267219] Subtest cursor-random-max-size: SKIP (0.000s)
[176.317098] [076/142] (813s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[176.417943] Starting subtest: y-tiled-64bpp-rotate-270
[176.453498] Subtest y-tiled-64bpp-rotate-270: SKIP (0.034s)
[176.525862] [077/142] (813s left) gem_exec_gttfill (basic)
[176.581355] Starting subtest: basic
[179.589473] Subtest basic: SUCCESS (3.008s)
[179.735025] [078/142] (809s left) i915_hangman (engine-error-state-capture)
[179.825891] Starting subtest: engine-error-state-capture
[179.826708] Starting dynamic subtest: rcs0
[183.784776] Dynamic subtest rcs0: SUCCESS (3.959s)
[183.797588] Starting dynamic subtest: bcs0
[187.750605] Dynamic subtest bcs0: SUCCESS (3.964s)
[187.761853] Starting dynamic subtest: vcs0
[191.787668] Dynamic subtest vcs0: SUCCESS (4.036s)
[191.795464] Starting dynamic subtest: vcs1
[195.747959] Dynamic subtest vcs1: SUCCESS (3.953s)
[195.755564] Starting dynamic subtest: vecs0
[199.782793] Dynamic subtest vecs0: SUCCESS (4.039s)
[199.784071] Subtest engine-error-state-capture: SUCCESS (19.958s)
[199.837537] [079/142] (789s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[199.952974] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[199.954482] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[200.013060] [080/142] (789s left) gem_render_copy (linear)
[200.064284] Starting subtest: linear
[200.065184] Starting dynamic subtest: smem
[200.130364] Dynamic subtest smem: SUCCESS (0.066s)
[200.131205] Subtest linear: SUCCESS (0.066s)
[200.290164] [081/142] (789s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[200.400875] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[200.402325] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[200.454737] [082/142] (789s left) perf (per-context-mode-unprivileged)
[200.554747] Starting subtest: per-context-mode-unprivileged
[200.556409] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[200.570402] [083/142] (789s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[200.670878] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[200.672304] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[200.721271] [084/142] (788s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc)
[200.924457] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc
[201.080813] Subtest pipe-B-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SUCCESS (0.156s)
[201.363465] [085/142] (788s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[201.554239] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[201.557236] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.002s)
[201.828289] [086/142] (787s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[201.930110] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[201.931602] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[201.977803] [087/142] (787s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[202.026631] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[202.027399] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[202.062047] [088/142] (787s left) gem_exec_whisper (basic-queues-all)
[202.116298] Starting subtest: basic-queues-all
[204.065586] Subtest basic-queues-all: SUCCESS (1.948s)
[204.221692] [089/142] (785s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[204.337347] Starting subtest: x-tiled-16bpp-rotate-180
[205.298588] Subtest x-tiled-16bpp-rotate-180: SUCCESS (0.961s)
[205.406424] [090/142] (784s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_mc_ccs)
[205.509556] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_mc_ccs
[205.511190] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[205.887521] [091/142] (783s left) syncobj_timeline (signal-array)
[205.940485] Starting subtest: signal-array
[205.941219] Subtest signal-array: SUCCESS (0.000s)
[205.982054] [092/142] (783s left) api_intel_allocator (open-vm)
[206.031861] Starting subtest: open-vm
[206.032702] Subtest open-vm: SUCCESS (0.001s)
[206.094650] [093/142] (783s left) kms_psr2_su (page_flip-xrgb8888)
[206.142710] Subtest page_flip-XRGB8888: SKIP (0.000s)
[206.161893] [094/142] (783s left) kms_vblank (invalid)
[206.261134] Starting subtest: invalid
[206.399718] Subtest invalid: SUCCESS (0.139s)
[206.508684] [095/142] (783s left) kms_plane (invalid-pixel-format-settings)
[206.607032] Starting subtest: invalid-pixel-format-settings
[206.634216] Subtest invalid-pixel-format-settings: SUCCESS (0.027s)
[206.687176] [096/142] (783s left) kms_pipe_crc_basic (bad-source)
[206.782914] Starting subtest: bad-source
[206.783619] Subtest bad-source: SUCCESS (0.000s)
[206.820115] [097/142] (782s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[206.870592] Starting subtest: writes-after-reads
[207.888383] Subtest writes-after-reads: SUCCESS (1.018s)
[207.959403] [098/142] (781s left) kms_color (ctm-blue-to-red)
[208.068473] Starting subtest: ctm-blue-to-red
[208.069191] Starting dynamic subtest: pipe-A
[208.338218] Dynamic subtest pipe-A: SUCCESS (0.270s)
[208.344700] Starting dynamic subtest: pipe-B
[208.615457] Dynamic subtest pipe-B: SUCCESS (0.277s)
[208.622199] Starting dynamic subtest: pipe-C
[208.883010] Dynamic subtest pipe-C: SUCCESS (0.266s)
[208.889822] Starting dynamic subtest: pipe-D
[209.135455] Dynamic subtest pipe-D: SUCCESS (0.252s)
[209.136309] Subtest ctm-blue-to-red: SUCCESS (1.068s)
[209.233495] [099/142] (780s left) syncobj_timeline (wait-for-submit-complex)
[209.282489] Starting subtest: wait-for-submit-complex
[209.343798] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[209.383183] [100/142] (780s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[209.543940] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[209.545487] Subtest pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[209.831299] [101/142] (780s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[209.933753] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[209.935217] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[209.980957] [102/142] (779s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[210.029476] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[210.030264] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[210.064189] [103/142] (779s left) gem_bad_reloc (negative-reloc)
[210.114852] Starting subtest: negative-reloc
[210.117126] Subtest negative-reloc: SUCCESS (0.000s)
[210.172304] [104/142] (779s left) i915_hangman (gt-error-state-capture)
[210.226513] Starting subtest: gt-error-state-capture
[210.227296] Starting dynamic subtest: rcs0
[220.280107] Dynamic subtest rcs0: SUCCESS (10.053s)
[220.286617] Starting dynamic subtest: bcs0
[230.260168] Dynamic subtest bcs0: SUCCESS (9.979s)
[230.266665] Starting dynamic subtest: vcs0
[240.240416] Dynamic subtest vcs0: SUCCESS (9.976s)
[240.246826] Starting dynamic subtest: vcs1
[250.231070] Dynamic subtest vcs1: SUCCESS (9.993s)
[250.237551] Starting dynamic subtest: vecs0
[260.270797] Dynamic subtest vecs0: SUCCESS (10.038s)
[260.271642] Subtest gt-error-state-capture: SUCCESS (50.044s)
[260.312544] [105/142] (729s left) api_intel_bb (blit-reloc-purge-cache)
[260.362806] Starting subtest: blit-reloc-purge-cache
[260.368473] Subtest blit-reloc-purge-cache: SUCCESS (0.006s)
[260.446408] [106/142] (729s left) kms_frontbuffer_tracking (fbc-badstride)
[260.550776] Starting subtest: fbc-badstride
[267.725216] Subtest fbc-badstride: SUCCESS (7.176s)
[267.799918] [107/142] (722s left) kms_addfb_basic (tile-pitch-mismatch)
[267.857016] Starting subtest: tile-pitch-mismatch
[267.857845] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[267.949878] [108/142] (721s left) gem_mmap (bad-offset)
[268.000886] Starting subtest: bad-offset
[268.001702] Subtest bad-offset: SUCCESS (0.000s)
[268.062629] [109/142] (721s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[268.173233] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[268.174856] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[268.232668] [110/142] (721s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[268.331472] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[268.332890] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[268.381041] [111/142] (721s left) kms_flip (bo-too-big-interruptible)
[268.465775] Starting subtest: bo-too-big-interruptible
[268.468311] Starting dynamic subtest: A-HDMI-A1
[268.735645] Dynamic subtest A-HDMI-A1: SUCCESS (0.267s)
[268.740799] Starting dynamic subtest: B-HDMI-A1
[268.979214] Dynamic subtest B-HDMI-A1: SUCCESS (0.241s)
[268.985754] Starting dynamic subtest: C-HDMI-A1
[269.206489] Dynamic subtest C-HDMI-A1: SUCCESS (0.227s)
[269.212809] Starting dynamic subtest: D-HDMI-A1
[269.420769] Dynamic subtest D-HDMI-A1: SUCCESS (0.213s)
[269.422594] Subtest bo-too-big-interruptible: SUCCESS (0.956s)
[269.602112] [112/142] (720s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[269.688633] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[269.701950] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.013s)
[269.742609] [113/142] (720s left) gem_softpin (allocator-basic)
[269.797126] Starting subtest: allocator-basic
[269.858693] Subtest allocator-basic: SUCCESS (0.061s)
[269.932466] [114/142] (720s left) kms_psr (psr2_sprite_mmap_cpu)
[269.979978] Subtest psr2_sprite_mmap_cpu: SKIP (0.000s)
[270.002366] [115/142] (720s left) kms_plane_scaling (plane-upscale-with-pixel-format-factor-0-25)
[270.103381] Starting subtest: plane-upscale-with-pixel-format-factor-0-25
[270.104161] Starting dynamic subtest: pipe-A-HDMI-A-1
[271.693372] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.590s)
[271.701197] Starting dynamic subtest: pipe-B-HDMI-A-1
[273.335986] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.641s)
[273.343522] Starting dynamic subtest: pipe-C-HDMI-A-1
[273.518343] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.181s)
[273.526146] Starting dynamic subtest: pipe-D-HDMI-A-1
[273.708889] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.189s)
[273.709674] Subtest plane-upscale-with-pixel-format-factor-0-25: SUCCESS (3.607s)
[273.850149] [116/142] (716s left) gem_mmap_offset (close-race)
[273.906683] Starting subtest: close-race
[293.992954] Subtest close-race: SUCCESS (20.078s)
[294.058658] [117/142] (696s left) kms_chamelium_color (ctm-0-75)
[294.165938] Subtest ctm-0-75: SKIP (0.000s)
[294.186412] [118/142] (695s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[294.284201] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[300.143148] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (5.858s)
[300.250903] [119/142] (689s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[300.308099] Starting subtest: yf-tiled-ccs-to-x-tiled
[300.308910] Starting dynamic subtest: smem
[300.409914] Dynamic subtest smem: SUCCESS (0.101s)
[300.410733] Subtest yf-tiled-ccs-to-x-tiled: SUCCESS (0.102s)
[300.554315] [120/142] (689s left) gem_lmem_swapping (basic)
[300.606973] Subtest basic: SKIP (0.000s)
[300.624127] [121/142] (689s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[300.670485] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[300.784183] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[300.823423] [122/142] (689s left) gem_mmap_gtt (medium-copy-odd)
[300.882923] Starting subtest: medium-copy-odd
[304.430509] Subtest medium-copy-odd: SUCCESS (3.548s)
[304.471602] [123/142] (685s left) v3d/v3d_submit_csd (multisync-out-syncs)
[304.576912] Subtest multisync-out-syncs: SKIP (0.000s)
[304.606798] [124/142] (685s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_mc_ccs)
[304.845042] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs
[304.847108] Subtest pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[305.090355] [125/142] (685s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_mc_ccs)
[305.426975] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs
[305.428654] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[305.566789] [126/142] (684s left) gem_create (create-clear)
[305.616978] Starting subtest: create-clear
[305.617902] Starting dynamic subtest: smem0
[337.956941] Dynamic subtest smem0: SUCCESS (32.341s)
[337.958337] Subtest create-clear: SUCCESS (32.343s)
[338.143310] [127/142] (652s left) gem_ctx_isolation (nonpriv)
[338.248913] Starting subtest: nonpriv
[338.250488] Starting dynamic subtest: rcs0
[338.403368] Dynamic subtest rcs0: SUCCESS (0.146s)
[338.409063] Starting dynamic subtest: bcs0
[338.633158] Dynamic subtest bcs0: SUCCESS (0.229s)
[338.638993] Starting dynamic subtest: vcs0
[338.852032] Dynamic subtest vcs0: SUCCESS (0.218s)
[338.857776] Starting dynamic subtest: vcs1
[339.202956] Dynamic subtest vcs1: SUCCESS (0.350s)
[339.209033] Starting dynamic subtest: vecs0
[339.400593] Dynamic subtest vecs0: SUCCESS (0.198s)
[339.401326] Subtest nonpriv: SUCCESS (1.144s)
[339.550084] [128/142] (650s left) syncobj_timeline (invalid-signal-bad-pad)
[339.607632] Starting subtest: invalid-signal-bad-pad
[339.608652] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[339.656051] [129/142] (650s left) gem_exec_fence (syncobj-signal)
[340.000859] Starting subtest: syncobj-signal
[340.002934] Subtest syncobj-signal: SUCCESS (0.002s)
[340.264253] [130/142] (650s left) kms_getfb (getfb-handle-zero)
[340.314092] Starting subtest: getfb-handle-zero
[340.314973] Subtest getfb-handle-zero: SUCCESS (0.000s)
[340.374748] [131/142] (649s left) gem_exec_reloc (basic-gtt-read-noreloc)
[340.426927] Starting subtest: basic-gtt-read-noreloc
[340.429694] Subtest basic-gtt-read-noreloc: SUCCESS (0.003s)
[340.522416] [132/142] (649s left) gem_exec_schedule (noreorder-corked)
[340.711593] Starting subtest: noreorder-corked
[340.712569] Starting dynamic subtest: rcs0
[341.816815] Dynamic subtest rcs0: SUCCESS (1.113s)
[341.825367] Starting dynamic subtest: bcs0
[342.922360] Dynamic subtest bcs0: SUCCESS (1.104s)
[342.930785] Starting dynamic subtest: vcs0
[344.034538] Dynamic subtest vcs0: SUCCESS (1.111s)
[344.044946] Starting dynamic subtest: vcs1
[345.141383] Dynamic subtest vcs1: SUCCESS (1.105s)
[345.148914] Starting dynamic subtest: vecs0
[346.248470] Dynamic subtest vecs0: SUCCESS (1.106s)
[346.249703] Subtest noreorder-corked: SUCCESS (5.546s)
[346.409655] [133/142] (643s left) gem_workarounds (suspend-resume)
[346.473574] Starting subtest: suspend-resume
[361.977418] Subtest suspend-resume: SUCCESS (1.260s)
[367.771753] [134/142] (642s left) gem_userptr_blits (sync-unmap)
[367.833778] Starting subtest: sync-unmap
[367.848461] Subtest sync-unmap: SUCCESS (0.015s)
[367.940064] [135/142] (642s left) perf_pmu (busy-double-start)
[367.994172] Starting subtest: busy-double-start
[367.995160] Starting dynamic subtest: rcs0
[371.539610] Dynamic subtest rcs0: SUCCESS (3.544s)
[371.549071] Starting dynamic subtest: bcs0
[375.075639] Dynamic subtest bcs0: SUCCESS (3.534s)
[375.084423] Starting dynamic subtest: vcs0
[378.611560] Dynamic subtest vcs0: SUCCESS (3.534s)
[378.623501] Starting dynamic subtest: vcs1
[382.148698] Dynamic subtest vcs1: SUCCESS (3.535s)
[382.160769] Starting dynamic subtest: vecs0
[385.689671] Dynamic subtest vecs0: SUCCESS (3.539s)
[385.691195] Subtest busy-double-start: SUCCESS (17.696s)
[385.737075] [136/142] (624s left) kms_big_fb (linear-8bpp-rotate-270)
[385.842323] Starting subtest: linear-8bpp-rotate-270
[385.901664] Subtest linear-8bpp-rotate-270: SKIP (0.059s)
[385.972060] [137/142] (624s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[386.075920] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[386.077541] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[386.122212] [138/142] (624s left) i915_pm_rpm (universal-planes)
[387.704280] Starting subtest: universal-planes
[387.705605] Starting dynamic subtest: plane-31
[398.699376] Dynamic subtest plane-31: FAIL (10.974s)
[398.705586] Starting dynamic subtest: plane-40
[409.773613] Dynamic subtest plane-40: FAIL (11.071s)
[409.979242] Starting dynamic subtest: plane-49
[420.895874] Dynamic subtest plane-49: FAIL (10.908s)
[420.947268] Starting dynamic subtest: plane-58
[431.987030] Dynamic subtest plane-58: FAIL (11.033s)
[432.032877] Starting dynamic subtest: plane-67
[442.980407] Dynamic subtest plane-67: FAIL (10.941s)
[443.000721] Starting dynamic subtest: plane-76
[453.914026] Dynamic subtest plane-76: FAIL (10.889s)
[453.920789] Starting dynamic subtest: plane-85
[465.063459] Dynamic subtest plane-85: FAIL (11.149s)
[465.069682] Starting dynamic subtest: plane-94
[476.020475] Dynamic subtest plane-94: FAIL (10.942s)
[476.021371] Subtest universal-planes: FAIL (88.302s)
[476.160347] [139/142] (534s left) gem_exec_async (concurrent-writes)
[476.219307] Starting subtest: concurrent-writes
[476.220156] Starting dynamic subtest: rcs0
[476.224957] Dynamic subtest rcs0: SUCCESS (0.005s)
[476.233861] Starting dynamic subtest: bcs0
[476.234772] Dynamic subtest bcs0: SUCCESS (0.004s)
[476.243620] Starting dynamic subtest: vcs0
[476.244420] Dynamic subtest vcs0: SUCCESS (0.004s)
[476.252447] Starting dynamic subtest: vcs1
[476.253196] Dynamic subtest vcs1: SUCCESS (0.004s)
[476.260911] Starting dynamic subtest: vecs0
[476.261737] Dynamic subtest vecs0: SUCCESS (0.004s)
[476.262528] Subtest concurrent-writes: SUCCESS (0.025s)
[476.405801] [140/142] (534s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[476.517662] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[476.519267] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[476.575134] [141/142] (533s left) kms_psr (sprite_mmap_cpu)
[476.623394] Subtest sprite_mmap_cpu: SKIP (0.000s)
[476.642327] [142/142] (533s left) prime_busy (hang-wait)
[476.691970] Starting subtest: hang-wait
[476.692859] Starting dynamic subtest: rcs0
[490.345544] Dynamic subtest rcs0: SUCCESS (13.651s)
[490.354820] Starting dynamic subtest: bcs0
[497.193100] Dynamic subtest bcs0: SUCCESS (6.846s)
[497.201008] Starting dynamic subtest: vcs0
[504.169184] Dynamic subtest vcs0: SUCCESS (6.974s)
[504.176723] Starting dynamic subtest: vcs1
[511.144899] Dynamic subtest vcs1: SUCCESS (6.975s)
[511.152804] Starting dynamic subtest: vecs0
[518.184834] Dynamic subtest vecs0: SUCCESS (7.039s)
[518.186044] Subtest hang-wait: SUCCESS (41.492s)
[518.249608] Closing watchdogs
Done.