[60.107357] Initializing watchdogs
[60.107402]   /dev/watchdog0
[60.121757] [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]
[60.126370] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.128499] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.132203] [001/133] (960s left) gem_exec_schedule (pi-common)
[60.346301] Starting subtest: pi-common
[60.348393] Starting dynamic subtest: rcs0
[60.392840] Dynamic subtest rcs0: SUCCESS (0.051s)
[60.409136] Starting dynamic subtest: bcs0
[60.422896] Dynamic subtest bcs0: SUCCESS (0.030s)
[60.432290] Starting dynamic subtest: vcs0
[60.455876] Dynamic subtest vcs0: SUCCESS (0.033s)
[60.475239] Starting dynamic subtest: vcs1
[60.486107] Dynamic subtest vcs1: SUCCESS (0.030s)
[60.493152] Starting dynamic subtest: vecs0
[60.515904] Dynamic subtest vecs0: SUCCESS (0.030s)
[60.523154] Starting dynamic subtest: ccs0
[60.546903] Dynamic subtest ccs0: SUCCESS (0.031s)
[60.549209] Subtest pi-common: SUCCESS (0.206s)
[60.785914] [FACT gem_exec_schedule (pi-common)] new: kernel.kmod_is_loaded.vgem: true
[60.791400] [002/133] (959s left) gem_exec_reloc (basic-scanout)
[60.848908] Subtest basic-scanout: SKIP (0.000s)
[60.919709] [003/133] (959s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[61.018798] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[68.130156] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.132s)
[68.230270] [004/133] (952s left) kms_chamelium_edid (dp-edid-resolution-list)
[68.613639] Subtest dp-edid-resolution-list: SKIP (0.000s)
[69.399542] [005/133] (951s left) gem_tiled_blits (normal)
[69.462434] Subtest normal: SKIP (0.000s)
[69.523876] [006/133] (951s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[69.619397] Starting subtest: plane-downscale-factor-0-75-with-rotation
[69.621821] Starting dynamic subtest: pipe-A
[69.846674] Dynamic subtest pipe-A: SUCCESS (0.230s)
[69.854712] Starting dynamic subtest: pipe-B
[71.109097] Dynamic subtest pipe-B: SUCCESS (1.263s)
[71.118290] Starting dynamic subtest: pipe-C
[72.446808] Dynamic subtest pipe-C: SUCCESS (1.338s)
[72.456220] Starting dynamic subtest: pipe-D
[73.776471] Dynamic subtest pipe-D: SUCCESS (1.330s)
[73.778811] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.164s)
[74.957814] [007/133] (945s left) i915_pm_rc6_residency (rc6-idle)
[75.021088] Starting subtest: rc6-idle
[75.045344] Starting dynamic subtest: gt0-rcs0
[99.330612] Dynamic subtest gt0-rcs0: SUCCESS (24.285s)
[99.338916] Starting dynamic subtest: gt0-bcs0
[123.676498] Dynamic subtest gt0-bcs0: SUCCESS (24.344s)
[123.686403] Starting dynamic subtest: gt0-ccs0
[147.959999] Dynamic subtest gt0-ccs0: SUCCESS (24.283s)
[147.970103] Starting dynamic subtest: gt1-vcs0
[154.133585] Dynamic subtest gt1-vcs0: SUCCESS (6.171s)
[154.143996] Starting dynamic subtest: gt1-vecs0
[160.305662] Dynamic subtest gt1-vecs0: SUCCESS (6.170s)
[160.308688] Subtest rc6-idle: SUCCESS (85.286s)
[160.380344] [008/133] (860s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[160.522025] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[160.526583] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[160.665334] [009/133] (860s left) vgem_basic (second-client)
[160.708803] Starting subtest: second-client
[160.729573] Subtest second-client: SUCCESS (0.022s)
[160.817346] [010/133] (860s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[160.883615] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[160.887948] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[161.060834] [011/133] (859s left) kms_vblank (query-idle)
[161.154907] Starting subtest: query-idle
[161.157181] Starting dynamic subtest: pipe-A-eDP-1
[162.895318] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.741s)
[162.907897] Starting dynamic subtest: pipe-D-eDP-1
[165.299379] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.403s)
[165.301315] Subtest query-idle: SUCCESS (4.147s)
[166.126197] [012/133] (854s left) syncobj_wait (multi-wait-all-submitted)
[166.178762] Starting subtest: multi-wait-all-submitted
[166.282882] Subtest multi-wait-all-submitted: SUCCESS (0.103s)
[166.358687] [013/133] (854s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[166.443362] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[166.445905] Starting dynamic subtest: pipe-A-eDP-1
[166.466456] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[166.476247] Starting dynamic subtest: pipe-B-eDP-1
[166.496310] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[166.504292] Starting dynamic subtest: pipe-C-eDP-1
[166.524220] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[166.532250] Starting dynamic subtest: pipe-D-eDP-1
[166.552404] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[166.554680] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.090s)
[168.695595] [014/133] (852s left) gem_lmem_swapping (parallel-random)
[168.747825] Subtest parallel-random: SKIP (0.000s)
[168.794768] [015/133] (852s left) kms_getfb (getfb-handle-valid)
[168.877028] Starting subtest: getfb-handle-valid
[168.879383] Subtest getfb-handle-valid: SUCCESS (0.000s)
[168.981996] [016/133] (852s left) kms_psr (pr-sprite-mmap-gtt)
[169.087663] Starting subtest: pr-sprite-mmap-gtt
[169.092235] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[169.157298] [017/133] (852s left) gen3_mixed_blits
[169.276306] [018/133] (852s left) i915_query (query-topology-matches-eu-total)
[169.327821] Starting subtest: query-topology-matches-eu-total
[169.329752] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[169.428452] [019/133] (851s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[169.518990] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[169.523247] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[169.591230] [020/133] (851s left) kms_pm_rpm (modeset-stress-extra-wait)
[171.130406] Starting subtest: modeset-stress-extra-wait
[225.465603] Subtest modeset-stress-extra-wait: SUCCESS (54.336s)
[225.555823] [021/133] (795s left) sw_sync (timeline_closed_signaled)
[225.595396] Starting subtest: timeline_closed_signaled
[225.597731] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[225.658827] [022/133] (795s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[225.712061] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[225.771428] [023/133] (795s left) perf_pmu (init-sema)
[225.813089] Starting subtest: init-sema
[225.815493] Starting dynamic subtest: rcs0
[225.817843] Dynamic subtest rcs0: SUCCESS (0.001s)
[225.827306] Starting dynamic subtest: bcs0
[225.829464] Dynamic subtest bcs0: SUCCESS (0.000s)
[225.837204] Starting dynamic subtest: vcs0
[225.839399] Dynamic subtest vcs0: SUCCESS (0.000s)
[225.848481] Starting dynamic subtest: vcs1
[225.850714] Dynamic subtest vcs1: SUCCESS (0.000s)
[225.858357] Starting dynamic subtest: vecs0
[225.860593] Dynamic subtest vecs0: SUCCESS (0.001s)
[225.867469] Starting dynamic subtest: ccs0
[225.869793] Dynamic subtest ccs0: SUCCESS (0.000s)
[225.871970] Subtest init-sema: SUCCESS (0.005s)
[225.919678] [024/133] (795s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[226.015863] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[226.020259] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[227.149651] [025/133] (794s left) kms_psr (psr2-sprite-render)
[227.237758] Starting subtest: psr2-sprite-render
[227.240206] Starting dynamic subtest: eDP-1
[228.935504] Dynamic subtest eDP-1: SUCCESS (1.698s)
[228.938020] Subtest psr2-sprite-render: SUCCESS (1.699s)
[229.810483] [026/133] (791s left) kms_invalid_mode (bad-htotal)
[229.905188] Starting subtest: bad-htotal
[229.907448] Starting dynamic subtest: pipe-A-eDP-1
[230.186885] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[230.196511] Starting dynamic subtest: pipe-B-eDP-1
[230.198479] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[230.205682] Starting dynamic subtest: pipe-C-eDP-1
[230.208084] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[230.215513] Starting dynamic subtest: pipe-D-eDP-1
[230.217510] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[230.219479] Subtest bad-htotal: SUCCESS (0.288s)
[231.038703] [027/133] (790s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[231.142345] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[231.146747] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[231.235941] [028/133] (790s left) kms_dp_aux_dev (basic)
[231.288745] Starting subtest: basic
[231.510343] Subtest basic: SUCCESS (0.221s)
[231.597019] [029/133] (790s left) gem_ctx_shared (exec-shared-gtt)
[231.660092] Starting subtest: exec-shared-gtt
[231.662545] Starting dynamic subtest: rcs0
[231.671377] Dynamic subtest rcs0: SUCCESS (0.011s)
[231.683066] Starting dynamic subtest: bcs0
[231.685418] Dynamic subtest bcs0: SUCCESS (0.006s)
[231.693882] Starting dynamic subtest: vcs0
[231.695825] Dynamic subtest vcs0: SUCCESS (0.007s)
[231.704068] Starting dynamic subtest: vcs1
[231.706350] Dynamic subtest vcs1: SUCCESS (0.007s)
[231.714745] Starting dynamic subtest: vecs0
[231.716998] Dynamic subtest vecs0: SUCCESS (0.005s)
[231.724598] Starting dynamic subtest: ccs0
[231.726822] Dynamic subtest ccs0: SUCCESS (0.004s)
[231.729063] Subtest exec-shared-gtt: SUCCESS (0.041s)
[231.889668] [030/133] (789s left) perf_pmu (busy-accuracy-50)
[231.931054] Starting subtest: busy-accuracy-50
[231.933746] Starting dynamic subtest: rcs0
[233.465772] Dynamic subtest rcs0: SUCCESS (1.533s)
[233.474709] Starting dynamic subtest: bcs0
[235.009068] Dynamic subtest bcs0: SUCCESS (1.542s)
[235.020078] Starting dynamic subtest: vcs0
[236.553490] Dynamic subtest vcs0: SUCCESS (1.543s)
[236.563100] Starting dynamic subtest: vcs1
[238.095963] Dynamic subtest vcs1: SUCCESS (1.541s)
[238.106704] Starting dynamic subtest: vecs0
[239.635802] Dynamic subtest vecs0: SUCCESS (1.539s)
[239.645655] Starting dynamic subtest: ccs0
[241.183843] Dynamic subtest ccs0: SUCCESS (1.546s)
[241.186293] Subtest busy-accuracy-50: SUCCESS (9.253s)
[241.298155] [031/133] (780s left) gem_exec_schedule (u-independent)
[241.369830] Starting subtest: u-independent
[241.371932] Starting dynamic subtest: rcs0
[242.841377] Dynamic subtest rcs0: SUCCESS (1.479s)
[242.852978] Starting dynamic subtest: vcs0
[244.900695] Dynamic subtest vcs0: SUCCESS (2.058s)
[244.911990] Starting dynamic subtest: vcs1
[246.934355] Dynamic subtest vcs1: SUCCESS (2.032s)
[246.946139] Starting dynamic subtest: vecs0
[248.924750] Dynamic subtest vecs0: SUCCESS (1.989s)
[248.936448] Starting dynamic subtest: ccs0
[250.900793] Dynamic subtest ccs0: SUCCESS (1.976s)
[250.903545] Subtest u-independent: SUCCESS (9.541s)
[251.202050] [032/133] (770s left) sw_sync (sync_busy)
[251.246076] Starting subtest: sync_busy
[251.248470] Subtest sync_busy: SUCCESS (0.002s)
[251.297480] [033/133] (770s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[251.384980] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[251.389218] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[251.471514] [034/133] (770s left) gem_exec_capture (capture)
[251.542125] Starting subtest: capture
[251.544452] Starting dynamic subtest: rcs0-smem
[252.364567] Dynamic subtest rcs0-smem: SUCCESS (0.822s)
[252.373874] Starting dynamic subtest: bcs0-smem
[253.686210] Dynamic subtest bcs0-smem: SUCCESS (1.322s)
[253.693837] Starting dynamic subtest: vcs0-smem
[254.513579] Dynamic subtest vcs0-smem: SUCCESS (0.826s)
[254.521607] Starting dynamic subtest: vcs1-smem
[255.828312] Dynamic subtest vcs1-smem: SUCCESS (1.317s)
[255.839226] Starting dynamic subtest: vecs0-smem
[256.710043] Dynamic subtest vecs0-smem: FAIL (0.823s)
[256.720115] Starting dynamic subtest: ccs0-smem
[257.975724] Dynamic subtest ccs0-smem: SUCCESS (1.322s)
[257.978065] Subtest capture: FAIL (6.434s)
[258.085612] [035/133] (763s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[258.182253] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[258.184525] Starting dynamic subtest: pipe-A-default-mode
[260.870517] Dynamic subtest pipe-A-default-mode: SUCCESS (2.687s)
[260.872915] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.688s)
[261.745095] [036/133] (760s left) syncobj_timeline (invalid-transfer-illegal-handle)
[261.805045] Starting subtest: invalid-transfer-illegal-handle
[261.807537] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[261.923315] [037/133] (760s left) gem_ctx_param (get-priority-new-ctx)
[261.966615] Starting subtest: get-priority-new-ctx
[261.968947] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[262.058260] [038/133] (760s left) kms_pm_rpm (cursor-dpms)
[264.088093] Starting subtest: cursor-dpms
[264.093241] Subtest cursor-dpms: SKIP (0.000s)
[264.468838] [039/133] (757s left) perf_pmu (invalid-init)
[264.506729] Starting subtest: invalid-init
[264.509136] Subtest invalid-init: SUCCESS (0.000s)
[264.598206] [040/133] (757s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[264.685099] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[264.687374] Starting dynamic subtest: A-eDP1
[268.294368] Dynamic subtest A-eDP1: SUCCESS (3.606s)
[268.303857] Starting dynamic subtest: B-eDP1
[271.516654] Dynamic subtest B-eDP1: SUCCESS (3.221s)
[271.526071] Starting dynamic subtest: C-eDP1
[274.613684] Dynamic subtest C-eDP1: SUCCESS (3.096s)
[274.623524] Starting dynamic subtest: D-eDP1
[277.726264] Dynamic subtest D-eDP1: SUCCESS (3.112s)
[277.728230] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.042s)
[278.524355] [041/133] (743s left) gem_ctx_persistence (many-contexts)
[278.578581] Starting subtest: many-contexts
[323.781045] Subtest many-contexts: SUCCESS (45.202s)
[323.864366] [042/133] (698s left) kms_dsc (dsc-with-output-formats-with-bpc)
[323.971840] Starting subtest: dsc-with-output-formats-with-bpc
[324.030105] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[324.070159] [043/133] (698s left) fbdev (unaligned-read)
[324.116693] Starting subtest: unaligned-read
[324.130055] Subtest unaligned-read: SUCCESS (0.014s)
[324.190522] [044/133] (698s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[324.280142] Starting subtest: bad-rotation-90-yf-tiled-ccs
[324.282424] Starting dynamic subtest: pipe-A-eDP-1
[324.304248] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[324.314644] Starting dynamic subtest: pipe-B-eDP-1
[324.335075] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[324.345495] Starting dynamic subtest: pipe-C-eDP-1
[324.366754] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[324.376106] Starting dynamic subtest: pipe-D-eDP-1
[324.397945] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[324.402049] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.005s)
[326.487250] [045/133] (695s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[326.585142] Starting subtest: fbc-psr2-cursor-plane-onoff
[326.587413] Starting dynamic subtest: eDP-1
[326.591790] Dynamic subtest eDP-1: SKIP (0.000s)
[326.596212] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[326.669452] [046/133] (695s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[326.781298] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[326.785372] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[326.881829] [047/133] (695s left) kms_big_fb (linear-32bpp-rotate-180)
[326.997283] Starting subtest: linear-32bpp-rotate-180
[328.566969] Subtest linear-32bpp-rotate-180: SUCCESS (1.569s)
[328.745694] [048/133] (693s left) kms_plane_lowres (tiling-4)
[328.849991] Starting subtest: tiling-4
[328.852227] Starting dynamic subtest: pipe-A-eDP-1
[328.856524] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[329.149796] Starting dynamic subtest: pipe-B-eDP-1
[329.154203] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[329.162541] Starting dynamic subtest: pipe-C-eDP-1
[329.166966] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[329.176217] Starting dynamic subtest: pipe-D-eDP-1
[329.184790] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[329.190871] Subtest tiling-4: SKIP (0.302s)
[330.021239] [049/133] (692s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[330.152342] Starting subtest: basic-busy-flip-before-cursor-atomic
[330.156758] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[330.318511] [050/133] (692s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[330.375904] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[330.378135] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[330.455061] [051/133] (692s left) gem_exec_reloc (basic-write-gtt)
[330.514505] Subtest basic-write-gtt: SKIP (0.000s)
[330.589103] [052/133] (692s left) kms_cursor_crc (cursor-offscreen-128x42)
[330.827093] Starting subtest: cursor-offscreen-128x42
[330.875332] Subtest cursor-offscreen-128x42: SKIP (0.044s)
[331.089338] [053/133] (691s left) api_intel_allocator (standalone)
[331.161873] Starting subtest: standalone
[331.177934] Subtest standalone: SUCCESS (0.018s)
[331.302097] [054/133] (691s left) kms_atomic (test-only)
[331.403264] Starting subtest: test-only
[331.452624] Starting dynamic subtest: pipe-A-eDP-1
[333.996072] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.543s)
[333.997908] Subtest test-only: SUCCESS (2.592s)
[334.791985] [055/133] (688s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[334.885563] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[341.496510] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.613s)
[341.611807] [056/133] (681s left) gem_exec_reloc (basic-write-read-active)
[341.662834] Subtest basic-write-read-active: SKIP (0.000s)
[341.723380] [057/133] (681s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[341.815311] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[348.474797] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.662s)
[348.594553] [058/133] (674s left) perf (invalid-open-flags)
[348.739074] Starting subtest: invalid-open-flags
[348.741179] Subtest invalid-open-flags: SUCCESS (0.000s)
[348.782304] [059/133] (674s left) gem_exec_balancer (full)
[348.836948] Starting subtest: full
[353.744909] Subtest full: SUCCESS (4.908s)
[354.080791] [060/133] (668s left) kms_sequence (queue-busy)
[354.182898] Starting subtest: queue-busy
[354.185210] Starting dynamic subtest: pipe-A-eDP-1
[356.117877] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[356.128785] Starting dynamic subtest: pipe-B-eDP-1
[358.702311] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.587s)
[358.712369] Starting dynamic subtest: pipe-C-eDP-1
[361.303313] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.598s)
[361.312154] Starting dynamic subtest: pipe-D-eDP-1
[363.841430] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.536s)
[363.843614] Subtest queue-busy: SUCCESS (9.659s)
[364.638739] [061/133] (658s left) kms_force_connector_basic (prune-stale-modes)
[364.714927] Starting subtest: prune-stale-modes
[365.156766] Subtest prune-stale-modes: SUCCESS (0.444s)
[365.294263] [062/133] (657s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[365.389462] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[372.547407] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.160s)
[372.670268] [063/133] (650s left) sw_sync (sync_multi_consumer)
[372.715501] Starting subtest: sync_multi_consumer
[373.137312] Subtest sync_multi_consumer: SUCCESS (0.425s)
[373.198351] [064/133] (649s left) vgem_basic (bad-pad)
[373.233114] Starting subtest: bad-pad
[373.235059] Subtest bad-pad: SUCCESS (0.000s)
[373.288459] [065/133] (649s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[373.380960] Starting subtest: primary-y-tiled-reflect-x-0
[373.440541] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[373.545982] [066/133] (649s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[373.634206] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[374.247197] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.614s)
[374.412077] [067/133] (648s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[374.504556] Starting subtest: psr-indfb-scaledprimary
[380.168531] Subtest psr-indfb-scaledprimary: SUCCESS (5.668s)
[380.284841] [068/133] (643s left) api_intel_bb (render-ccs)
[380.344887] Starting subtest: render-ccs
[380.726245] Subtest render-ccs: SUCCESS (0.382s)
[380.814930] [069/133] (642s left) kms_cursor_edge_walk (256x256-right-edge)
[380.909159] Starting subtest: 256x256-right-edge
[380.911498] Starting dynamic subtest: pipe-A-eDP-1
[384.397244] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.489s)
[384.405771] Starting dynamic subtest: pipe-D-eDP-1
[387.575088] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.175s)
[387.577301] Subtest 256x256-right-edge: SUCCESS (6.667s)
[388.373889] [070/133] (635s left) gen9_exec_parse (bb-start-out)
[388.439209] Subtest bb-start-out: SKIP (0.000s)
[388.507131] [071/133] (634s left) kms_psr (fbc-psr2-sprite-render)
[388.588367] Starting subtest: fbc-psr2-sprite-render
[388.590730] Starting dynamic subtest: eDP-1
[388.595227] Dynamic subtest eDP-1: SKIP (0.000s)
[388.599670] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[388.665469] [072/133] (634s left) gem_exec_flush (basic-wb-prw-default)
[388.722838] Starting subtest: basic-wb-prw-default
[394.179378] Subtest basic-wb-prw-default: SUCCESS (5.457s)
[394.364887] [073/133] (629s left) gem_ctx_persistence (engines-queued)
[394.433665] Starting subtest: engines-queued
[394.436022] Starting dynamic subtest: rcs0
[394.490213] Dynamic subtest rcs0: SUCCESS (0.057s)
[394.503961] Starting dynamic subtest: bcs0
[394.566181] Dynamic subtest bcs0: SUCCESS (0.073s)
[394.576127] Starting dynamic subtest: vcs0
[395.893193] Dynamic subtest vcs0: SUCCESS (1.324s)
[395.904485] Starting dynamic subtest: vcs1
[397.877484] Dynamic subtest vcs1: SUCCESS (1.981s)
[397.889790] Starting dynamic subtest: vecs0
[399.860536] Dynamic subtest vecs0: SUCCESS (1.980s)
[399.873089] Starting dynamic subtest: ccs0
[400.047117] Dynamic subtest ccs0: SUCCESS (0.185s)
[400.049487] Subtest engines-queued: SUCCESS (5.613s)
[400.152082] [074/133] (623s left) syncobj_eventfd (binary-wait)
[400.202408] Starting subtest: binary-wait
[400.210896] Subtest binary-wait: SUCCESS (0.010s)
[400.312445] [075/133] (623s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[400.407928] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[400.434723] Starting dynamic subtest: pipe-A-plane-0
[400.747591] Dynamic subtest pipe-A-plane-0: SUCCESS (0.310s)
[400.761465] Starting dynamic subtest: pipe-A-plane-3
[400.978435] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[402.121141] Starting dynamic subtest: pipe-B-plane-0
[402.437051] Dynamic subtest pipe-B-plane-0: SUCCESS (0.321s)
[402.445308] Starting dynamic subtest: pipe-B-plane-3
[402.669794] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[402.991899] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.585s)
[403.874722] [076/133] (619s left) gem_userptr_blits (unsync-unmap-cycles)
[403.946531] Subtest unsync-unmap-cycles: SKIP (0.000s)
[404.094477] [077/133] (619s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[404.190720] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[404.194668] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[404.261494] [078/133] (619s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[404.399735] Starting subtest: 2x-flip-vs-cursor-atomic
[404.404057] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[404.540810] [079/133] (619s left) kms_content_protection (atomic-dpms)
[404.703243] Starting subtest: atomic-dpms
[404.707499] Subtest atomic-dpms: SKIP (0.000s)
[404.815247] [080/133] (619s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[404.922717] Starting subtest: y-tiled-64bpp-rotate-180
[404.926363] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[405.044498] [081/133] (618s left) vgem_basic (create)
[405.082061] Starting subtest: create
[405.083898] Subtest create: SUCCESS (0.000s)
[405.137286] [082/133] (618s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[405.226084] Starting subtest: yf-tiled-16bpp-rotate-180
[405.229672] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[405.311597] [083/133] (618s left) gem_mmap_wc (bad-object)
[405.359481] Subtest bad-object: SKIP (0.000s)
[405.415055] [084/133] (618s left) kms_chamelium_hpd (dp-hpd-fast)
[405.799725] Subtest dp-hpd-fast: SKIP (0.000s)
[406.641221] [085/133] (617s left) i915_drm_fdinfo (most-busy-idle-check-all)
[406.687482] Starting subtest: most-busy-idle-check-all
[406.689893] Starting dynamic subtest: rcs0
[406.694966] Dynamic subtest rcs0: SKIP (0.003s)
[406.703876] Starting dynamic subtest: bcs0
[406.707817] Dynamic subtest bcs0: SKIP (0.000s)
[406.716044] Starting dynamic subtest: vcs0
[406.720258] Dynamic subtest vcs0: SKIP (0.000s)
[406.728459] Starting dynamic subtest: vcs1
[406.732701] Dynamic subtest vcs1: SKIP (0.000s)
[406.740628] Starting dynamic subtest: vecs0
[406.744779] Dynamic subtest vecs0: SKIP (0.000s)
[406.752975] Starting dynamic subtest: ccs0
[406.757192] Dynamic subtest ccs0: SKIP (0.000s)
[406.761462] Subtest most-busy-idle-check-all: SKIP (0.008s)
[406.799279] [086/133] (617s left) kms_plane_alpha_blend (coverage-7efc)
[406.942276] Starting subtest: coverage-7efc
[407.264856] Starting dynamic subtest: pipe-A-eDP-1
[407.866284] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.601s)
[407.894237] Starting dynamic subtest: pipe-D-eDP-1
[409.153727] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.266s)
[409.156061] Subtest coverage-7efc: SUCCESS (2.214s)
[409.959650] [087/133] (614s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[410.048421] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[410.050804] Starting dynamic subtest: pipe-A-eDP-1
[410.060131] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[410.069773] Starting dynamic subtest: pipe-B-eDP-1
[410.079087] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[410.087371] Starting dynamic subtest: pipe-C-eDP-1
[410.096292] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[410.104473] Starting dynamic subtest: pipe-D-eDP-1
[410.113213] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[410.117491] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[412.260536] [088/133] (611s left) sriov_basic (bind-unbind-vf)
[412.315936] Starting subtest: bind-unbind-vf
[412.318125] Starting dynamic subtest: vf-1
[412.388884] Dynamic subtest vf-1: FAIL (0.005s)
[412.397839] Starting dynamic subtest: vf-2
[412.466695] Dynamic subtest vf-2: FAIL (0.003s)
[412.475286] Starting dynamic subtest: vf-3
[412.544777] Dynamic subtest vf-3: FAIL (0.003s)
[412.553532] Starting dynamic subtest: vf-4
[412.622050] Dynamic subtest vf-4: FAIL (0.003s)
[412.630634] Starting dynamic subtest: vf-5
[412.699958] Dynamic subtest vf-5: FAIL (0.004s)
[412.709124] Starting dynamic subtest: vf-6
[412.778603] Dynamic subtest vf-6: FAIL (0.003s)
[412.787450] Starting dynamic subtest: vf-7
[412.855971] Dynamic subtest vf-7: FAIL (0.004s)
[412.864566] Starting dynamic subtest: vf-random
[412.933389] Dynamic subtest vf-random: FAIL (0.003s)
[412.942662] Starting dynamic subtest: vf-last
[413.011744] Dynamic subtest vf-last: FAIL (0.481s)
[413.013619] Subtest bind-unbind-vf: FAIL (0.518s)
[413.051525] [089/133] (611s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[413.598300] Starting subtest: syncobj-stationary-timeline-chain-engines
[413.710467] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.112s)
[415.041570] [090/133] (609s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[415.139055] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[421.804033] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.667s)
[421.938170] [091/133] (602s left) kms_big_fb (x-tiled-addfb)
[422.043504] Starting subtest: x-tiled-addfb
[422.045757] Subtest x-tiled-addfb: SUCCESS (0.000s)
[422.149900] [092/133] (602s left) kms_color_pipeline (plane-lut1d-lut1d)
[422.245463] Starting subtest: plane-lut1d-lut1d
[422.368892] Starting dynamic subtest: pipe-A-plane-0
[422.513233] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[422.522725] Starting dynamic subtest: pipe-A-plane-1
[422.713256] Dynamic subtest pipe-A-plane-1: SUCCESS (0.199s)
[422.722195] Starting dynamic subtest: pipe-A-plane-2
[422.846232] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[424.029931] Starting dynamic subtest: pipe-B-plane-0
[424.190085] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[424.199315] Starting dynamic subtest: pipe-B-plane-1
[424.347519] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[424.358622] Starting dynamic subtest: pipe-B-plane-2
[424.556409] Dynamic subtest pipe-B-plane-2: SUCCESS (0.210s)
[425.711656] Starting dynamic subtest: pipe-C-plane-0
[425.849077] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[425.857848] Starting dynamic subtest: pipe-C-plane-1
[425.982577] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[425.991610] Starting dynamic subtest: pipe-C-plane-2
[426.140561] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[427.324526] Starting dynamic subtest: pipe-D-plane-0
[427.462909] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[427.471736] Starting dynamic subtest: pipe-D-plane-1
[427.617072] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[427.626750] Starting dynamic subtest: pipe-D-plane-2
[427.761532] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[428.047693] Subtest plane-lut1d-lut1d: SUCCESS (5.802s)
[428.868418] [093/133] (595s left) kms_flip (wf_vblank-ts-check)
[428.979147] Starting subtest: wf_vblank-ts-check
[428.981370] Starting dynamic subtest: A-eDP1
[437.884522] Dynamic subtest A-eDP1: SUCCESS (8.907s)
[437.894190] Starting dynamic subtest: B-eDP1
[446.762163] Dynamic subtest B-eDP1: SUCCESS (8.876s)
[446.772754] Starting dynamic subtest: C-eDP1
[455.661719] Dynamic subtest C-eDP1: SUCCESS (8.897s)
[455.673729] Starting dynamic subtest: D-eDP1
[464.559259] Dynamic subtest D-eDP1: SUCCESS (8.897s)
[464.564585] Subtest wf_vblank-ts-check: SUCCESS (35.587s)
[465.743952] [094/133] (558s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[465.840595] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[465.844978] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[465.938631] [095/133] (558s left) dmabuf_sync_file (export-multiwait)
[465.988643] Starting subtest: export-multiwait
[465.990995] Subtest export-multiwait: SUCCESS (0.000s)
[466.044815] [096/133] (558s left) kms_cdclk (mode-transition-all-outputs)
[466.135273] Starting subtest: mode-transition-all-outputs
[466.432730] Subtest mode-transition-all-outputs: SKIP (0.295s)
[467.258908] [097/133] (557s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[467.350808] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[467.354864] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[467.449458] [098/133] (557s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[467.720724] Starting subtest: cursor-rapid-movement-512x512
[467.725248] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[467.886030] [099/133] (556s left) i915_drm_fdinfo (virtual-idle)
[467.941072] Starting subtest: virtual-idle
[471.006388] Subtest virtual-idle: SUCCESS (3.063s)
[471.083554] [100/133] (553s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[471.141111] Starting subtest: wait-all-for-submit-delayed-submit
[471.244658] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[471.324424] [101/133] (553s left) i915_suspend (basic-s2idle-without-i915)
[471.370811] Starting subtest: basic-s2idle-without-i915
[492.524261] Subtest basic-s2idle-without-i915: SUCCESS (5.738s)
[492.649026] [102/133] (532s left) gem_exec_suspend (basic-s4-devices)
[492.732117] Starting subtest: basic-S4-devices
[492.734274] Starting dynamic subtest: smem
[504.336873] Dynamic subtest smem: SUCCESS (11.764s)
[504.339868] Subtest basic-S4-devices: SUCCESS (11.767s)
[509.539364] [103/133] (520s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[509.655495] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[509.660153] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[509.786849] [104/133] (520s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[509.896206] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[509.900697] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[510.003734] [105/133] (519s left) gem_exec_params (sol-reset-invalid)
[510.062957] Starting subtest: sol-reset-invalid
[510.065301] Subtest sol-reset-invalid: SUCCESS (0.002s)
[511.383042] [106/133] (518s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[511.500652] Starting subtest: short-flip-before-cursor-toggle
[512.431499] Subtest short-flip-before-cursor-toggle: SUCCESS (0.929s)
[513.269074] [107/133] (516s left) kms_lease (lessee-list)
[513.358032] Starting subtest: lessee-list
[513.360389] Starting dynamic subtest: pipe-A-eDP-1
[513.362888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[513.374024] Starting dynamic subtest: pipe-B-eDP-1
[513.376178] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[513.384696] Starting dynamic subtest: pipe-C-eDP-1
[513.386780] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[513.395346] Starting dynamic subtest: pipe-D-eDP-1
[513.397446] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[513.399564] Subtest lessee-list: SUCCESS (0.009s)
[513.463064] [108/133] (516s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[513.575370] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[513.586890] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[513.704044] [109/133] (516s left) gem_wait (write-busy)
[513.762291] Starting subtest: write-busy
[513.764670] Starting dynamic subtest: all-engines
[514.345803] Dynamic subtest all-engines: SUCCESS (0.584s)
[514.367431] Starting dynamic subtest: rcs0
[514.898652] Dynamic subtest rcs0: SUCCESS (0.552s)
[514.915044] Starting dynamic subtest: bcs0
[515.448670] Dynamic subtest bcs0: SUCCESS (0.550s)
[515.464781] Starting dynamic subtest: vcs0
[517.328821] Dynamic subtest vcs0: SUCCESS (1.879s)
[517.345948] Starting dynamic subtest: vcs1
[519.247450] Dynamic subtest vcs1: SUCCESS (1.917s)
[519.262887] Starting dynamic subtest: vecs0
[521.294996] Dynamic subtest vecs0: SUCCESS (2.046s)
[521.312020] Starting dynamic subtest: ccs0
[521.845178] Dynamic subtest ccs0: SUCCESS (0.550s)
[521.847912] Subtest write-busy: SUCCESS (8.090s)
[522.061772] [110/133] (508s left) prime_mmap (test_correct)
[522.119456] Starting subtest: test_correct
[522.121648] Starting dynamic subtest: test_correct-smem
[522.153158] Dynamic subtest test_correct-smem: SUCCESS (0.035s)
[522.155403] Subtest test_correct: SUCCESS (0.036s)
[522.257271] [111/133] (507s left) gem_create (create-size-update)
[522.310550] Starting subtest: create-size-update
[522.312881] Subtest create-size-update: SUCCESS (0.000s)
[522.535110] [112/133] (507s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[522.638399] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[529.380394] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.751s)
[529.530948] [113/133] (500s left) prime_self_import (basic-llseek-size)
[529.570667] Starting subtest: basic-llseek-size
[529.587933] Subtest basic-llseek-size: SUCCESS (0.015s)
[529.671511] [114/133] (500s left) syncobj_wait (wait-all-complex)
[529.720153] Starting subtest: wait-all-complex
[529.937127] Subtest wait-all-complex: SUCCESS (0.217s)
[530.013311] [115/133] (500s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[530.114507] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[530.118407] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[530.207491] [116/133] (500s left) syncobj_timeline (wait-all-interrupted)
[530.273140] Starting subtest: wait-all-interrupted
[530.373865] Subtest wait-all-interrupted: SUCCESS (0.101s)
[530.450184] [117/133] (499s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[530.538866] Starting subtest: fbc-pr-cursor-mmap-cpu
[530.543189] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[530.625932] [118/133] (499s left) kms_scaling_modes (scaling-mode-center)
[530.721129] Starting subtest: scaling-mode-center
[530.723354] Starting dynamic subtest: pipe-A-eDP-1
[530.813297] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s)
[530.823057] Starting dynamic subtest: pipe-B-eDP-1
[532.010365] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.196s)
[532.022173] Starting dynamic subtest: pipe-C-eDP-1
[533.133671] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.123s)
[533.145877] Starting dynamic subtest: pipe-D-eDP-1
[534.264836] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[534.266991] Subtest scaling-mode-center: SUCCESS (3.549s)
[535.422298] [119/133] (495s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[535.514488] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[535.518804] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[535.614550] [120/133] (494s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[535.708495] Starting subtest: fbc-rgb565-draw-blt
[539.878028] Subtest fbc-rgb565-draw-blt: SUCCESS (4.175s)
[541.024422] [121/133] (489s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[541.122870] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[548.823954] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.710s)
[548.947741] [122/133] (481s left) gem_softpin (evict-prime-sanity-check)
[549.003366] Starting subtest: evict-prime-sanity-check
[549.005714] Starting dynamic subtest: rcs0
[550.138447] Dynamic subtest rcs0: SUCCESS (1.135s)
[550.148248] Starting dynamic subtest: bcs0
[551.248141] Dynamic subtest bcs0: SUCCESS (1.109s)
[551.257404] Starting dynamic subtest: vcs0
[553.288533] Dynamic subtest vcs0: SUCCESS (2.039s)
[553.300721] Starting dynamic subtest: vcs1
[555.318130] Dynamic subtest vcs1: SUCCESS (2.028s)
[555.331832] Starting dynamic subtest: vecs0
[557.321498] Dynamic subtest vecs0: SUCCESS (2.002s)
[557.333681] Starting dynamic subtest: ccs0
[558.443972] Dynamic subtest ccs0: SUCCESS (1.123s)
[558.454817] Starting dynamic subtest: all
[559.568987] Dynamic subtest all: SUCCESS (1.123s)
[559.571667] Subtest evict-prime-sanity-check: SUCCESS (10.573s)
[559.649239] [123/133] (471s left) gem_pxp (reject-modify-context-protection-off-1)
[559.771124] Starting subtest: reject-modify-context-protection-off-1
[559.773994] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[559.880063] [124/133] (470s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[559.968964] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[559.971266] Starting dynamic subtest: pipe-A
[559.988771] Dynamic subtest pipe-A: SKIP (0.016s)
[559.998615] Starting dynamic subtest: pipe-B
[560.019232] Dynamic subtest pipe-B: SKIP (0.029s)
[560.028731] Starting dynamic subtest: pipe-C
[560.048820] Dynamic subtest pipe-C: SKIP (0.029s)
[560.058571] Starting dynamic subtest: pipe-D
[560.076229] Dynamic subtest pipe-D: SKIP (0.029s)
[560.079862] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.105s)
[560.220540] [125/133] (470s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[560.336637] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[560.338890] Starting dynamic subtest: pipe-A-eDP-1
[560.434253] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[560.445395] Starting dynamic subtest: pipe-B-eDP-1
[561.609170] Dynamic subtest pipe-B-eDP-1: SKIP (1.173s)
[561.613593] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.275s)
[562.810875] [126/133] (468s left) kms_addfb_basic (addfb25-4-tiled)
[562.911307] Starting subtest: addfb25-4-tiled
[562.913383] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[563.002858] [127/133] (467s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[563.097137] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[567.509740] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.419s)
[568.717001] [128/133] (462s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[568.776841] Starting subtest: etime-single-wait-available-unsubmitted
[568.883876] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.105s)
[568.963593] [129/133] (462s left) gem_workarounds (reset-context)
[569.026434] Starting subtest: reset-context
[569.206462] Subtest reset-context: SUCCESS (0.180s)
[569.351141] [130/133] (461s left) gem_exec_whisper (basic-forked)
[569.413510] Starting subtest: basic-forked
[577.787449] Subtest basic-forked: SUCCESS (8.379s)
[577.973982] [131/133] (453s left) kms_flip (bo-too-big-interruptible)
[578.078986] Starting subtest: bo-too-big-interruptible
[578.081313] Starting dynamic subtest: A-eDP1
[579.331271] Dynamic subtest A-eDP1: SUCCESS (1.252s)
[579.343929] Starting dynamic subtest: B-eDP1
[580.564562] Dynamic subtest B-eDP1: SUCCESS (1.230s)
[580.576967] Starting dynamic subtest: C-eDP1
[581.800037] Dynamic subtest C-eDP1: SUCCESS (1.233s)
[581.813508] Starting dynamic subtest: D-eDP1
[583.037175] Dynamic subtest D-eDP1: SUCCESS (1.237s)
[583.039609] Subtest bo-too-big-interruptible: SUCCESS (4.963s)
[584.244770] [132/133] (446s left) gem_mmap_offset (clear)
[584.296134] Starting subtest: clear
[584.298458] Starting dynamic subtest: smem0
[608.378353] Dynamic subtest smem0: SUCCESS (24.092s)
[608.380385] Subtest clear: SUCCESS (24.093s)
[608.506811] [133/133] (422s left) kms_flip (basic-plain-flip)
[608.659284] Starting subtest: basic-plain-flip
[608.661572] Starting dynamic subtest: A-eDP1
[610.341255] Dynamic subtest A-eDP1: SUCCESS (1.685s)
[610.355901] Starting dynamic subtest: B-eDP1
[611.984272] Dynamic subtest B-eDP1: SUCCESS (1.642s)
[611.995467] Starting dynamic subtest: C-eDP1
[613.625415] Dynamic subtest C-eDP1: SUCCESS (1.640s)
[613.636711] Starting dynamic subtest: D-eDP1
[615.265106] Dynamic subtest D-eDP1: SUCCESS (1.639s)
[615.270673] Subtest basic-plain-flip: SUCCESS (6.616s)
[616.431658] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_schedule
results: parsing output: 1/ for test: gem_exec_reloc
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_edid
results: parsing output: 4/ for test: gem_tiled_blits
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: i915_pm_rc6_residency
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: vgem_basic
results: parsing output: 9/ for test: gem_render_copy
results: parsing output: 10/ for test: kms_vblank
results: parsing output: 11/ for test: syncobj_wait
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: gem_lmem_swapping
results: parsing output: 14/ for test: kms_getfb
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: gen3_mixed_blits
results: parsing output: 17/ for test: i915_query
results: parsing output: 18/ for test: kms_flip_scaled_crc
results: parsing output: 19/ for test: kms_pm_rpm
results: parsing output: 20/ for test: sw_sync
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: perf_pmu
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_invalid_mode
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_dp_aux_dev
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: perf_pmu
results: parsing output: 30/ for test: gem_exec_schedule
results: parsing output: 31/ for test: sw_sync
results: parsing output: 32/ for test: kms_flip_scaled_crc
results: parsing output: 33/ for test: gem_exec_capture
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: syncobj_timeline
results: parsing output: 36/ for test: gem_ctx_param
results: parsing output: 37/ for test: kms_pm_rpm
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_dsc
results: parsing output: 42/ for test: fbdev
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_plane_lowres
results: parsing output: 48/ for test: kms_cursor_legacy
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: gem_exec_reloc
results: parsing output: 51/ for test: kms_cursor_crc
results: parsing output: 52/ for test: api_intel_allocator
results: parsing output: 53/ for test: kms_atomic
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: perf
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: kms_sequence
results: parsing output: 60/ for test: kms_force_connector_basic
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: sw_sync
results: parsing output: 63/ for test: vgem_basic
results: parsing output: 64/ for test: kms_rotation_crc
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: api_intel_bb
results: parsing output: 68/ for test: kms_cursor_edge_walk
results: parsing output: 69/ for test: gen9_exec_parse
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: gem_exec_flush
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: syncobj_eventfd
results: parsing output: 74/ for test: kms_plane
results: parsing output: 75/ for test: gem_userptr_blits
results: parsing output: 76/ for test: kms_psr2_sf
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: vgem_basic
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: gem_mmap_wc
results: parsing output: 83/ for test: kms_chamelium_hpd
results: parsing output: 84/ for test: i915_drm_fdinfo
results: parsing output: 85/ for test: kms_plane_alpha_blend
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: sriov_basic
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_color_pipeline
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: dmabuf_sync_file
results: parsing output: 95/ for test: kms_cdclk
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: i915_drm_fdinfo
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: i915_suspend
results: parsing output: 101/ for test: gem_exec_suspend
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_exec_params
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: kms_lease
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_wait
results: parsing output: 109/ for test: prime_mmap
results: parsing output: 110/ for test: gem_create
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: prime_self_import
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_scaling_modes
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_softpin
results: parsing output: 122/ for test: gem_pxp
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: kms_addfb_basic
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: syncobj_timeline
results: parsing output: 128/ for test: gem_workarounds
results: parsing output: 129/ for test: gem_exec_whisper
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: gem_mmap_offset
results: parsing output: 132/ for test: kms_flip
Done.