[62.638666] Initializing watchdogs
[62.638716]   /dev/watchdog0
[62.654199] [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]
[62.658825] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.661517] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.664928] [001/133] (960s left) gem_exec_schedule (pi-common)
[62.881949] Starting subtest: pi-common
[62.884170] Starting dynamic subtest: rcs0
[62.931068] Dynamic subtest rcs0: SUCCESS (0.051s)
[62.939181] Starting dynamic subtest: bcs0
[62.961726] Dynamic subtest bcs0: SUCCESS (0.031s)
[62.969824] Starting dynamic subtest: vcs0
[62.993289] Dynamic subtest vcs0: SUCCESS (0.031s)
[63.000735] Starting dynamic subtest: vcs1
[63.024996] Dynamic subtest vcs1: SUCCESS (0.031s)
[63.035825] Starting dynamic subtest: vecs0
[63.053523] Dynamic subtest vecs0: SUCCESS (0.028s)
[63.060607] Starting dynamic subtest: ccs0
[63.085091] Dynamic subtest ccs0: SUCCESS (0.031s)
[63.087101] Subtest pi-common: SUCCESS (0.205s)
[63.304090] [FACT gem_exec_schedule (pi-common)] new: kernel.kmod_is_loaded.vgem: true
[63.307246] [002/133] (959s left) gem_exec_reloc (basic-scanout)
[63.373381] Subtest basic-scanout: SKIP (0.000s)
[63.437593] [003/133] (959s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[63.540892] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[70.589103] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.052s)
[70.723771] [004/133] (952s left) kms_chamelium_edid (dp-edid-resolution-list)
[71.109532] Subtest dp-edid-resolution-list: SKIP (0.000s)
[71.964928] [005/133] (951s left) gem_tiled_blits (normal)
[72.035488] Subtest normal: SKIP (0.000s)
[72.106237] [006/133] (951s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[72.202843] Starting subtest: plane-downscale-factor-0-75-with-rotation
[72.205062] Starting dynamic subtest: pipe-A
[72.442028] Dynamic subtest pipe-A: SUCCESS (0.241s)
[72.450273] Starting dynamic subtest: pipe-B
[73.769134] Dynamic subtest pipe-B: SUCCESS (1.326s)
[73.778256] Starting dynamic subtest: pipe-C
[75.125054] Dynamic subtest pipe-C: SUCCESS (1.355s)
[75.133773] Starting dynamic subtest: pipe-D
[76.465858] Dynamic subtest pipe-D: SUCCESS (1.341s)
[76.468202] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.266s)
[77.859594] [007/133] (945s left) i915_pm_rc6_residency (rc6-idle)
[77.923688] Starting subtest: rc6-idle
[77.958467] Starting dynamic subtest: gt0-rcs0
[102.252960] Dynamic subtest gt0-rcs0: SUCCESS (24.293s)
[102.261280] Starting dynamic subtest: gt0-bcs0
[126.534945] Dynamic subtest gt0-bcs0: SUCCESS (24.281s)
[126.543060] Starting dynamic subtest: gt0-ccs0
[150.816736] Dynamic subtest gt0-ccs0: SUCCESS (24.280s)
[150.825677] Starting dynamic subtest: gt1-vcs0
[156.990472] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[157.004586] Starting dynamic subtest: gt1-vecs0
[163.161997] Dynamic subtest gt1-vecs0: SUCCESS (6.170s)
[163.164624] Subtest rc6-idle: SUCCESS (85.237s)
[163.241180] [008/133] (860s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[163.371113] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[163.375768] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[163.513623] [009/133] (859s left) vgem_basic (second-client)
[163.556963] Starting subtest: second-client
[163.579379] Subtest second-client: SUCCESS (0.022s)
[163.652117] [010/133] (859s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[163.713776] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[163.718259] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[163.909185] [011/133] (859s left) kms_vblank (query-idle)
[163.995136] Starting subtest: query-idle
[163.997068] Starting dynamic subtest: pipe-A-eDP-1
[165.747121] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.753s)
[165.761202] Starting dynamic subtest: pipe-D-eDP-1
[168.205280] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.456s)
[168.207675] Subtest query-idle: SUCCESS (4.212s)
[169.028137] [012/133] (854s left) syncobj_wait (multi-wait-all-submitted)
[169.074661] Starting subtest: multi-wait-all-submitted
[169.178853] Subtest multi-wait-all-submitted: SUCCESS (0.103s)
[169.253565] [013/133] (854s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[169.348366] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[169.350783] Starting dynamic subtest: pipe-A-eDP-1
[169.372115] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[169.381625] Starting dynamic subtest: pipe-B-eDP-1
[169.402514] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.023s)
[169.410719] Starting dynamic subtest: pipe-C-eDP-1
[169.431632] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[169.439670] Starting dynamic subtest: pipe-D-eDP-1
[169.460189] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.025s)
[169.462468] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.094s)
[171.615038] [014/133] (852s left) gem_lmem_swapping (parallel-random)
[171.662542] Subtest parallel-random: SKIP (0.000s)
[171.704769] [015/133] (852s left) kms_getfb (getfb-handle-valid)
[171.794707] Starting subtest: getfb-handle-valid
[171.796911] Subtest getfb-handle-valid: SUCCESS (0.000s)
[171.909256] [016/133] (851s left) kms_psr (pr-sprite-mmap-gtt)
[172.001949] Starting subtest: pr-sprite-mmap-gtt
[172.006577] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[172.073926] [017/133] (851s left) gen3_mixed_blits
[172.205830] [018/133] (851s left) i915_query (query-topology-matches-eu-total)
[172.257072] Starting subtest: query-topology-matches-eu-total
[172.259466] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[172.354226] [019/133] (851s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[172.458143] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[172.462529] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[172.531096] [020/133] (851s left) kms_pm_rpm (modeset-stress-extra-wait)
[174.075745] Starting subtest: modeset-stress-extra-wait
[228.396810] Subtest modeset-stress-extra-wait: SUCCESS (54.321s)
[228.483764] [021/133] (795s left) sw_sync (timeline_closed_signaled)
[228.523361] Starting subtest: timeline_closed_signaled
[228.525559] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[228.573430] [022/133] (795s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[228.633122] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[228.695727] [023/133] (795s left) perf_pmu (init-sema)
[228.738314] Starting subtest: init-sema
[228.740628] Starting dynamic subtest: rcs0
[228.742958] Dynamic subtest rcs0: SUCCESS (0.002s)
[228.751630] Starting dynamic subtest: bcs0
[228.753537] Dynamic subtest bcs0: SUCCESS (0.000s)
[228.761094] Starting dynamic subtest: vcs0
[228.763073] Dynamic subtest vcs0: SUCCESS (0.001s)
[228.770398] Starting dynamic subtest: vcs1
[228.772675] Dynamic subtest vcs1: SUCCESS (0.001s)
[228.780666] Starting dynamic subtest: vecs0
[228.782845] Dynamic subtest vecs0: SUCCESS (0.001s)
[228.790971] Starting dynamic subtest: ccs0
[228.793306] Dynamic subtest ccs0: SUCCESS (0.000s)
[228.795464] Subtest init-sema: SUCCESS (0.006s)
[228.861238] [024/133] (795s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[228.957489] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[228.961915] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[230.071090] [025/133] (794s left) kms_psr (psr2-sprite-render)
[230.162298] Starting subtest: psr2-sprite-render
[230.164647] Starting dynamic subtest: eDP-1
[231.866576] Dynamic subtest eDP-1: SUCCESS (1.705s)
[231.868830] Subtest psr2-sprite-render: SUCCESS (1.706s)
[232.736898] [026/133] (791s left) kms_invalid_mode (bad-htotal)
[232.838612] Starting subtest: bad-htotal
[232.840954] Starting dynamic subtest: pipe-A-eDP-1
[233.143354] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.307s)
[233.153865] Starting dynamic subtest: pipe-B-eDP-1
[233.155688] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[233.162748] Starting dynamic subtest: pipe-C-eDP-1
[233.165036] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[233.172399] Starting dynamic subtest: pipe-D-eDP-1
[233.174249] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[233.176061] Subtest bad-htotal: SUCCESS (0.312s)
[233.979483] [027/133] (790s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[234.075537] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[234.080064] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[234.183402] [028/133] (790s left) kms_dp_aux_dev (basic)
[234.235624] Starting subtest: basic
[234.464108] Subtest basic: SUCCESS (0.228s)
[234.534710] [029/133] (789s left) gem_ctx_shared (exec-shared-gtt)
[234.593611] Starting subtest: exec-shared-gtt
[234.595908] Starting dynamic subtest: rcs0
[234.603315] Dynamic subtest rcs0: SUCCESS (0.010s)
[234.613454] Starting dynamic subtest: bcs0
[234.615367] Dynamic subtest bcs0: SUCCESS (0.006s)
[234.623904] Starting dynamic subtest: vcs0
[234.626117] Dynamic subtest vcs0: SUCCESS (0.007s)
[234.634806] Starting dynamic subtest: vcs1
[234.637251] Dynamic subtest vcs1: SUCCESS (0.008s)
[234.645616] Starting dynamic subtest: vecs0
[234.647518] Dynamic subtest vecs0: SUCCESS (0.007s)
[234.655378] Starting dynamic subtest: ccs0
[234.657282] Dynamic subtest ccs0: SUCCESS (0.008s)
[234.659116] Subtest exec-shared-gtt: SUCCESS (0.048s)
[234.829119] [030/133] (789s left) perf_pmu (busy-accuracy-50)
[234.872348] Starting subtest: busy-accuracy-50
[234.874605] Starting dynamic subtest: rcs0
[236.408553] Dynamic subtest rcs0: SUCCESS (1.534s)
[236.419073] Starting dynamic subtest: bcs0
[237.954289] Dynamic subtest bcs0: SUCCESS (1.544s)
[237.962695] Starting dynamic subtest: vcs0
[239.500381] Dynamic subtest vcs0: SUCCESS (1.545s)
[239.509739] Starting dynamic subtest: vcs1
[241.043806] Dynamic subtest vcs1: SUCCESS (1.542s)
[241.053906] Starting dynamic subtest: vecs0
[242.585632] Dynamic subtest vecs0: SUCCESS (1.540s)
[242.593822] Starting dynamic subtest: ccs0
[244.134606] Dynamic subtest ccs0: SUCCESS (1.548s)
[244.137058] Subtest busy-accuracy-50: SUCCESS (9.261s)
[244.251550] [031/133] (780s left) gem_exec_schedule (u-independent)
[244.318504] Starting subtest: u-independent
[244.320781] Starting dynamic subtest: rcs0
[245.940512] Dynamic subtest rcs0: SUCCESS (1.594s)
[245.953587] Starting dynamic subtest: vcs0
[247.901400] Dynamic subtest vcs0: SUCCESS (1.989s)
[247.913064] Starting dynamic subtest: vcs1
[249.953513] Dynamic subtest vcs1: SUCCESS (2.051s)
[249.964863] Starting dynamic subtest: vecs0
[251.936096] Dynamic subtest vecs0: SUCCESS (1.981s)
[251.947830] Starting dynamic subtest: ccs0
[253.910111] Dynamic subtest ccs0: SUCCESS (1.973s)
[253.913040] Subtest u-independent: SUCCESS (9.594s)
[254.212267] [032/133] (770s left) sw_sync (sync_busy)
[254.256705] Starting subtest: sync_busy
[254.259278] Subtest sync_busy: SUCCESS (0.002s)
[254.318957] [033/133] (770s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[254.415052] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[254.419296] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[254.489979] [034/133] (770s left) gem_exec_capture (capture)
[254.545044] Starting subtest: capture
[254.547759] Starting dynamic subtest: rcs0-smem
[255.374214] Dynamic subtest rcs0-smem: SUCCESS (0.828s)
[255.385159] Starting dynamic subtest: bcs0-smem
[256.696283] Dynamic subtest bcs0-smem: SUCCESS (1.320s)
[256.706140] Starting dynamic subtest: vcs0-smem
[257.521020] Dynamic subtest vcs0-smem: SUCCESS (0.823s)
[257.531629] Starting dynamic subtest: vcs1-smem
[258.836753] Dynamic subtest vcs1-smem: SUCCESS (1.318s)
[258.847607] Starting dynamic subtest: vecs0-smem
[260.233461] Dynamic subtest vecs0-smem: FAIL (1.337s)
[260.243586] Starting dynamic subtest: ccs0-smem
[261.494093] Dynamic subtest ccs0-smem: SUCCESS (1.316s)
[261.496642] Subtest capture: FAIL (6.946s)
[261.616249] [035/133] (762s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[261.716913] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[261.719303] Starting dynamic subtest: pipe-A-default-mode
[264.408881] Dynamic subtest pipe-A-default-mode: SUCCESS (2.692s)
[264.411320] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.693s)
[265.277244] [036/133] (759s left) syncobj_timeline (invalid-transfer-illegal-handle)
[265.331252] Starting subtest: invalid-transfer-illegal-handle
[265.333766] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[265.423081] [037/133] (759s left) gem_ctx_param (get-priority-new-ctx)
[265.475985] Starting subtest: get-priority-new-ctx
[265.478327] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[265.576786] [038/133] (759s left) kms_pm_rpm (cursor-dpms)
[267.107194] Starting subtest: cursor-dpms
[267.111979] Subtest cursor-dpms: SKIP (0.000s)
[267.808258] [039/133] (756s left) perf_pmu (invalid-init)
[267.848172] Starting subtest: invalid-init
[267.850473] Subtest invalid-init: SUCCESS (0.000s)
[267.952082] [040/133] (756s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[268.046529] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[268.048933] Starting dynamic subtest: A-eDP1
[271.643066] Dynamic subtest A-eDP1: SUCCESS (3.593s)
[271.653302] Starting dynamic subtest: B-eDP1
[274.849527] Dynamic subtest B-eDP1: SUCCESS (3.205s)
[274.863995] Starting dynamic subtest: C-eDP1
[278.071184] Dynamic subtest C-eDP1: SUCCESS (3.221s)
[278.080879] Starting dynamic subtest: D-eDP1
[281.169941] Dynamic subtest D-eDP1: SUCCESS (3.100s)
[281.172821] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.125s)
[281.974793] [041/133] (742s left) gem_ctx_persistence (many-contexts)
[282.031699] Starting subtest: many-contexts
[327.222878] Subtest many-contexts: SUCCESS (45.188s)
[327.301634] [042/133] (697s left) kms_dsc (dsc-with-output-formats-with-bpc)
[327.407860] Starting subtest: dsc-with-output-formats-with-bpc
[327.464100] Subtest dsc-with-output-formats-with-bpc: SKIP (0.002s)
[327.503700] [043/133] (697s left) fbdev (unaligned-read)
[327.557259] Starting subtest: unaligned-read
[327.571335] Subtest unaligned-read: SUCCESS (0.015s)
[327.637682] [044/133] (697s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[327.725389] Starting subtest: bad-rotation-90-yf-tiled-ccs
[327.727675] Starting dynamic subtest: pipe-A-eDP-1
[327.750400] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[327.759951] Starting dynamic subtest: pipe-B-eDP-1
[327.781819] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[327.789778] Starting dynamic subtest: pipe-C-eDP-1
[327.811153] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[327.820025] Starting dynamic subtest: pipe-D-eDP-1
[327.841639] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[327.845692] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[330.030905] [045/133] (694s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[330.119240] Starting subtest: fbc-psr2-cursor-plane-onoff
[330.121072] Starting dynamic subtest: eDP-1
[330.125039] Dynamic subtest eDP-1: SKIP (0.000s)
[330.129016] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[330.196999] [046/133] (694s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[330.291483] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[330.295559] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[330.404665] [047/133] (694s left) kms_big_fb (linear-32bpp-rotate-180)
[330.498935] Starting subtest: linear-32bpp-rotate-180
[332.094088] Subtest linear-32bpp-rotate-180: SUCCESS (1.594s)
[332.224877] [048/133] (692s left) kms_plane_lowres (tiling-4)
[332.316261] Starting subtest: tiling-4
[332.318315] Starting dynamic subtest: pipe-A-eDP-1
[332.322332] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[332.619791] Starting dynamic subtest: pipe-B-eDP-1
[332.624477] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[332.632883] Starting dynamic subtest: pipe-C-eDP-1
[332.637558] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[332.646082] Starting dynamic subtest: pipe-D-eDP-1
[332.651016] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[332.655583] Subtest tiling-4: SKIP (0.306s)
[333.468385] [049/133] (691s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[333.599728] Starting subtest: basic-busy-flip-before-cursor-atomic
[333.604521] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[333.755928] [050/133] (691s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[333.821949] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[333.824298] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[333.926421] [051/133] (691s left) gem_exec_reloc (basic-write-gtt)
[333.981782] Subtest basic-write-gtt: SKIP (0.000s)
[334.057821] [052/133] (691s left) kms_cursor_crc (cursor-offscreen-128x42)
[334.295111] Starting subtest: cursor-offscreen-128x42
[334.339080] Subtest cursor-offscreen-128x42: SKIP (0.041s)
[334.557039] [053/133] (690s left) api_intel_allocator (standalone)
[334.612313] Starting subtest: standalone
[334.626729] Subtest standalone: SUCCESS (0.017s)
[334.717838] [054/133] (690s left) kms_atomic (test-only)
[334.810321] Starting subtest: test-only
[334.856107] Starting dynamic subtest: pipe-A-eDP-1
[337.361417] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.504s)
[337.363854] Subtest test-only: SUCCESS (2.552s)
[338.170093] [055/133] (687s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[338.271670] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[344.906646] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.636s)
[345.019215] [056/133] (680s left) gem_exec_reloc (basic-write-read-active)
[345.077058] Subtest basic-write-read-active: SKIP (0.000s)
[345.138992] [057/133] (680s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[345.240810] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[351.828928] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.590s)
[351.945991] [058/133] (673s left) perf (invalid-open-flags)
[352.064038] Starting subtest: invalid-open-flags
[352.066530] Subtest invalid-open-flags: SUCCESS (0.000s)
[352.127279] [059/133] (673s left) gem_exec_balancer (full)
[352.188351] Starting subtest: full
[356.776435] Subtest full: SUCCESS (4.587s)
[357.099651] [060/133] (668s left) kms_sequence (queue-busy)
[357.198807] Starting subtest: queue-busy
[357.200730] Starting dynamic subtest: pipe-A-eDP-1
[359.132888] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[359.145358] Starting dynamic subtest: pipe-B-eDP-1
[361.742123] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.608s)
[361.753052] Starting dynamic subtest: pipe-C-eDP-1
[364.331818] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.586s)
[364.341808] Starting dynamic subtest: pipe-D-eDP-1
[366.871597] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[366.874017] Subtest queue-busy: SUCCESS (9.674s)
[367.669898] [061/133] (657s left) kms_force_connector_basic (prune-stale-modes)
[367.752326] Starting subtest: prune-stale-modes
[368.197421] Subtest prune-stale-modes: SUCCESS (0.448s)
[368.343071] [062/133] (657s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[368.455814] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[375.793452] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.341s)
[375.909123] [063/133] (649s left) sw_sync (sync_multi_consumer)
[375.961825] Starting subtest: sync_multi_consumer
[376.384441] Subtest sync_multi_consumer: SUCCESS (0.423s)
[376.448686] [064/133] (649s left) vgem_basic (bad-pad)
[376.490873] Starting subtest: bad-pad
[376.493130] Subtest bad-pad: SUCCESS (0.000s)
[376.548281] [065/133] (649s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[376.646898] Starting subtest: primary-y-tiled-reflect-x-0
[376.706574] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[376.809310] [066/133] (649s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[376.912242] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[377.525357] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.615s)
[377.690490] [067/133] (648s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[377.785686] Starting subtest: psr-indfb-scaledprimary
[383.517671] Subtest psr-indfb-scaledprimary: SUCCESS (5.734s)
[383.680453] [068/133] (642s left) api_intel_bb (render-ccs)
[383.741580] Starting subtest: render-ccs
[384.098038] Subtest render-ccs: SUCCESS (0.357s)
[384.185926] [069/133] (641s left) kms_cursor_edge_walk (256x256-right-edge)
[384.280727] Starting subtest: 256x256-right-edge
[384.283798] Starting dynamic subtest: pipe-A-eDP-1
[387.773997] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.493s)
[387.785601] Starting dynamic subtest: pipe-D-eDP-1
[390.885669] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[390.888068] Subtest 256x256-right-edge: SUCCESS (6.606s)
[391.702080] [070/133] (634s left) gen9_exec_parse (bb-start-out)
[391.763630] Subtest bb-start-out: SKIP (0.000s)
[391.823969] [071/133] (634s left) kms_psr (fbc-psr2-sprite-render)
[391.923120] Starting subtest: fbc-psr2-sprite-render
[391.925324] Starting dynamic subtest: eDP-1
[391.929316] Dynamic subtest eDP-1: SKIP (0.000s)
[391.933359] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[391.998715] [072/133] (634s left) gem_exec_flush (basic-wb-prw-default)
[392.062736] Starting subtest: basic-wb-prw-default
[397.529621] Subtest basic-wb-prw-default: SUCCESS (5.467s)
[397.717278] [073/133] (628s left) gem_ctx_persistence (engines-queued)
[397.781826] Starting subtest: engines-queued
[397.784069] Starting dynamic subtest: rcs0
[397.864698] Dynamic subtest rcs0: SUCCESS (0.082s)
[397.876736] Starting dynamic subtest: bcs0
[397.919282] Dynamic subtest bcs0: SUCCESS (0.053s)
[397.930673] Starting dynamic subtest: vcs0
[399.860563] Dynamic subtest vcs0: SUCCESS (1.938s)
[399.872677] Starting dynamic subtest: vcs1
[401.906973] Dynamic subtest vcs1: SUCCESS (2.044s)
[401.918606] Starting dynamic subtest: vecs0
[403.892443] Dynamic subtest vecs0: SUCCESS (1.982s)
[403.904106] Starting dynamic subtest: ccs0
[404.095024] Dynamic subtest ccs0: SUCCESS (0.201s)
[404.097765] Subtest engines-queued: SUCCESS (6.313s)
[404.187748] [074/133] (622s left) syncobj_eventfd (binary-wait)
[404.256700] Starting subtest: binary-wait
[404.265054] Subtest binary-wait: SUCCESS (0.011s)
[404.379486] [075/133] (621s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[404.474943] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[404.501626] Starting dynamic subtest: pipe-A-plane-0
[404.790394] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[404.802925] Starting dynamic subtest: pipe-A-plane-3
[405.023585] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[406.092489] Starting dynamic subtest: pipe-B-plane-0
[406.385078] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[406.394183] Starting dynamic subtest: pipe-B-plane-3
[406.618720] Dynamic subtest pipe-B-plane-3: SUCCESS (0.231s)
[406.934787] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.461s)
[407.764223] [076/133] (618s left) gem_userptr_blits (unsync-unmap-cycles)
[407.832233] Subtest unsync-unmap-cycles: SKIP (0.000s)
[407.942386] [077/133] (618s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[408.041887] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[408.046242] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[408.110105] [078/133] (618s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[408.226894] Starting subtest: 2x-flip-vs-cursor-atomic
[408.231514] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[408.386836] [079/133] (618s left) kms_content_protection (atomic-dpms)
[408.524850] Starting subtest: atomic-dpms
[408.529319] Subtest atomic-dpms: SKIP (0.000s)
[408.647946] [080/133] (617s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[408.742995] Starting subtest: y-tiled-64bpp-rotate-180
[408.746499] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[408.851422] [081/133] (617s left) vgem_basic (create)
[408.893045] Starting subtest: create
[408.894676] Subtest create: SUCCESS (0.000s)
[408.949731] [082/133] (617s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[409.040907] Starting subtest: yf-tiled-16bpp-rotate-180
[409.044670] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[409.140386] [083/133] (617s left) gem_mmap_wc (bad-object)
[409.191215] Subtest bad-object: SKIP (0.000s)
[409.253112] [084/133] (617s left) kms_chamelium_hpd (dp-hpd-fast)
[409.649247] Subtest dp-hpd-fast: SKIP (0.000s)
[410.478377] [085/133] (616s left) i915_drm_fdinfo (most-busy-idle-check-all)
[410.520485] Starting subtest: most-busy-idle-check-all
[410.522669] Starting dynamic subtest: rcs0
[410.526836] Dynamic subtest rcs0: SKIP (0.004s)
[410.540779] Starting dynamic subtest: bcs0
[410.545140] Dynamic subtest bcs0: SKIP (0.000s)
[410.553495] Starting dynamic subtest: vcs0
[410.557705] Dynamic subtest vcs0: SKIP (0.000s)
[410.565760] Starting dynamic subtest: vcs1
[410.569804] Dynamic subtest vcs1: SKIP (0.000s)
[410.577596] Starting dynamic subtest: vecs0
[410.581635] Dynamic subtest vecs0: SKIP (0.000s)
[410.589457] Starting dynamic subtest: ccs0
[410.593412] Dynamic subtest ccs0: SKIP (0.001s)
[410.597410] Subtest most-busy-idle-check-all: SKIP (0.010s)
[410.636403] [086/133] (616s left) kms_plane_alpha_blend (coverage-7efc)
[410.757314] Starting subtest: coverage-7efc
[411.049608] Starting dynamic subtest: pipe-A-eDP-1
[411.646412] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.593s)
[411.672365] Starting dynamic subtest: pipe-D-eDP-1
[412.955272] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.290s)
[412.957576] Subtest coverage-7efc: SUCCESS (2.200s)
[413.775808] [087/133] (612s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[413.868221] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[413.870504] Starting dynamic subtest: pipe-A-eDP-1
[413.879155] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[413.888877] Starting dynamic subtest: pipe-B-eDP-1
[413.897467] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[413.906669] Starting dynamic subtest: pipe-C-eDP-1
[413.915144] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[413.923540] Starting dynamic subtest: pipe-D-eDP-1
[413.932056] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[413.936251] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[416.059012] [088/133] (610s left) sriov_basic (bind-unbind-vf)
[416.112244] Starting subtest: bind-unbind-vf
[416.114541] Starting dynamic subtest: vf-1
[416.185591] Dynamic subtest vf-1: FAIL (0.005s)
[416.195898] Starting dynamic subtest: vf-2
[416.267027] Dynamic subtest vf-2: FAIL (0.004s)
[416.277480] Starting dynamic subtest: vf-3
[416.349833] Dynamic subtest vf-3: FAIL (0.004s)
[416.361795] Starting dynamic subtest: vf-4
[416.430200] Dynamic subtest vf-4: FAIL (0.004s)
[416.438743] Starting dynamic subtest: vf-5
[416.507210] Dynamic subtest vf-5: FAIL (0.004s)
[416.516390] Starting dynamic subtest: vf-6
[416.585378] Dynamic subtest vf-6: FAIL (0.004s)
[416.593968] Starting dynamic subtest: vf-7
[416.666977] Dynamic subtest vf-7: FAIL (0.004s)
[416.677350] Starting dynamic subtest: vf-random
[416.749858] Dynamic subtest vf-random: FAIL (0.004s)
[416.759950] Starting dynamic subtest: vf-last
[416.832521] Dynamic subtest vf-last: FAIL (0.494s)
[416.834896] Subtest bind-unbind-vf: FAIL (0.535s)
[416.874825] [089/133] (609s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[417.424068] Starting subtest: syncobj-stationary-timeline-chain-engines
[417.535044] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.111s)
[418.983173] [090/133] (607s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[419.094473] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[425.833477] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.741s)
[425.969588] [091/133] (600s left) kms_big_fb (x-tiled-addfb)
[426.090166] Starting subtest: x-tiled-addfb
[426.092500] Subtest x-tiled-addfb: SUCCESS (0.000s)
[426.188054] [092/133] (600s left) kms_color_pipeline (plane-lut1d-lut1d)
[426.298489] Starting subtest: plane-lut1d-lut1d
[426.465512] Starting dynamic subtest: pipe-A-plane-0
[426.620553] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[426.629202] Starting dynamic subtest: pipe-A-plane-1
[426.786784] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[426.797085] Starting dynamic subtest: pipe-A-plane-2
[426.975657] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[428.201226] Starting dynamic subtest: pipe-B-plane-0
[428.370580] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s)
[428.379645] Starting dynamic subtest: pipe-B-plane-1
[428.535544] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[428.544938] Starting dynamic subtest: pipe-B-plane-2
[428.669506] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[429.910825] Starting dynamic subtest: pipe-C-plane-0
[430.048091] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[430.057223] Starting dynamic subtest: pipe-C-plane-1
[430.193073] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[430.203339] Starting dynamic subtest: pipe-C-plane-2
[430.336961] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[431.518030] Starting dynamic subtest: pipe-D-plane-0
[431.676649] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[431.685657] Starting dynamic subtest: pipe-D-plane-1
[431.821480] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[431.831042] Starting dynamic subtest: pipe-D-plane-2
[431.978845] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[432.271627] Subtest plane-lut1d-lut1d: SUCCESS (5.974s)
[433.097461] [093/133] (593s left) kms_flip (wf_vblank-ts-check)
[433.209263] Starting subtest: wf_vblank-ts-check
[433.211629] Starting dynamic subtest: A-eDP1
[442.141758] Dynamic subtest A-eDP1: SUCCESS (8.931s)
[442.150920] Starting dynamic subtest: B-eDP1
[451.078395] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[451.087773] Starting dynamic subtest: C-eDP1
[459.993939] Dynamic subtest C-eDP1: SUCCESS (8.915s)
[460.004844] Starting dynamic subtest: D-eDP1
[468.933054] Dynamic subtest D-eDP1: SUCCESS (8.939s)
[468.937730] Subtest wf_vblank-ts-check: SUCCESS (35.728s)
[470.170661] [094/133] (556s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[470.271576] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[470.275985] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[470.360332] [095/133] (556s left) dmabuf_sync_file (export-multiwait)
[470.400602] Starting subtest: export-multiwait
[470.402461] Subtest export-multiwait: SUCCESS (0.000s)
[470.462677] [096/133] (556s left) kms_cdclk (mode-transition-all-outputs)
[470.547167] Starting subtest: mode-transition-all-outputs
[470.836742] Subtest mode-transition-all-outputs: SKIP (0.288s)
[471.715424] [097/133] (555s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[471.825610] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[471.829861] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[471.939420] [098/133] (555s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[472.227939] Starting subtest: cursor-rapid-movement-512x512
[472.232354] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[472.385713] [099/133] (554s left) i915_drm_fdinfo (virtual-idle)
[472.427697] Starting subtest: virtual-idle
[475.462900] Subtest virtual-idle: SUCCESS (3.035s)
[475.560414] [100/133] (551s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[475.617389] Starting subtest: wait-all-for-submit-delayed-submit
[475.719061] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[475.801995] [101/133] (551s left) i915_suspend (basic-s2idle-without-i915)
[475.849415] Starting subtest: basic-s2idle-without-i915
[496.869224] Subtest basic-s2idle-without-i915: SUCCESS (6.276s)
[500.022523] [102/133] (530s left) gem_exec_suspend (basic-s4-devices)
[500.117944] Starting subtest: basic-S4-devices
[500.120805] Starting dynamic subtest: smem
[511.598528] Dynamic subtest smem: SUCCESS (11.467s)
[511.601514] Subtest basic-S4-devices: SUCCESS (11.469s)
[522.810150] [103/133] (518s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[522.931675] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[522.938088] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[523.036717] [104/133] (518s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[523.125554] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[523.130866] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[523.216882] [105/133] (518s left) gem_exec_params (sol-reset-invalid)
[523.278512] Starting subtest: sol-reset-invalid
[523.281340] Subtest sol-reset-invalid: SUCCESS (0.002s)
[524.706404] [106/133] (516s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[524.842992] Starting subtest: short-flip-before-cursor-toggle
[525.779432] Subtest short-flip-before-cursor-toggle: SUCCESS (0.936s)
[526.614654] [107/133] (515s left) kms_lease (lessee-list)
[526.715346] Starting subtest: lessee-list
[526.717833] Starting dynamic subtest: pipe-A-eDP-1
[526.720527] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[526.730868] Starting dynamic subtest: pipe-B-eDP-1
[526.732719] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[526.742188] Starting dynamic subtest: pipe-C-eDP-1
[526.744328] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[526.753391] Starting dynamic subtest: pipe-D-eDP-1
[526.755786] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[526.758100] Subtest lessee-list: SUCCESS (0.014s)
[526.854310] [108/133] (514s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[526.958012] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[526.969376] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[527.063128] [109/133] (514s left) gem_wait (write-busy)
[527.134547] Starting subtest: write-busy
[527.137438] Starting dynamic subtest: all-engines
[527.745504] Dynamic subtest all-engines: SUCCESS (0.610s)
[527.761347] Starting dynamic subtest: rcs0
[528.299865] Dynamic subtest rcs0: SUCCESS (0.553s)
[528.318141] Starting dynamic subtest: bcs0
[528.850765] Dynamic subtest bcs0: SUCCESS (0.550s)
[528.868877] Starting dynamic subtest: vcs0
[530.671884] Dynamic subtest vcs0: SUCCESS (1.818s)
[530.688929] Starting dynamic subtest: vcs1
[532.653867] Dynamic subtest vcs1: SUCCESS (1.979s)
[532.670658] Starting dynamic subtest: vecs0
[534.637835] Dynamic subtest vecs0: SUCCESS (1.981s)
[534.656013] Starting dynamic subtest: ccs0
[535.190893] Dynamic subtest ccs0: SUCCESS (0.551s)
[535.193742] Subtest write-busy: SUCCESS (8.054s)
[535.392038] [110/133] (506s left) prime_mmap (test_correct)
[535.468447] Starting subtest: test_correct
[535.471073] Starting dynamic subtest: test_correct-smem
[535.500929] Dynamic subtest test_correct-smem: SUCCESS (0.035s)
[535.503701] Subtest test_correct: SUCCESS (0.035s)
[535.605346] [111/133] (506s left) gem_create (create-size-update)
[535.653153] Starting subtest: create-size-update
[535.656100] Subtest create-size-update: SUCCESS (0.000s)
[535.884856] [112/133] (506s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[535.994453] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[542.912311] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.920s)
[543.037571] [113/133] (498s left) prime_self_import (basic-llseek-size)
[543.077222] Starting subtest: basic-llseek-size
[543.095413] Subtest basic-llseek-size: SUCCESS (0.019s)
[543.203225] [114/133] (498s left) syncobj_wait (wait-all-complex)
[543.264260] Starting subtest: wait-all-complex
[543.481081] Subtest wait-all-complex: SUCCESS (0.218s)
[543.572226] [115/133] (498s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[543.679228] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[543.684458] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[543.762559] [116/133] (498s left) syncobj_timeline (wait-all-interrupted)
[543.825759] Starting subtest: wait-all-interrupted
[543.927926] Subtest wait-all-interrupted: SUCCESS (0.103s)
[544.010101] [117/133] (498s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[544.104467] Starting subtest: fbc-pr-cursor-mmap-cpu
[544.109733] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[544.178730] [118/133] (497s left) kms_scaling_modes (scaling-mode-center)
[544.276043] Starting subtest: scaling-mode-center
[544.278583] Starting dynamic subtest: pipe-A-eDP-1
[544.370393] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[544.383404] Starting dynamic subtest: pipe-B-eDP-1
[545.579458] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.205s)
[545.589425] Starting dynamic subtest: pipe-C-eDP-1
[546.706885] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[546.716905] Starting dynamic subtest: pipe-D-eDP-1
[547.842708] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[547.845329] Subtest scaling-mode-center: SUCCESS (3.569s)
[549.006661] [119/133] (493s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[549.152375] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[549.157356] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[549.240181] [120/133] (493s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[549.333131] Starting subtest: fbc-rgb565-draw-blt
[553.504529] Subtest fbc-rgb565-draw-blt: SUCCESS (4.176s)
[554.632278] [121/133] (487s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[554.743516] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[562.357827] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.617s)
[562.472299] [122/133] (479s left) gem_softpin (evict-prime-sanity-check)
[562.530101] Starting subtest: evict-prime-sanity-check
[562.532851] Starting dynamic subtest: rcs0
[563.685927] Dynamic subtest rcs0: SUCCESS (1.155s)
[563.699515] Starting dynamic subtest: bcs0
[564.802145] Dynamic subtest bcs0: SUCCESS (1.114s)
[564.815673] Starting dynamic subtest: vcs0
[566.612591] Dynamic subtest vcs0: SUCCESS (1.808s)
[566.626039] Starting dynamic subtest: vcs1
[568.596700] Dynamic subtest vcs1: SUCCESS (1.981s)
[568.609890] Starting dynamic subtest: vecs0
[570.581202] Dynamic subtest vecs0: SUCCESS (1.982s)
[570.592374] Starting dynamic subtest: ccs0
[571.718550] Dynamic subtest ccs0: SUCCESS (1.136s)
[571.728967] Starting dynamic subtest: all
[572.838647] Dynamic subtest all: SUCCESS (1.118s)
[572.841312] Subtest evict-prime-sanity-check: SUCCESS (10.308s)
[572.934667] [123/133] (469s left) gem_pxp (reject-modify-context-protection-off-1)
[573.070704] Starting subtest: reject-modify-context-protection-off-1
[573.073382] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[573.180770] [124/133] (469s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[573.275509] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[573.277828] Starting dynamic subtest: pipe-A
[573.296527] Dynamic subtest pipe-A: SKIP (0.017s)
[573.306575] Starting dynamic subtest: pipe-B
[573.326037] Dynamic subtest pipe-B: SKIP (0.030s)
[573.336213] Starting dynamic subtest: pipe-C
[573.357399] Dynamic subtest pipe-C: SKIP (0.030s)
[573.367523] Starting dynamic subtest: pipe-D
[573.396145] Dynamic subtest pipe-D: SKIP (0.037s)
[573.400681] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.116s)
[573.475274] [125/133] (469s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[573.578121] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[573.580434] Starting dynamic subtest: pipe-A-eDP-1
[573.673034] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[573.686539] Starting dynamic subtest: pipe-B-eDP-1
[574.781405] Dynamic subtest pipe-B-eDP-1: SKIP (1.105s)
[574.786801] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.208s)
[575.972504] [126/133] (466s left) kms_addfb_basic (addfb25-4-tiled)
[576.065547] Starting subtest: addfb25-4-tiled
[576.068333] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[576.162051] [127/133] (466s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[576.253405] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[580.701756] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.453s)
[581.894439] [128/133] (460s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[581.953785] Starting subtest: etime-single-wait-available-unsubmitted
[582.058284] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.105s)
[582.150191] [129/133] (460s left) gem_workarounds (reset-context)
[582.220839] Starting subtest: reset-context
[582.400390] Subtest reset-context: SUCCESS (0.178s)
[582.533108] [130/133] (460s left) gem_exec_whisper (basic-forked)
[582.598291] Starting subtest: basic-forked
[590.518525] Subtest basic-forked: SUCCESS (7.919s)
[590.707954] [131/133] (452s left) kms_flip (bo-too-big-interruptible)
[590.837292] Starting subtest: bo-too-big-interruptible
[590.841901] Starting dynamic subtest: A-eDP1
[592.129719] Dynamic subtest A-eDP1: SUCCESS (1.292s)
[592.142825] Starting dynamic subtest: B-eDP1
[593.468042] Dynamic subtest B-eDP1: SUCCESS (1.335s)
[593.482692] Starting dynamic subtest: C-eDP1
[594.711443] Dynamic subtest C-eDP1: SUCCESS (1.243s)
[594.724454] Starting dynamic subtest: D-eDP1
[596.045522] Dynamic subtest D-eDP1: SUCCESS (1.332s)
[596.048129] Subtest bo-too-big-interruptible: SUCCESS (5.213s)
[597.247166] [132/133] (445s left) gem_mmap_offset (clear)
[597.302244] Starting subtest: clear
[597.305973] Starting dynamic subtest: smem0
[621.498757] Dynamic subtest smem0: SUCCESS (24.191s)
[621.501553] Subtest clear: SUCCESS (24.193s)
[621.626390] [133/133] (421s left) kms_flip (basic-plain-flip)
[621.774530] Starting subtest: basic-plain-flip
[621.777287] Starting dynamic subtest: A-eDP1
[623.450280] Dynamic subtest A-eDP1: SUCCESS (1.675s)
[623.463365] Starting dynamic subtest: B-eDP1
[625.096085] Dynamic subtest B-eDP1: SUCCESS (1.644s)
[625.110713] Starting dynamic subtest: C-eDP1
[626.717759] Dynamic subtest C-eDP1: SUCCESS (1.620s)
[626.730321] Starting dynamic subtest: D-eDP1
[628.360469] Dynamic subtest D-eDP1: SUCCESS (1.641s)
[628.366905] Subtest basic-plain-flip: SUCCESS (6.592s)
[629.532586] 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.