[59.879748] Initializing watchdogs
[59.879790]   /dev/watchdog0
[59.892582] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.896931] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.899035] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.902350] [001/131] (960s left) kms_flip (blocking-wf_vblank)
[60.064215] Starting subtest: blocking-wf_vblank
[60.066433] Starting dynamic subtest: A-eDP1
[69.256386] Dynamic subtest A-eDP1: FAIL (8.959s)
[69.265044] Starting dynamic subtest: B-eDP1
[77.854051] Dynamic subtest B-eDP1: SUCCESS (8.922s)
[77.863148] Starting dynamic subtest: C-eDP1
[86.760965] Dynamic subtest C-eDP1: SUCCESS (8.914s)
[86.769237] Starting dynamic subtest: D-eDP1
[95.686423] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[95.688784] Subtest blocking-wf_vblank: FAIL (35.722s)
[96.895721] [002/131] (923s left) gem_exec_suspend (basic)
[96.956484] Starting subtest: basic
[96.958835] Starting dynamic subtest: smem
[98.922761] Dynamic subtest smem: SUCCESS (1.964s)
[98.925618] Subtest basic: SUCCESS (1.964s)
[99.098966] [003/131] (921s left) perf_pmu (idle)
[99.142208] Starting subtest: idle
[99.144405] Starting dynamic subtest: rcs0
[99.686906] Dynamic subtest rcs0: SUCCESS (0.544s)
[99.696207] Starting dynamic subtest: bcs0
[100.214926] Dynamic subtest bcs0: SUCCESS (0.527s)
[100.222575] Starting dynamic subtest: vcs0
[100.745813] Dynamic subtest vcs0: SUCCESS (0.530s)
[100.758236] Starting dynamic subtest: vcs1
[101.274908] Dynamic subtest vcs1: SUCCESS (0.528s)
[101.284046] Starting dynamic subtest: vecs0
[101.806952] Dynamic subtest vecs0: SUCCESS (0.531s)
[101.814742] Starting dynamic subtest: ccs0
[102.325906] Dynamic subtest ccs0: SUCCESS (0.518s)
[102.328831] Subtest idle: SUCCESS (3.182s)
[102.404488] [004/131] (917s left) gem_mmap_gtt (cpuset-medium-copy)
[102.461277] Subtest cpuset-medium-copy: SKIP (0.000s)
[102.511136] [005/131] (917s left) kms_hdr (static-toggle)
[102.616813] Starting subtest: static-toggle
[102.623596] Subtest static-toggle: SKIP (0.000s)
[102.681564] [006/131] (917s left) syncobj_wait (single-wait-for-submit-submitted)
[102.733754] Starting subtest: single-wait-for-submit-submitted
[102.839507] Subtest single-wait-for-submit-submitted: SUCCESS (0.105s)
[102.906987] [007/131] (917s left) gem_exec_schedule (smoketest)
[103.092597] Starting subtest: smoketest
[103.094960] Starting dynamic subtest: rcs0
[108.637593] Dynamic subtest rcs0: SUCCESS (5.491s)
[108.649739] Starting dynamic subtest: bcs0
[114.138982] Dynamic subtest bcs0: SUCCESS (5.497s)
[114.147310] Starting dynamic subtest: vcs0
[119.658228] Dynamic subtest vcs0: SUCCESS (5.520s)
[119.670418] Starting dynamic subtest: vcs1
[125.153523] Dynamic subtest vcs1: SUCCESS (5.498s)
[125.166493] Starting dynamic subtest: vecs0
[130.657507] Dynamic subtest vecs0: SUCCESS (5.504s)
[130.669238] Starting dynamic subtest: ccs0
[136.166537] Dynamic subtest ccs0: SUCCESS (5.509s)
[136.168510] Subtest smoketest: SUCCESS (33.019s)
[136.300599] [008/131] (884s left) gem_eio (suspend)
[136.555546] Starting subtest: suspend
[148.471041] Subtest suspend: SUCCESS (11.913s)
[149.586059] [009/131] (871s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[149.691526] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[149.696271] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[149.794377] [010/131] (871s left) perf (per-context-mode-unprivileged)
[149.897646] Starting subtest: per-context-mode-unprivileged
[149.902078] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[149.931274] [011/131] (871s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[150.048111] Starting subtest: cursorB-vs-flipB-legacy
[150.052541] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[150.173997] [012/131] (871s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[155.243331] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[155.247834] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[156.083725] [013/131] (865s left) kms_pipe_crc_basic (suspend-read-crc)
[156.186089] Starting subtest: suspend-read-crc
[156.188511] Starting dynamic subtest: pipe-A-eDP-1
[174.333043] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.545s)
[174.341354] Starting dynamic subtest: pipe-B-eDP-1
[192.032141] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.838s)
[192.039802] Starting dynamic subtest: pipe-C-eDP-1
[209.966826] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.690s)
[209.975455] Starting dynamic subtest: pipe-D-eDP-1
[227.992185] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.770s)
[227.994696] Subtest suspend-read-crc: SUCCESS (10.847s)
[228.787540] [014/131] (853s left) gem_mmap (pf-nonblock)
[228.842957] Subtest pf-nonblock: SKIP (0.000s)
[228.899289] [015/131] (853s left) api_intel_bb (intel-bb-blit-y)
[228.960624] Starting subtest: intel-bb-blit-y
[232.544473] Subtest intel-bb-blit-y: SUCCESS (3.689s)
[232.679380] [016/131] (849s left) gem_ctx_param (invalid-get-no-zeromap)
[232.740100] Starting subtest: invalid-get-no-zeromap
[232.742621] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[232.827397] [017/131] (849s left) kms_flip (flip-vs-modeset-vs-hang)
[232.943298] Starting subtest: flip-vs-modeset-vs-hang
[232.945381] Starting dynamic subtest: A-eDP1
[283.663384] Dynamic subtest A-eDP1: SUCCESS (50.874s)
[283.673362] Starting dynamic subtest: D-eDP1
[332.691955] Dynamic subtest D-eDP1: SUCCESS (49.031s)
[332.695056] Subtest flip-vs-modeset-vs-hang: SUCCESS (99.909s)
[333.056173] [018/131] (749s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[333.107739] Starting subtest: multi-wait-for-submit-submitted-signaled
[333.110611] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[333.206709] [019/131] (749s left) syncobj_timeline (wait-zero-handles)
[333.253581] Starting subtest: wait-zero-handles
[333.256463] Subtest wait-zero-handles: SUCCESS (0.000s)
[333.335153] [020/131] (749s left) kms_flip (bo-too-big)
[333.437223] Starting subtest: bo-too-big
[333.439421] Starting dynamic subtest: A-eDP1
[335.157295] Dynamic subtest A-eDP1: SUCCESS (1.719s)
[335.166975] Starting dynamic subtest: B-eDP1
[336.886758] Dynamic subtest B-eDP1: SUCCESS (1.728s)
[336.897231] Starting dynamic subtest: C-eDP1
[338.613723] Dynamic subtest C-eDP1: SUCCESS (1.725s)
[338.622393] Starting dynamic subtest: D-eDP1
[340.346636] Dynamic subtest D-eDP1: SUCCESS (1.732s)
[340.349667] Subtest bo-too-big: SUCCESS (6.911s)
[341.598553] [021/131] (741s left) i915_query (query-topology-matches-eu-total)
[341.657713] Starting subtest: query-topology-matches-eu-total
[341.660629] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[341.737946] [022/131] (740s left) gem_readwrite (read-write)
[341.797546] Subtest read-write: SKIP (0.000s)
[341.849566] [023/131] (740s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[341.956471] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[342.779580] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.825s)
[343.011486] [024/131] (739s left) gem_render_copy (yf-tiled-to-vebox-linear)
[343.077627] Starting subtest: yf-tiled-to-vebox-linear
[343.083373] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[343.250965] [025/131] (739s left) api_intel_bb (object-noreloc-purge-cache-simple)
[343.312262] Starting subtest: object-noreloc-purge-cache-simple
[343.317467] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[343.439820] [026/131] (739s left) perf (missing-sample-flags)
[343.524720] Starting subtest: missing-sample-flags
[343.527633] Subtest missing-sample-flags: SUCCESS (0.000s)
[343.567123] [027/131] (739s left) kms_vblank (wait-busy-hang)
[343.670726] Starting subtest: wait-busy-hang
[343.673510] Starting dynamic subtest: pipe-A-eDP-1
[369.960550] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.287s)
[369.975029] Starting dynamic subtest: pipe-D-eDP-1
[394.929712] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.969s)
[394.932262] Subtest wait-busy-hang: SUCCESS (51.259s)
[395.738505] [028/131] (687s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render)
[395.842666] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render
[400.668479] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.830s)
[400.818428] [029/131] (682s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[400.930449] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[400.935751] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[401.035006] [030/131] (681s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[401.141501] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[401.146476] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[402.224003] [031/131] (680s left) gen7_exec_parse (basic-offset)
[402.294230] Subtest basic-offset: SKIP (0.000s)
[402.354566] [032/131] (680s left) gem_softpin (evict-single-offset)
[402.412767] Starting subtest: evict-single-offset
[429.180203] Subtest evict-single-offset: SUCCESS (26.771s)
[429.261323] [033/131] (653s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[429.373561] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[429.379193] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[429.563298] [034/131] (653s left) gem_mmap_gtt (basic-read)
[429.614740] Subtest basic-read: SKIP (0.000s)
[429.664242] [035/131] (653s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[429.765262] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[429.767597] Starting dynamic subtest: pipe-A-default-mode
[432.435744] Dynamic subtest pipe-A-default-mode: SUCCESS (2.668s)
[432.439024] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.669s)
[433.296858] [036/131] (649s left) kms_flip (2x-absolute-wf_vblank)
[433.401742] Starting subtest: 2x-absolute-wf_vblank
[433.414498] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[433.488054] [037/131] (649s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[433.590389] Starting subtest: psr-rgb101010-draw-mmap-wc
[438.470735] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (4.885s)
[438.566826] [038/131] (644s left) kms_psr (pr-primary-render)
[438.658428] Starting subtest: pr-primary-render
[438.663471] Subtest pr-primary-render: SKIP (0.000s)
[438.723346] [039/131] (644s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[438.791654] Subtest tiled-to-tiled: SKIP (0.000s)
[438.861560] [040/131] (644s left) kms_content_protection (lic-type-0)
[439.040167] Starting subtest: lic-type-0
[442.386718] Subtest lic-type-0: SKIP (3.345s)
[443.545381] [041/131] (639s left) i915_pm_rpm (reg-read-ioctl)
[445.305900] Starting subtest: reg-read-ioctl
[447.699337] Subtest reg-read-ioctl: SUCCESS (2.393s)
[448.060084] [042/131] (635s left) kms_psr (pr-cursor-plane-onoff)
[448.166664] Starting subtest: pr-cursor-plane-onoff
[448.172306] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[448.231821] [043/131] (635s left) syncobj_timeline (multi-wait-all-signaled)
[448.293603] Starting subtest: multi-wait-all-signaled
[448.296732] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[448.402973] [044/131] (635s left) gem_exec_balancer (parallel-keep-submit-fence)
[448.651715] Starting subtest: parallel-keep-submit-fence
[449.878171] Subtest parallel-keep-submit-fence: SUCCESS (1.227s)
[449.985014] [045/131] (633s left) gem_exec_fence (syncobj-unused-fence)
[450.444663] Starting subtest: syncobj-unused-fence
[450.451242] Subtest syncobj-unused-fence: SUCCESS (0.004s)
[452.251394] [FACT gem_exec_fence (syncobj-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[452.255034] [046/131] (631s left) i915_pm_freq_mult (media-freq)
[452.322523] Starting subtest: media-freq
[452.325605] Starting dynamic subtest: gt0
[452.330704] Dynamic subtest gt0: SKIP (0.000s)
[452.340203] Starting dynamic subtest: gt1
[452.344791] Dynamic subtest gt1: SKIP (0.000s)
[452.349890] Subtest media-freq: SKIP (0.001s)
[452.421499] [047/131] (631s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[452.533593] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[452.538745] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[452.637521] [048/131] (630s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[452.749510] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[456.083910] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (3.338s)
[456.192806] [049/131] (627s left) sw_sync (sync_busy_fork)
[456.235227] Starting subtest: sync_busy_fork
[457.250651] Subtest sync_busy_fork: SUCCESS (1.014s)
[457.314976] [050/131] (626s left) kms_properties (invalid-properties-legacy)
[457.413532] Starting subtest: invalid-properties-legacy
[457.516609] Subtest invalid-properties-legacy: SUCCESS (0.105s)
[457.584393] [051/131] (626s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[457.681631] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[457.684539] Starting dynamic subtest: pipe-A-eDP-1
[458.529570] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.838s)
[458.539096] Starting dynamic subtest: pipe-B-eDP-1
[460.082704] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.551s)
[460.092298] Starting dynamic subtest: pipe-C-eDP-1
[461.592018] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.504s)
[461.601329] Starting dynamic subtest: pipe-D-eDP-1
[463.015585] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.426s)
[463.018407] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.323s)
[464.194951] [052/131] (619s left) kms_addfb_basic (clobberred-modifier)
[464.261504] Starting subtest: clobberred-modifier
[464.266702] Subtest clobberred-modifier: SKIP (0.000s)
[464.409216] [053/131] (619s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[464.521485] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[464.532592] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[464.652816] [054/131] (619s left) kms_psr (psr2-sprite-plane-move)
[464.753165] Starting subtest: psr2-sprite-plane-move
[464.755438] Starting dynamic subtest: eDP-1
[466.512264] Dynamic subtest eDP-1: SUCCESS (1.757s)
[466.515277] Subtest psr2-sprite-plane-move: SUCCESS (1.758s)
[467.339720] [055/131] (616s left) kms_cursor_crc (cursor-random-64x64)
[467.508648] Starting subtest: cursor-random-64x64
[467.511588] Starting dynamic subtest: pipe-A-eDP-1
[471.352763] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.839s)
[471.382417] Starting dynamic subtest: pipe-D-eDP-1
[475.886731] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.509s)
[475.889537] Subtest cursor-random-64x64: SUCCESS (8.375s)
[476.696128] [056/131] (607s left) gem_create (create-ext-placement-sanity-check)
[476.875567] Starting subtest: create-ext-placement-sanity-check
[476.878529] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[476.983245] [057/131] (606s left) perf_pmu (busy-idle)
[477.025407] Starting subtest: busy-idle
[477.028113] Starting dynamic subtest: rcs0
[477.706913] Dynamic subtest rcs0: SUCCESS (0.684s)
[477.717758] Starting dynamic subtest: bcs0
[478.384216] Dynamic subtest bcs0: SUCCESS (0.675s)
[478.397003] Starting dynamic subtest: vcs0
[480.187233] Dynamic subtest vcs0: SUCCESS (1.799s)
[480.199038] Starting dynamic subtest: vcs1
[482.170172] Dynamic subtest vcs1: SUCCESS (1.980s)
[482.182713] Starting dynamic subtest: vecs0
[484.218583] Dynamic subtest vecs0: SUCCESS (2.046s)
[484.229943] Starting dynamic subtest: ccs0
[484.885034] Dynamic subtest ccs0: SUCCESS (0.665s)
[484.888101] Subtest busy-idle: SUCCESS (7.861s)
[484.989355] [058/131] (598s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[485.101569] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[490.499733] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.402s)
[490.623319] [059/131] (593s left) kms_vblank (crtc-id)
[490.720143] Starting subtest: crtc-id
[490.722851] Starting dynamic subtest: pipe-A-eDP-1
[491.163935] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.440s)
[491.179306] Starting dynamic subtest: pipe-D-eDP-1
[492.244472] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.075s)
[492.246778] Subtest crtc-id: SUCCESS (1.522s)
[493.067903] [060/131] (590s left) gen9_exec_parse (shadow-peek)
[493.121592] Subtest shadow-peek: SKIP (0.000s)
[493.192520] [061/131] (590s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[493.296142] Starting subtest: sprite-rotation-90-pos-100-0
[493.301440] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[493.364589] [062/131] (590s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[493.473418] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[493.478514] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[493.557531] [063/131] (590s left) gem_userptr_blits (unsync-unmap)
[493.626059] Subtest unsync-unmap: SKIP (0.000s)
[493.736641] [064/131] (590s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[493.836178] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[493.841339] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[493.946337] [065/131] (590s left) gem_pwrite (basic-self)
[494.005641] Subtest basic-self: SKIP (0.000s)
[494.060187] [066/131] (590s left) gem_exec_whisper (basic-fds-all)
[494.117248] Starting subtest: basic-fds-all
[504.154266] Subtest basic-fds-all: SUCCESS (10.031s)
[504.335249] [067/131] (579s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[504.457355] Starting subtest: yf-tiled-64bpp-rotate-0
[504.462572] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[504.560964] [068/131] (579s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[504.671407] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[505.748391] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.078s)
[505.900786] [069/131] (578s left) kms_addfb_basic (addfb25-4-tiled)
[505.996573] Starting subtest: addfb25-4-tiled
[505.998781] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[506.097129] [070/131] (578s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[506.183972] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[506.186091] Starting dynamic subtest: pipe-A-eDP-1
[506.240575] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[506.251529] Starting dynamic subtest: pipe-B-eDP-1
[506.310064] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[506.318760] Starting dynamic subtest: pipe-C-eDP-1
[506.377405] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[506.387719] Starting dynamic subtest: pipe-D-eDP-1
[506.446959] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[506.451342] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.008s)
[508.313532] [071/131] (576s left) gem_exec_schedule (preempt-queue-chain)
[508.391617] Subtest preempt-queue-chain: SKIP (0.000s)
[508.678154] [072/131] (575s left) gem_exec_params (larger-than-life-batch)
[508.738358] Starting subtest: larger-than-life-batch
[512.176721] Subtest larger-than-life-batch: SUCCESS (3.438s)
[513.260859] [073/131] (571s left) gem_ctx_shared (exec-single-timeline)
[513.367462] Starting subtest: exec-single-timeline
[513.370283] Starting dynamic subtest: rcs0
[514.454790] Dynamic subtest rcs0: SUCCESS (1.085s)
[514.467612] Starting dynamic subtest: bcs0
[515.543430] Dynamic subtest bcs0: SUCCESS (1.086s)
[515.553300] Starting dynamic subtest: vcs0
[516.631497] Dynamic subtest vcs0: SUCCESS (1.085s)
[516.643314] Starting dynamic subtest: vcs1
[517.719052] Dynamic subtest vcs1: SUCCESS (1.085s)
[517.730451] Starting dynamic subtest: vecs0
[518.807791] Dynamic subtest vecs0: SUCCESS (1.086s)
[518.820192] Starting dynamic subtest: ccs0
[519.895919] Dynamic subtest ccs0: SUCCESS (1.086s)
[519.899217] Subtest exec-single-timeline: SUCCESS (6.528s)
[520.085954] [074/131] (564s left) kms_cursor_edge_walk (64x64-left-edge)
[520.197382] Starting subtest: 64x64-left-edge
[520.199962] Starting dynamic subtest: pipe-A-eDP-1
[523.678578] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.479s)
[523.687859] Starting dynamic subtest: pipe-D-eDP-1
[526.783739] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.104s)
[526.786644] Subtest 64x64-left-edge: SUCCESS (6.586s)
[527.586191] [075/131] (556s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[532.639985] Starting subtest: psr2-cursor-plane-move-continuous-sf
[532.642703] Starting dynamic subtest: pipe-A-eDP-1
[543.732707] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.090s)
[543.748716] Starting dynamic subtest: pipe-B-eDP-1
[554.785444] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.055s)
[554.788033] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (22.149s)
[555.645240] [076/131] (528s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[555.743503] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[555.746615] Starting dynamic subtest: pipe-A-default-mode
[556.109717] Dynamic subtest pipe-A-default-mode: SKIP (0.363s)
[556.115649] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.364s)
[556.874091] [077/131] (527s left) kms_dp_link_training (uhbr-mst)
[556.970341] Starting subtest: uhbr-mst
[556.980586] Subtest uhbr-mst: SKIP (0.000s)
[557.039425] [078/131] (527s left) kms_big_fb (linear-32bpp-rotate-0)
[557.147593] Starting subtest: linear-32bpp-rotate-0
[559.012494] Subtest linear-32bpp-rotate-0: SUCCESS (1.866s)
[559.189966] [079/131] (525s left) gem_lmem_swapping (parallel-random-verify)
[559.237486] Subtest parallel-random-verify: SKIP (0.000s)
[559.276417] [080/131] (525s left) kms_color (ctm-negative)
[559.377755] Starting subtest: ctm-negative
[559.379737] Starting dynamic subtest: pipe-A-eDP-1
[560.068436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.686s)
[560.078154] Starting dynamic subtest: pipe-B-eDP-1
[561.453542] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[561.464884] Starting dynamic subtest: pipe-C-eDP-1
[562.763997] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.313s)
[562.773094] Starting dynamic subtest: pipe-D-eDP-1
[564.084526] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.316s)
[564.087363] Subtest ctm-negative: SUCCESS (4.704s)
[564.889026] [081/131] (519s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[564.945218] Starting subtest: invalid-bsd2-flag-on-vebox
[564.947692] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[565.033405] [082/131] (519s left) kms_sequence (queue-idle)
[565.123368] Starting subtest: queue-idle
[565.125774] Starting dynamic subtest: pipe-A-eDP-1
[566.926272] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.801s)
[566.936013] Starting dynamic subtest: pipe-B-eDP-1
[569.335287] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.409s)
[569.345475] Starting dynamic subtest: pipe-C-eDP-1
[571.758566] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.420s)
[571.769170] Starting dynamic subtest: pipe-D-eDP-1
[574.174054] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.415s)
[574.176659] Subtest queue-idle: SUCCESS (9.049s)
[574.978775] [083/131] (509s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[575.077614] Starting subtest: bad-aux-stride-yf-tiled-ccs
[575.080637] Starting dynamic subtest: pipe-A-eDP-1
[575.107121] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[575.117580] Starting dynamic subtest: pipe-B-eDP-1
[575.142879] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[575.151991] Starting dynamic subtest: pipe-C-eDP-1
[575.178193] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[575.188653] Starting dynamic subtest: pipe-D-eDP-1
[575.214516] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[575.219879] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[577.233421] [084/131] (507s left) kms_lease (master-vs-lease)
[577.332303] Starting subtest: master-vs-lease
[577.334773] Subtest master-vs-lease: SUCCESS (0.002s)
[577.415705] [085/131] (507s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[577.517521] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[577.522774] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[577.628795] [086/131] (507s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[577.735739] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[582.061984] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (4.329s)
[582.196727] [087/131] (502s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[582.309702] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[582.315040] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[582.408113] [088/131] (502s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[582.521479] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[582.526297] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[583.620859] [089/131] (501s left) fbdev (write)
[583.665368] Starting subtest: write
[584.032761] Subtest write: SUCCESS (0.366s)
[584.098010] [090/131] (501s left) kms_plane_scaling (intel-max-src-size)
[584.191460] Starting subtest: intel-max-src-size
[584.199579] Subtest intel-max-src-size: SKIP (0.000s)
[584.279955] [091/131] (500s left) prime_mmap_coherency (read)
[584.346194] Starting subtest: read
[589.722470] Subtest read: SUCCESS (5.376s)
[589.852332] [092/131] (495s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[590.250754] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[591.019009] [093/131] (494s left) gem_mmap_gtt (big-bo-tiledy)
[591.076779] Subtest big-bo-tiledY: SKIP (0.000s)
[591.140231] [094/131] (494s left) syncobj_wait (invalid-reset-bad-pad)
[591.207732] Starting subtest: invalid-reset-bad-pad
[591.210581] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[591.289241] [095/131] (494s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[591.398957] Starting subtest: 4-tiled-64bpp-rotate-180
[593.620505] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (2.222s)
[593.913786] [096/131] (491s left) gem_exec_fence (submit3)
[594.088987] Starting subtest: submit3
[594.094479] Subtest submit3: SKIP (0.000s)
[596.326603] [097/131] (489s left) gem_ctx_param (get-priority-new-ctx)
[596.385458] Starting subtest: get-priority-new-ctx
[596.388761] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[596.497097] [098/131] (488s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[596.607026] Starting subtest: primary-yf-tiled-reflect-x-270
[596.612396] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[596.679499] [099/131] (488s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[596.768054] Starting subtest: plane-downscale-factor-0-75-with-rotation
[596.770409] Starting dynamic subtest: pipe-A
[597.119341] Dynamic subtest pipe-A: SUCCESS (0.351s)
[597.131381] Starting dynamic subtest: pipe-B
[598.523482] Dynamic subtest pipe-B: SUCCESS (1.404s)
[598.533640] Starting dynamic subtest: pipe-C
[599.991887] Dynamic subtest pipe-C: SUCCESS (1.468s)
[600.003630] Starting dynamic subtest: pipe-D
[601.400499] Dynamic subtest pipe-D: SUCCESS (1.409s)
[601.403249] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.633s)
[602.549117] [100/131] (482s left) kms_psr (fbc-pr-sprite-plane-move)
[602.648910] Starting subtest: fbc-pr-sprite-plane-move
[602.654742] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[602.718643] [101/131] (482s left) kms_vblank (query-forked-busy-hang)
[602.812173] Starting subtest: query-forked-busy-hang
[602.814664] Starting dynamic subtest: pipe-A-eDP-1
[628.906877] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.094s)
[628.921095] Starting dynamic subtest: pipe-D-eDP-1
[643.952650] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.046s)
[643.955398] Subtest query-forked-busy-hang: SUCCESS (41.145s)
[644.784214] [102/131] (440s left) gem_reg_read (timestamp-monotonic)
[644.842379] Starting subtest: timestamp-monotonic
[648.914399] Subtest timestamp-monotonic: SUCCESS (4.074s)
[648.985906] [103/131] (436s left) kms_flip (2x-nonexisting-fb)
[649.085580] Starting subtest: 2x-nonexisting-fb
[649.096699] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[649.175404] [104/131] (436s left) gem_exec_reloc (basic-write-read)
[649.244306] Subtest basic-write-read: SKIP (0.000s)
[649.308085] [105/131] (436s left) kms_atomic (atomic-plane-damage)
[649.403055] Starting subtest: atomic-plane-damage
[649.462378] Starting dynamic subtest: pipe-A-eDP-1
[650.089333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.630s)
[650.091979] Subtest atomic-plane-damage: SUCCESS (0.686s)
[650.907067] [106/131] (434s left) vgem_basic (bad-handle)
[650.954439] Starting subtest: bad-handle
[650.956651] Subtest bad-handle: SUCCESS (0.000s)
[651.008154] [107/131] (434s left) gem_softpin (evict-active)
[651.071548] Starting subtest: evict-active
[651.078012] Subtest evict-active: SUCCESS (0.006s)
[651.209187] [108/131] (434s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[651.307721] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[651.313197] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[651.392051] [109/131] (434s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[651.491529] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[651.494243] Starting dynamic subtest: pipe-A
[651.513137] Dynamic subtest pipe-A: SKIP (0.014s)
[651.522616] Starting dynamic subtest: pipe-B
[651.536553] Dynamic subtest pipe-B: SKIP (0.026s)
[651.545764] Starting dynamic subtest: pipe-C
[651.564956] Dynamic subtest pipe-C: SKIP (0.026s)
[651.574001] Starting dynamic subtest: pipe-D
[651.591963] Dynamic subtest pipe-D: SKIP (0.026s)
[651.596850] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.094s)
[651.718438] [110/131] (434s left) gem_mmap_gtt (big-copy)
[651.774635] Subtest big-copy: SKIP (0.000s)
[651.831948] [111/131] (434s left) kms_pm_rpm (cursor-dpms)
[653.369227] Starting subtest: cursor-dpms
[653.375501] Subtest cursor-dpms: SKIP (0.000s)
[654.142585] [112/131] (431s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[659.180521] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[659.185709] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.003s)
[660.025541] [113/131] (425s left) syncobj_wait (single-wait-submitted)
[660.085593] Starting subtest: single-wait-submitted
[660.190811] Subtest single-wait-submitted: SUCCESS (0.106s)
[660.264176] [114/131] (425s left) sriov_basic (enable-vfs-autoprobe-off)
[660.320989] Starting subtest: enable-vfs-autoprobe-off
[660.323721] Starting dynamic subtest: numvfs-1
[660.406384] Dynamic subtest numvfs-1: FAIL (0.028s)
[660.415765] Starting dynamic subtest: numvfs-2
[660.504652] Dynamic subtest numvfs-2: FAIL (0.004s)
[660.515754] Starting dynamic subtest: numvfs-3
[660.602622] Dynamic subtest numvfs-3: FAIL (0.003s)
[660.612574] Starting dynamic subtest: numvfs-4
[660.699755] Dynamic subtest numvfs-4: FAIL (0.004s)
[660.710243] Starting dynamic subtest: numvfs-5
[660.797863] Dynamic subtest numvfs-5: FAIL (0.003s)
[660.807706] Starting dynamic subtest: numvfs-6
[660.894137] Dynamic subtest numvfs-6: FAIL (0.004s)
[660.904332] Starting dynamic subtest: numvfs-7
[660.991200] Dynamic subtest numvfs-7: FAIL (0.003s)
[661.001739] Starting dynamic subtest: numvfs-random
[661.090500] Dynamic subtest numvfs-random: FAIL (0.003s)
[661.101150] Starting dynamic subtest: numvfs-all
[661.188012] Dynamic subtest numvfs-all: FAIL (0.596s)
[661.190546] Subtest enable-vfs-autoprobe-off: FAIL (0.654s)
[661.225052] [115/131] (424s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[661.319386] Starting subtest: fbc-psr-cursor-mmap-cpu
[661.321738] Starting dynamic subtest: eDP-1
[661.326616] Dynamic subtest eDP-1: SKIP (0.000s)
[661.330986] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[661.383814] [116/131] (424s left) gem_softpin (evict-snoop)
[661.449742] Starting subtest: evict-snoop
[661.454209] Subtest evict-snoop: SKIP (0.000s)
[661.570236] [117/131] (424s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[661.625086] Starting subtest: single-wait-for-submit-unsubmitted
[661.734090] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[661.811921] [118/131] (424s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[662.023572] Starting subtest: cursor-rapid-movement-256x256
[662.026435] Starting dynamic subtest: pipe-A-eDP-1
[662.577336] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.552s)
[662.600854] Starting dynamic subtest: pipe-D-eDP-1
[663.834048] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.240s)
[663.836736] Subtest cursor-rapid-movement-256x256: SUCCESS (1.812s)
[664.678147] [119/131] (421s left) syncobj_timeline (etime-multi-wait-all-submitted)
[664.732556] Starting subtest: etime-multi-wait-all-submitted
[664.840163] Subtest etime-multi-wait-all-submitted: SUCCESS (0.108s)
[664.914385] [120/131] (421s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[665.020509] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[669.356298] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.340s)
[669.479352] [121/131] (416s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[669.579200] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[669.581524] Starting dynamic subtest: pipe-A-default-mode
[669.891839] Dynamic subtest pipe-A-default-mode: SKIP (0.309s)
[669.897455] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.310s)
[670.657879] [122/131] (415s left) kms_lease (cursor-implicit-plane)
[670.758095] Starting subtest: cursor-implicit-plane
[670.760519] Starting dynamic subtest: pipe-A-eDP-1
[671.227830] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.468s)
[671.236965] Starting dynamic subtest: pipe-B-eDP-1
[672.362903] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.132s)
[672.373662] Starting dynamic subtest: pipe-C-eDP-1
[673.477179] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[673.494269] Starting dynamic subtest: pipe-D-eDP-1
[674.603658] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[674.606549] Subtest cursor-implicit-plane: SUCCESS (3.844s)
[675.456980] [123/131] (410s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[675.561592] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[675.566585] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[675.642453] [124/131] (410s left) kms_chamelium_color (ctm-red-to-blue)
[675.747056] Subtest ctm-red-to-blue: SKIP (0.000s)
[675.795086] [125/131] (410s left) perf (rc6-disable)
[675.883885] Starting subtest: rc6-disable
[676.071148] Subtest rc6-disable: SUCCESS (0.187s)
[676.114884] [126/131] (410s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[676.176334] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[676.179021] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[676.269605] [127/131] (410s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[676.381958] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[676.387286] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[676.492831] [128/131] (410s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[676.602038] Starting subtest: fbcpsr-shrfb-scaledprimary
[682.298943] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.701s)
[682.429949] [129/131] (404s left) drm_fdinfo (most-busy-check-all)
[682.472737] Starting subtest: most-busy-check-all
[682.475145] Starting dynamic subtest: rcs0
[682.480203] Dynamic subtest rcs0: SKIP (0.004s)
[682.490560] Starting dynamic subtest: bcs0
[682.495883] Dynamic subtest bcs0: SKIP (0.000s)
[682.505526] Starting dynamic subtest: vcs0
[682.510608] Dynamic subtest vcs0: SKIP (0.000s)
[682.519891] Starting dynamic subtest: vcs1
[682.524726] Dynamic subtest vcs1: SKIP (0.000s)
[682.533986] Starting dynamic subtest: vecs0
[682.538636] Dynamic subtest vecs0: SKIP (0.000s)
[682.547346] Starting dynamic subtest: ccs0
[682.552300] Dynamic subtest ccs0: SKIP (0.000s)
[682.557480] Subtest most-busy-check-all: SKIP (0.008s)
[682.589089] [130/131] (404s left) drm_fdinfo (idle)
[682.633485] Starting subtest: idle
[682.636549] Starting dynamic subtest: rcs0
[683.253019] Dynamic subtest rcs0: SUCCESS (0.621s)
[683.265757] Starting dynamic subtest: bcs0
[683.760010] Dynamic subtest bcs0: SUCCESS (0.504s)
[683.771765] Starting dynamic subtest: vcs0
[684.267431] Dynamic subtest vcs0: SUCCESS (0.504s)
[684.280122] Starting dynamic subtest: vcs1
[684.772750] Dynamic subtest vcs1: SUCCESS (0.503s)
[684.784777] Starting dynamic subtest: vecs0
[685.277582] Dynamic subtest vecs0: SUCCESS (0.503s)
[685.291134] Starting dynamic subtest: ccs0
[685.787460] Dynamic subtest ccs0: SUCCESS (0.504s)
[685.790597] Subtest idle: SUCCESS (3.152s)
[685.859075] [131/131] (400s left) gem_exec_balancer (bonded-sync)
[685.938188] Starting subtest: bonded-sync
[685.943610] Subtest bonded-sync: SKIP (0.000s)
[686.234189] Closing watchdogs
Done.