[54.807860] Initializing watchdogs
[54.807904]   /dev/watchdog0
[54.821970] [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]
[54.826936] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.829314] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.832553] [001/133] (960s left) kms_prop_blob (blob-prop-validate)
[54.900169] Starting subtest: blob-prop-validate
[54.902595] Subtest blob-prop-validate: SUCCESS (0.000s)
[55.027398] [002/133] (960s left) gem_exec_schedule (u-submit-golden-slice)
[55.095068] Starting subtest: u-submit-golden-slice
[55.097563] Starting dynamic subtest: rcs0
[55.151692] Dynamic subtest rcs0: SUCCESS (0.062s)
[55.161404] Starting dynamic subtest: bcs0
[55.196709] Dynamic subtest bcs0: SUCCESS (0.044s)
[55.205380] Starting dynamic subtest: vcs0
[55.246082] Dynamic subtest vcs0: SUCCESS (0.049s)
[55.254285] Starting dynamic subtest: vcs1
[55.298738] Dynamic subtest vcs1: SUCCESS (0.052s)
[55.305296] Starting dynamic subtest: vecs0
[55.338567] Dynamic subtest vecs0: SUCCESS (0.040s)
[55.345174] Starting dynamic subtest: ccs0
[55.378252] Dynamic subtest ccs0: SUCCESS (0.039s)
[55.380385] Subtest u-submit-golden-slice: SUCCESS (0.289s)
[57.028381] [003/133] (958s left) kms_color (ctm-0-75)
[57.136451] Starting subtest: ctm-0-75
[57.138304] Starting dynamic subtest: pipe-A-eDP-1
[62.033823] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.898s)
[62.042812] Starting dynamic subtest: pipe-B-eDP-1
[67.499848] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.464s)
[67.509405] Starting dynamic subtest: pipe-C-eDP-1
[72.791910] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.293s)
[72.799435] Starting dynamic subtest: pipe-D-eDP-1
[78.106914] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.313s)
[78.109277] Subtest ctm-0-75: SUCCESS (20.970s)
[78.940843] [004/133] (936s left) gem_exec_reloc (basic-wc)
[78.993958] Subtest basic-wc: SKIP (0.000s)
[79.084488] [005/133] (936s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[79.179749] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[80.214079] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.033s)
[80.379452] [006/133] (935s left) i915_hangman (engine-engine-hang)
[80.444090] Starting subtest: engine-engine-hang
[80.446054] Starting dynamic subtest: rcs0
[81.845468] Dynamic subtest rcs0: SUCCESS (1.399s)
[81.856223] Starting dynamic subtest: bcs0
[83.889168] Dynamic subtest bcs0: SUCCESS (2.042s)
[83.898624] Starting dynamic subtest: vcs0
[85.874428] Dynamic subtest vcs0: SUCCESS (1.983s)
[85.885612] Starting dynamic subtest: vcs1
[87.857786] Dynamic subtest vcs1: SUCCESS (1.981s)
[87.868568] Starting dynamic subtest: vecs0
[89.905668] Dynamic subtest vecs0: SUCCESS (2.046s)
[89.916111] Starting dynamic subtest: ccs0
[91.889490] Dynamic subtest ccs0: SUCCESS (1.982s)
[91.892439] Subtest engine-engine-hang: SUCCESS (11.443s)
[91.981109] [007/133] (923s left) gem_exec_schedule (submit-late-slice)
[92.066755] Starting subtest: submit-late-slice
[92.069160] Starting dynamic subtest: rcs0
[92.119751] Dynamic subtest rcs0: SUCCESS (0.060s)
[92.132582] Starting dynamic subtest: bcs0
[92.160313] Dynamic subtest bcs0: SUCCESS (0.040s)
[92.170760] Starting dynamic subtest: vcs0
[92.202349] Dynamic subtest vcs0: SUCCESS (0.042s)
[92.209238] Starting dynamic subtest: vcs1
[92.254840] Dynamic subtest vcs1: SUCCESS (0.052s)
[92.262192] Starting dynamic subtest: vecs0
[92.299674] Dynamic subtest vecs0: SUCCESS (0.045s)
[92.308917] Starting dynamic subtest: ccs0
[92.345932] Dynamic subtest ccs0: SUCCESS (0.046s)
[92.348261] Subtest submit-late-slice: SUCCESS (0.287s)
[94.037886] [008/133] (921s left) gem_tiled_wb
[94.148624] [009/133] (921s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[94.249034] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[94.251334] Starting dynamic subtest: pipe-A
[94.270039] Dynamic subtest pipe-A: SKIP (0.015s)
[94.278742] Starting dynamic subtest: pipe-B
[94.295777] Dynamic subtest pipe-B: SKIP (0.029s)
[94.303127] Starting dynamic subtest: pipe-C
[94.324766] Dynamic subtest pipe-C: SKIP (0.029s)
[94.331733] Starting dynamic subtest: pipe-D
[94.355660] Dynamic subtest pipe-D: SKIP (0.029s)
[94.359413] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.104s)
[94.473473] [010/133] (921s left) gem_mmap_wc (set-cache-level)
[94.537182] Subtest set-cache-level: SKIP (0.000s)
[94.597762] [011/133] (921s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[94.687899] Starting subtest: extended-modeset-hang-oldfb-with-reset
[94.690391] Starting dynamic subtest: pipe-A
[106.402446] Dynamic subtest pipe-A: SUCCESS (11.712s)
[106.412209] Starting dynamic subtest: pipe-D
[118.700428] Dynamic subtest pipe-D: SUCCESS (12.295s)
[118.702806] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.010s)
[119.515158] [012/133] (896s left) drm_read (empty-block)
[119.666096] Starting subtest: empty-block
[120.666444] Subtest empty-block: SUCCESS (1.000s)
[120.838718] [013/133] (894s left) syncobj_timeline (invalid-signal-illegal-point)
[120.894200] Starting subtest: invalid-signal-illegal-point
[120.896496] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[121.008980] [014/133] (894s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[126.093914] Starting subtest: fbc-psr2-cursor-plane-update-sf
[126.096228] Starting dynamic subtest: pipe-A-eDP-1
[126.893242] Dynamic subtest pipe-A-eDP-1: SKIP (0.795s)
[126.902588] Starting dynamic subtest: pipe-B-eDP-1
[128.024589] Dynamic subtest pipe-B-eDP-1: SKIP (1.128s)
[128.029503] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.929s)
[129.229270] [015/133] (886s left) i915_pm_rpm (system-suspend-devices)
[131.063503] Starting subtest: system-suspend-devices
[138.644303] Subtest system-suspend-devices: SUCCESS (7.582s)
[139.037813] [016/133] (876s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[139.146246] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[146.071307] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.921s)
[146.207209] [017/133] (869s left) kms_color (gamma)
[146.304889] Starting subtest: gamma
[146.306798] Starting dynamic subtest: pipe-A-eDP-1
[147.011672] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.706s)
[147.022802] Starting dynamic subtest: pipe-B-eDP-1
[148.348525] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.335s)
[148.357940] Starting dynamic subtest: pipe-C-eDP-1
[149.661478] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.311s)
[149.671077] Starting dynamic subtest: pipe-D-eDP-1
[151.003265] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[151.005190] Subtest gamma: SUCCESS (4.696s)
[151.822091] [018/133] (864s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[151.915044] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[151.917769] Starting dynamic subtest: pipe-A-eDP-1
[151.940639] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[151.951145] Starting dynamic subtest: pipe-B-eDP-1
[151.972641] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[151.981597] Starting dynamic subtest: pipe-C-eDP-1
[152.003598] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[152.013078] Starting dynamic subtest: pipe-D-eDP-1
[152.035102] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[152.039182] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[154.096794] [019/133] (861s left) gem_ccs (large-ctrl-surf-copy)
[154.152103] Starting subtest: large-ctrl-surf-copy
[154.156717] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[154.272227] [020/133] (861s left) gem_ctx_shared (detached-shared-gtt)
[154.343024] Starting subtest: detached-shared-gtt
[156.496297] Subtest detached-shared-gtt: SUCCESS (2.151s)
[156.866785] [021/133] (859s left) syncobj_wait (multi-wait-all-submitted-signaled)
[156.920380] Starting subtest: multi-wait-all-submitted-signaled
[157.024263] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[157.118286] [022/133] (859s left) kms_content_protection (atomic-dpms)
[157.256876] Starting subtest: atomic-dpms
[157.261770] Subtest atomic-dpms: SKIP (0.000s)
[157.428013] [023/133] (858s left) prime_busy (after-wait)
[157.478744] Starting subtest: after-wait
[157.480684] Starting dynamic subtest: rcs0
[157.519256] Dynamic subtest rcs0: SUCCESS (0.038s)
[157.529795] Starting dynamic subtest: bcs0
[157.577407] Dynamic subtest bcs0: SUCCESS (0.057s)
[157.587249] Starting dynamic subtest: vcs0
[157.618481] Dynamic subtest vcs0: SUCCESS (0.040s)
[157.627598] Starting dynamic subtest: vcs1
[157.659447] Dynamic subtest vcs1: SUCCESS (0.040s)
[157.668477] Starting dynamic subtest: vecs0
[157.700481] Dynamic subtest vecs0: SUCCESS (0.040s)
[157.710186] Starting dynamic subtest: ccs0
[157.739867] Dynamic subtest ccs0: SUCCESS (0.039s)
[157.847636] Subtest after-wait: SUCCESS (0.368s)
[157.931341] [024/133] (858s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[158.052876] Starting subtest: cursorA-vs-flipA-varying-size
[169.195524] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.138s)
[170.019795] [025/133] (846s left) kms_chamelium_frames (hdmi-crc-fast)
[170.411711] Subtest hdmi-crc-fast: SKIP (0.000s)
[171.211395] [026/133] (845s left) kms_addfb_basic (size-max)
[171.296826] Starting subtest: size-max
[171.299028] Subtest size-max: SUCCESS (0.000s)
[171.419545] [027/133] (844s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[171.484026] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[171.592075] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.108s)
[171.685484] [028/133] (844s left) gem_exec_reloc (basic-gtt-active)
[171.750125] Subtest basic-gtt-active: SKIP (0.000s)
[171.875309] [029/133] (844s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[171.968207] Starting subtest: fbc-farfromfence-mmap-gtt
[171.972619] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[172.057348] [030/133] (844s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[172.144225] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[172.148713] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[173.279640] [031/133] (843s left) kms_vrr (seamless-rr-switch-vrr)
[173.372205] Starting subtest: seamless-rr-switch-vrr
[173.379262] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[173.458983] [032/133] (843s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[173.555862] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[173.560161] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[173.656496] [033/133] (842s left) kms_async_flips (invalid-async-flip-atomic)
[173.751835] Starting subtest: invalid-async-flip-atomic
[174.127802] Starting dynamic subtest: pipe-A-eDP-1
[175.541379] Dynamic subtest pipe-A-eDP-1: FAIL (0.819s)
[175.551684] Starting dynamic subtest: pipe-B-eDP-1
[176.462562] Dynamic subtest pipe-B-eDP-1: FAIL (0.747s)
[176.472487] Starting dynamic subtest: pipe-C-eDP-1
[177.215844] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.798s)
[177.577509] Starting dynamic subtest: pipe-D-eDP-1
[178.350204] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.781s)
[178.352540] Subtest invalid-async-flip-atomic: FAIL (4.600s)
[179.504412] [034/133] (837s left) gem_mmap_wc (read)
[179.564738] Subtest read: SKIP (0.000s)
[179.619537] [035/133] (837s left) sw_sync (alloc_fence)
[179.652748] Starting subtest: alloc_fence
[179.655244] Subtest alloc_fence: SUCCESS (0.000s)
[179.724497] [036/133] (837s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[179.822412] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[179.826820] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[179.936971] [037/133] (836s left) gem_exec_fence (syncobj-timeline-export)
[180.493069] Starting subtest: syncobj-timeline-export
[180.499251] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[182.022812] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[182.026197] [038/133] (834s left) kms_scaling_modes (scaling-mode-none)
[182.123179] Starting subtest: scaling-mode-none
[182.125523] Starting dynamic subtest: pipe-A-eDP-1
[182.222655] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[182.234256] Starting dynamic subtest: pipe-B-eDP-1
[182.258070] Dynamic subtest pipe-B-eDP-1: SKIP (0.033s)
[182.268234] Starting dynamic subtest: pipe-C-eDP-1
[182.289985] Dynamic subtest pipe-C-eDP-1: SKIP (0.031s)
[182.298801] Starting dynamic subtest: pipe-D-eDP-1
[182.324790] Dynamic subtest pipe-D-eDP-1: SKIP (0.033s)
[182.329179] Subtest scaling-mode-none: SKIP (0.202s)
[182.496918] [039/133] (834s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[182.557991] Starting subtest: yf-tiled-ccs-to-x-tiled
[182.562722] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[182.752286] [040/133] (834s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[182.852680] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[183.812095] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.958s)
[183.968432] [041/133] (832s left) perf (polling)
[184.092042] Starting subtest: polling
[184.094338] Starting dynamic subtest: 0-rcs0
[194.124815] Dynamic subtest 0-rcs0: SUCCESS (10.029s)
[194.138252] Starting dynamic subtest: 1-vcs0
[204.146341] Dynamic subtest 1-vcs0: SUCCESS (10.017s)
[204.149131] Subtest polling: SUCCESS (20.049s)
[204.196916] [042/133] (812s left) gem_exec_schedule (pi-ringfull)
[204.382743] Starting subtest: pi-ringfull
[204.384787] Starting dynamic subtest: rcs0
[204.417882] Dynamic subtest rcs0: SUCCESS (0.036s)
[204.427423] Starting dynamic subtest: bcs0
[204.452284] Dynamic subtest bcs0: SUCCESS (0.034s)
[204.460877] Starting dynamic subtest: vcs0
[204.483438] Dynamic subtest vcs0: SUCCESS (0.030s)
[204.492208] Starting dynamic subtest: vcs1
[204.514498] Dynamic subtest vcs1: SUCCESS (0.030s)
[204.523279] Starting dynamic subtest: vecs0
[204.547040] Dynamic subtest vecs0: SUCCESS (0.032s)
[204.555616] Starting dynamic subtest: ccs0
[204.579110] Dynamic subtest ccs0: SUCCESS (0.031s)
[204.581264] Subtest pi-ringfull: SUCCESS (0.199s)
[204.831688] [043/133] (812s left) prime_mmap_coherency (read)
[204.888588] Starting subtest: read
[210.266728] Subtest read: SUCCESS (5.376s)
[210.419095] [044/133] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[210.523095] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[210.527319] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[210.657384] [045/133] (806s left) kms_cursor_edge_walk (128x128-top-bottom)
[210.750912] Starting subtest: 128x128-top-bottom
[210.753162] Starting dynamic subtest: pipe-A-eDP-1
[214.171513] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.418s)
[214.181988] Starting dynamic subtest: pipe-D-eDP-1
[217.294526] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.121s)
[217.296751] Subtest 128x128-top-bottom: SUCCESS (6.542s)
[218.110955] [046/133] (799s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[218.208062] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[218.583783] Starting dynamic subtest: pipe-A-eDP-1
[219.856526] Dynamic subtest pipe-A-eDP-1: FAIL (0.801s)
[219.867859] Starting dynamic subtest: pipe-B-eDP-1
[220.785410] Dynamic subtest pipe-B-eDP-1: FAIL (0.762s)
[220.903032] Starting dynamic subtest: pipe-C-eDP-1
[223.649800] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.753s)
[224.013088] Starting dynamic subtest: pipe-D-eDP-1
[226.776776] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.769s)
[226.779372] Subtest async-flip-with-page-flip-events-linear-atomic: FAIL (8.569s)
[227.978257] [047/133] (789s left) gem_exec_schedule (deep)
[228.156179] Starting subtest: deep
[228.158618] Starting dynamic subtest: rcs0
[232.819632] Dynamic subtest rcs0: SKIP (4.659s)
[232.831064] Starting dynamic subtest: vcs0
[242.701443] Dynamic subtest vcs0: SUCCESS (9.882s)
[242.712386] Starting dynamic subtest: vcs1
[254.092215] Dynamic subtest vcs1: SUCCESS (11.388s)
[254.102223] Starting dynamic subtest: vecs0
[264.661392] Dynamic subtest vecs0: SUCCESS (10.566s)
[264.675202] Starting dynamic subtest: ccs0
[277.077414] Dynamic subtest ccs0: SUCCESS (12.414s)
[277.079943] Subtest deep: SUCCESS (48.916s)
[278.471743] [048/133] (738s left) gem_mmap_wc (write-read-distinct)
[278.531043] Subtest write-read-distinct: SKIP (0.000s)
[278.597901] [049/133] (738s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[278.701948] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[278.706335] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[278.845360] [050/133] (738s left) gem_wait (invalid-buf)
[278.903969] Starting subtest: invalid-buf
[278.906186] Subtest invalid-buf: SUCCESS (0.000s)
[279.149607] [051/133] (738s left) gem_exec_reloc (basic-cpu-read-noreloc)
[279.209398] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[279.303285] [052/133] (738s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[279.396850] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[279.399112] Starting dynamic subtest: pipe-A-eDP-1
[279.421468] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[279.432710] Starting dynamic subtest: pipe-B-eDP-1
[279.454268] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[279.463113] Starting dynamic subtest: pipe-C-eDP-1
[279.484993] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[279.494543] Starting dynamic subtest: pipe-D-eDP-1
[279.518141] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[279.522377] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[281.672168] [053/133] (735s left) kms_cursor_crc (cursor-onscreen-64x21)
[281.853288] Starting subtest: cursor-onscreen-64x21
[281.900397] Subtest cursor-onscreen-64x21: SKIP (0.042s)
[282.145491] [054/133] (735s left) kms_plane_scaling (planes-downscale-factor-0-5)
[282.231021] Starting subtest: planes-downscale-factor-0-5
[282.233343] Starting dynamic subtest: pipe-A
[282.252293] Dynamic subtest pipe-A: SKIP (0.017s)
[282.261674] Starting dynamic subtest: pipe-B
[282.282565] Dynamic subtest pipe-B: SKIP (0.030s)
[282.291524] Starting dynamic subtest: pipe-C
[282.315260] Dynamic subtest pipe-C: SKIP (0.031s)
[282.324075] Starting dynamic subtest: pipe-D
[282.348915] Dynamic subtest pipe-D: SKIP (0.034s)
[282.353049] Subtest planes-downscale-factor-0-5: SKIP (0.116s)
[282.474137] [055/133] (735s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[282.564097] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[282.566317] Starting dynamic subtest: pipe-A-default-mode
[282.850793] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[282.855292] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.286s)
[283.696452] [056/133] (733s left) gem_ctx_exec (basic-invalid-context)
[283.771261] Starting subtest: basic-invalid-context
[283.773568] Subtest basic-invalid-context: SUCCESS (0.004s)
[283.931542] [057/133] (733s left) kms_pm_rpm (pm-caching)
[286.058144] Starting subtest: pm-caching
[286.070901] Subtest pm-caching: SKIP (0.000s)
[286.461186] [058/133] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[286.556431] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[293.615666] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.060s)
[293.785927] [059/133] (723s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[293.889693] Starting subtest: fbc-modesetfrombusy
[297.707412] Subtest fbc-modesetfrombusy: SUCCESS (3.822s)
[298.892195] [060/133] (718s left) perf (missing-sample-flags)
[298.988516] Starting subtest: missing-sample-flags
[298.990462] Subtest missing-sample-flags: SUCCESS (0.000s)
[299.029101] [061/133] (718s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[299.118609] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[299.122839] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[299.253014] [062/133] (718s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[299.359803] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[299.364214] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[299.495466] [063/133] (718s left) i915_drm_fdinfo (isolation)
[299.532415] Starting subtest: isolation
[299.534453] Starting dynamic subtest: rcs0
[299.563887] Dynamic subtest rcs0: SKIP (0.033s)
[299.575248] Starting dynamic subtest: bcs0
[299.579681] Dynamic subtest bcs0: SKIP (0.000s)
[299.588670] Starting dynamic subtest: vcs0
[299.592872] Dynamic subtest vcs0: SKIP (0.000s)
[299.601890] Starting dynamic subtest: vcs1
[299.606255] Dynamic subtest vcs1: SKIP (0.000s)
[299.614934] Starting dynamic subtest: vecs0
[299.619298] Dynamic subtest vecs0: SKIP (0.000s)
[299.627579] Starting dynamic subtest: ccs0
[299.631964] Dynamic subtest ccs0: SKIP (0.000s)
[299.636359] Subtest isolation: SKIP (0.038s)
[299.671988] [064/133] (718s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[304.666590] Starting subtest: psr2-overlay-plane-update-continuous-sf
[304.668952] Starting dynamic subtest: pipe-A-eDP-1
[306.414711] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.743s)
[306.424214] Starting dynamic subtest: pipe-B-eDP-1
[308.150041] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.733s)
[308.152343] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.484s)
[308.997522] [065/133] (708s left) kms_flip (2x-plain-flip-fb-recreate)
[309.092988] Starting subtest: 2x-plain-flip-fb-recreate
[309.103791] Subtest 2x-plain-flip-fb-recreate: SKIP (0.010s)
[309.218381] [066/133] (708s left) kms_psr (pr-no-drrs)
[309.313337] Starting subtest: pr-no-drrs
[309.317614] Subtest pr-no-drrs: SKIP (0.000s)
[309.400041] [067/133] (708s left) kms_invalid_mode (int-max-clock)
[309.493306] Starting subtest: int-max-clock
[309.495578] Starting dynamic subtest: pipe-A-eDP-1
[309.779219] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[309.789212] Starting dynamic subtest: pipe-B-eDP-1
[309.791482] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[309.800357] Starting dynamic subtest: pipe-C-eDP-1
[309.802622] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[309.811728] Starting dynamic subtest: pipe-D-eDP-1
[309.814056] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[309.816340] Subtest int-max-clock: SUCCESS (0.294s)
[310.642455] [068/133] (707s left) kms_flip (modeset-vs-vblank-race)
[310.744238] Starting subtest: modeset-vs-vblank-race
[310.746077] Starting dynamic subtest: A-eDP1
[315.415996] Dynamic subtest A-eDP1: SUCCESS (4.673s)
[315.427737] Starting dynamic subtest: B-eDP1
[320.133577] Dynamic subtest B-eDP1: SUCCESS (4.715s)
[320.148147] Starting dynamic subtest: C-eDP1
[324.762602] Dynamic subtest C-eDP1: SUCCESS (4.626s)
[324.774064] Starting dynamic subtest: D-eDP1
[329.393925] Dynamic subtest D-eDP1: SUCCESS (4.629s)
[329.398799] Subtest modeset-vs-vblank-race: SUCCESS (18.656s)
[330.670927] [069/133] (687s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[330.748377] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[330.808821] [070/133] (687s left) kms_draw_crc (draw-method-mmap-cpu)
[330.903221] Starting subtest: draw-method-mmap-cpu
[330.905088] Starting dynamic subtest: xrgb8888-untiled
[331.157093] Dynamic subtest xrgb8888-untiled: SUCCESS (0.256s)
[331.166681] Starting dynamic subtest: rgb565-untiled
[331.390347] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[331.400877] Starting dynamic subtest: xrgb2101010-untiled
[331.590114] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.199s)
[331.598953] Starting dynamic subtest: xbgr16161616f-untiled
[331.801019] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[331.809518] Starting dynamic subtest: xrgb8888-xtiled
[331.945348] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.144s)
[331.956179] Starting dynamic subtest: rgb565-xtiled
[332.090190] Dynamic subtest rgb565-xtiled: SUCCESS (0.142s)
[332.098737] Starting dynamic subtest: xrgb2101010-xtiled
[332.223453] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[332.232720] Starting dynamic subtest: xbgr16161616f-xtiled
[332.357192] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[332.371786] Starting dynamic subtest: xrgb8888-4tiled
[332.501793] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.142s)
[332.512536] Starting dynamic subtest: rgb565-4tiled
[332.645308] Dynamic subtest rgb565-4tiled: SUCCESS (0.141s)
[332.654268] Starting dynamic subtest: xrgb2101010-4tiled
[332.779108] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[332.788342] Starting dynamic subtest: xbgr16161616f-4tiled
[332.913651] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.134s)
[332.915653] Subtest draw-method-mmap-cpu: SUCCESS (2.013s)
[333.036904] [071/133] (685s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[333.141699] Starting subtest: yf-tiled-32bpp-rotate-0
[333.145469] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[333.284178] [072/133] (684s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[333.373434] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[342.596957] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.224s)
[342.744824] [073/133] (675s left) kms_flip (flip-vs-rmfb-interruptible)
[342.848287] Starting subtest: flip-vs-rmfb-interruptible
[342.850625] Starting dynamic subtest: A-eDP1
[351.527964] Dynamic subtest A-eDP1: SUCCESS (8.678s)
[351.538127] Starting dynamic subtest: B-eDP1
[360.152771] Dynamic subtest B-eDP1: SUCCESS (8.622s)
[360.163241] Starting dynamic subtest: C-eDP1
[368.772511] Dynamic subtest C-eDP1: SUCCESS (8.618s)
[368.781476] Starting dynamic subtest: D-eDP1
[377.405120] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[377.410391] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.558s)
[378.605109] [074/133] (639s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[378.697911] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[382.599502] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.906s)
[383.789047] [075/133] (634s left) gem_pread (self)
[383.845039] Subtest self: SKIP (0.000s)
[383.906857] [076/133] (634s left) gem_mmap_offset (mmap-boundaries)
[383.962604] Starting subtest: mmap-boundaries
[383.964951] Starting dynamic subtest: smem0
[384.237873] Dynamic subtest smem0: SUCCESS (0.275s)
[384.240558] Subtest mmap-boundaries: SUCCESS (0.277s)
[384.374765] [077/133] (634s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[384.470693] Starting subtest: psr-rgb565-draw-blt
[391.530305] Subtest psr-rgb565-draw-blt: SUCCESS (7.061s)
[391.670268] [078/133] (626s left) gem_spin_batch (spin-all)
[391.725888] Starting subtest: spin-all
[391.740527] Subtest spin-all: SUCCESS (0.013s)
[392.978411] [079/133] (625s left) gem_reg_read (timestamp-moving)
[393.027896] Starting subtest: timestamp-moving
[394.030079] Subtest timestamp-moving: SUCCESS (1.001s)
[394.132292] [080/133] (624s left) gen9_exec_parse (bb-large)
[394.186436] Subtest bb-large: SKIP (0.000s)
[394.241265] [081/133] (624s left) kms_cursor_crc (cursor-random-max-size)
[394.535899] Starting subtest: cursor-random-max-size
[394.543053] Subtest cursor-random-max-size: SKIP (0.000s)
[394.706256] [082/133] (623s left) kms_frontbuffer_tracking (fbc-1p-rte)
[394.805478] Starting subtest: fbc-1p-rte
[400.269915] Subtest fbc-1p-rte: SUCCESS (5.467s)
[401.491120] [083/133] (617s left) gem_exec_whisper (basic-queues-all)
[401.555750] Starting subtest: basic-queues-all
[412.162479] Subtest basic-queues-all: SUCCESS (10.605s)
[412.369360] [084/133] (606s left) kms_psr (psr-cursor-mmap-cpu)
[412.469845] Starting subtest: psr-cursor-mmap-cpu
[412.472108] Starting dynamic subtest: eDP-1
[414.156650] Dynamic subtest eDP-1: SUCCESS (1.688s)
[414.159188] Subtest psr-cursor-mmap-cpu: SUCCESS (1.689s)
[415.039258] [085/133] (603s left) kms_addfb_basic (invalid-get-prop-any)
[415.083947] Starting subtest: invalid-get-prop-any
[415.086317] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[415.252747] [086/133] (603s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[415.352757] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[415.357174] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[415.478428] [087/133] (603s left) gem_exec_async (forked-writes)
[415.541468] Starting subtest: forked-writes
[415.543977] Starting dynamic subtest: rcs0
[415.567136] Dynamic subtest rcs0: SUCCESS (0.026s)
[415.577211] Starting dynamic subtest: bcs0
[415.590328] Dynamic subtest bcs0: SUCCESS (0.022s)
[415.601827] Starting dynamic subtest: vcs0
[415.610051] Dynamic subtest vcs0: SUCCESS (0.019s)
[415.620685] Starting dynamic subtest: vcs1
[415.632972] Dynamic subtest vcs1: SUCCESS (0.022s)
[415.645749] Starting dynamic subtest: vecs0
[415.657193] Dynamic subtest vecs0: SUCCESS (0.023s)
[415.667123] Starting dynamic subtest: ccs0
[415.678721] Dynamic subtest ccs0: SUCCESS (0.021s)
[415.681089] Subtest forked-writes: SUCCESS (0.138s)
[415.935927] [088/133] (602s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[416.045654] Starting subtest: 4-tiled-32bpp-rotate-90
[416.064703] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s)
[416.187476] [089/133] (602s left) kms_addfb_basic (invalid-set-prop)
[416.242541] Starting subtest: invalid-set-prop
[416.244815] Subtest invalid-set-prop: SUCCESS (0.000s)
[416.417480] [090/133] (602s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[416.517804] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[416.522151] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[416.636341] [091/133] (602s left) kms_hdr (static-swap)
[416.738210] Starting subtest: static-swap
[416.745049] Subtest static-swap: SKIP (0.000s)
[416.840713] [092/133] (602s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[416.888986] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[416.959823] [093/133] (602s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[417.050172] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[423.584414] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.536s)
[423.723465] [094/133] (595s left) kms_psr (fbc-pr-no-drrs)
[423.816165] Starting subtest: fbc-pr-no-drrs
[423.820567] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[423.914740] [095/133] (595s left) kms_dsc (dsc-with-bpc-formats)
[424.016607] Starting subtest: dsc-with-bpc-formats
[424.129115] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[424.167168] [096/133] (595s left) gem_partial_pwrite_pread (write-snoop)
[424.238512] Subtest write-snoop: SKIP (0.000s)
[424.334589] [097/133] (594s left) kms_atomic_interruptible (atomic-setmode)
[424.421032] Starting subtest: atomic-setmode
[424.423371] Starting dynamic subtest: pipe-A-eDP-1
[431.337553] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.917s)
[431.339704] Subtest atomic-setmode: SUCCESS (6.919s)
[432.212365] [098/133] (587s left) kms_colorop (plane-xr30-xr30-ctm_3x4_bt709_enc)
[432.351948] Subtest plane-XR30-XR30-ctm_3x4_bt709_enc: SKIP (0.000s)
[432.446101] [099/133] (586s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[432.551250] Starting subtest: nonblocking-crc-frame-sequence
[432.553571] Starting dynamic subtest: pipe-A-eDP-1
[433.288379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[433.299634] Starting dynamic subtest: pipe-B-eDP-1
[434.700976] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.411s)
[434.711918] Starting dynamic subtest: pipe-C-eDP-1
[436.097924] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s)
[436.108577] Starting dynamic subtest: pipe-D-eDP-1
[437.427113] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[437.429282] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.877s)
[438.236282] [100/133] (581s left) gem_ctx_persistence (heartbeat-hang)
[438.302905] Starting subtest: heartbeat-hang
[438.307430] Subtest heartbeat-hang: SKIP (0.000s)
[438.446662] [101/133] (580s left) kms_plane_alpha_blend (constant-alpha-mid)
[438.534689] Starting subtest: constant-alpha-mid
[438.805027] Starting dynamic subtest: pipe-A-eDP-1
[439.675486] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.868s)
[439.685689] Starting dynamic subtest: pipe-D-eDP-1
[441.196027] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.521s)
[441.198467] Subtest constant-alpha-mid: SUCCESS (2.664s)
[442.115565] [102/133] (577s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[442.206325] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[442.208582] Starting dynamic subtest: pipe-A-eDP-1
[442.231967] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[442.244580] Starting dynamic subtest: pipe-B-eDP-1
[442.266276] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[442.275007] Starting dynamic subtest: pipe-C-eDP-1
[442.295535] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[442.304224] Starting dynamic subtest: pipe-D-eDP-1
[442.325152] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[442.329186] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[444.351762] [103/133] (575s left) api_intel_bb (reset-bb)
[444.407230] Starting subtest: reset-bb
[444.411968] Subtest reset-bb: SUCCESS (0.005s)
[444.538500] [104/133] (575s left) dmabuf_sync_file (export-before-signal)
[444.578183] Starting subtest: export-before-signal
[444.580565] Subtest export-before-signal: SUCCESS (0.001s)
[444.666782] [105/133] (574s left) perf_pmu (busy-idle-check-all)
[444.709254] Starting subtest: busy-idle-check-all
[444.711446] Starting dynamic subtest: rcs0
[445.377961] Dynamic subtest rcs0: SUCCESS (0.672s)
[445.390883] Starting dynamic subtest: bcs0
[446.029062] Dynamic subtest bcs0: SUCCESS (0.648s)
[446.041799] Starting dynamic subtest: vcs0
[447.891096] Dynamic subtest vcs0: SUCCESS (1.859s)
[447.903757] Starting dynamic subtest: vcs1
[449.939352] Dynamic subtest vcs1: SUCCESS (2.046s)
[449.951223] Starting dynamic subtest: vecs0
[451.924203] Dynamic subtest vecs0: SUCCESS (1.981s)
[451.939159] Starting dynamic subtest: ccs0
[452.583473] Dynamic subtest ccs0: SUCCESS (0.658s)
[452.585941] Subtest busy-idle-check-all: SUCCESS (7.881s)
[452.720861] [106/133] (566s left) gem_tiled_blits (interruptible)
[452.783851] Subtest interruptible: SKIP (0.000s)
[452.874748] [107/133] (566s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[452.925421] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[452.930142] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[453.119487] [108/133] (566s left) i915_query (query-topology-known-pci-ids)
[453.182192] Starting subtest: query-topology-known-pci-ids
[453.186626] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[453.314998] [109/133] (566s left) sysfs_heartbeat_interval (mixed)
[453.384171] Starting subtest: mixed
[453.386417] Starting dynamic subtest: vecs0
[458.684673] Dynamic subtest vecs0: SUCCESS (5.300s)
[458.696581] Starting dynamic subtest: ccs0
[463.886088] Dynamic subtest ccs0: SUCCESS (5.199s)
[463.897052] Starting dynamic subtest: vcs0
[469.123431] Dynamic subtest vcs0: SUCCESS (5.238s)
[469.135231] Starting dynamic subtest: bcs0
[474.335192] Dynamic subtest bcs0: SUCCESS (5.210s)
[474.347807] Starting dynamic subtest: rcs0
[479.568347] Dynamic subtest rcs0: SUCCESS (5.232s)
[479.578590] Starting dynamic subtest: vcs1
[484.777086] Dynamic subtest vcs1: SUCCESS (5.208s)
[484.779762] Subtest mixed: SUCCESS (31.400s)
[484.882253] [110/133] (534s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[484.990247] Starting subtest: 2x-plain-flip-ts-check-interruptible
[485.002384] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[485.101515] [111/133] (534s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[485.196307] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[485.200605] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[485.313210] [112/133] (534s left) syncobj_timeline (signal)
[485.360822] Starting subtest: signal
[485.363123] Subtest signal: SUCCESS (0.000s)
[485.466908] [113/133] (534s left) i915_suspend (basic-s3-without-i915)
[485.501613] Starting subtest: basic-s3-without-i915
[489.105182] Subtest basic-s3-without-i915: SKIP (3.580s)
[490.585901] [114/133] (529s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[490.717285] Starting subtest: cursorB-vs-flipB-legacy
[490.721694] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[490.883285] [115/133] (529s left) api_intel_allocator (execbuf-with-allocator)
[490.937596] Starting subtest: execbuf-with-allocator
[490.943714] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[491.124163] [116/133] (528s left) kms_psr (psr2-sprite-plane-onoff)
[491.218117] Starting subtest: psr2-sprite-plane-onoff
[491.220347] Starting dynamic subtest: eDP-1
[492.962542] Dynamic subtest eDP-1: SUCCESS (1.744s)
[492.964502] Subtest psr2-sprite-plane-onoff: SUCCESS (1.745s)
[493.830815] [117/133] (526s left) gem_render_linear_blits (basic)
[493.896474] Starting subtest: basic
[493.919317] Subtest basic: SUCCESS (0.023s)
[494.021500] [118/133] (526s left) gem_exec_balancer (semaphore)
[494.082311] Starting subtest: semaphore
[495.872864] Subtest semaphore: SUCCESS (1.789s)
[496.236291] [119/133] (523s left) kms_colorop (plane-xr30-xr30-bypass)
[496.367331] Subtest plane-XR30-XR30-bypass: SKIP (0.000s)
[496.447962] [120/133] (523s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[496.513566] Starting subtest: multi-wait-for-submit-available-signaled
[496.516096] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s)
[496.636508] [121/133] (523s left) prime_vgem (fence-wait)
[496.725881] Starting subtest: fence-wait
[496.728089] Starting dynamic subtest: rcs0
[497.779860] Dynamic subtest rcs0: SUCCESS (1.053s)
[497.791040] Starting dynamic subtest: bcs0
[498.868181] Dynamic subtest bcs0: SUCCESS (1.085s)
[498.876673] Starting dynamic subtest: vcs0
[499.911424] Dynamic subtest vcs0: SUCCESS (1.044s)
[499.920556] Starting dynamic subtest: vcs1
[500.953909] Dynamic subtest vcs1: SUCCESS (1.041s)
[500.963999] Starting dynamic subtest: vecs0
[501.994078] Dynamic subtest vecs0: SUCCESS (1.038s)
[502.005856] Starting dynamic subtest: ccs0
[503.038270] Dynamic subtest ccs0: SUCCESS (1.041s)
[503.040956] Subtest fence-wait: SUCCESS (6.313s)
[503.196787] [122/133] (517s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[503.293113] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[503.295366] Starting dynamic subtest: pipe-A-default-mode
[506.043694] Dynamic subtest pipe-A-default-mode: SUCCESS (2.752s)
[506.046163] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.753s)
[506.910412] [123/133] (513s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[507.002014] Starting subtest: fbc-psr-cursor-mmap-cpu
[507.004215] Starting dynamic subtest: eDP-1
[507.008137] Dynamic subtest eDP-1: SKIP (0.000s)
[507.012147] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.002s)
[507.103445] [124/133] (513s left) kms_chamelium_color (ctm-max)
[507.190230] Subtest ctm-max: SKIP (0.000s)
[507.239627] [125/133] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[507.336638] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[507.340924] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[507.477270] [126/133] (512s left) syncobj_wait (single-wait-all-submitted)
[507.538547] Starting subtest: single-wait-all-submitted
[507.638689] Subtest single-wait-all-submitted: SUCCESS (0.101s)
[507.737846] [127/133] (512s left) gem_create (create-invalid-size)
[507.796581] Starting subtest: create-invalid-size
[507.798568] Subtest create-invalid-size: SUCCESS (0.000s)
[508.001782] [128/133] (512s left) gem_vm_create (execbuf)
[508.054737] Starting subtest: execbuf
[508.061631] Subtest execbuf: SUCCESS (0.007s)
[508.198275] [129/133] (512s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[508.255510] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[508.257857] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[508.374674] [130/133] (512s left) gem_mmap_wc (write-read)
[508.430790] Subtest write-read: SKIP (0.000s)
[508.490821] [131/133] (512s left) kms_vblank (ts-continuation-modeset-rpm)
[508.587874] Starting subtest: ts-continuation-modeset-rpm
[508.590101] Starting dynamic subtest: pipe-A-eDP-1
[511.107270] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.518s)
[511.123238] Starting dynamic subtest: pipe-D-eDP-1
[513.348554] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.238s)
[513.350756] Subtest ts-continuation-modeset-rpm: SUCCESS (4.760s)
[514.163592] [132/133] (506s left) kms_pm_backlight (fade-with-dpms)
[514.262330] Starting subtest: fade-with-dpms
[516.416102] Starting dynamic subtest: eDP-1
[519.100600] Dynamic subtest eDP-1: SUCCESS (2.684s)
[519.102546] Subtest fade-with-dpms: SUCCESS (4.839s)
[519.901726] [133/133] (500s left) gem_busy (extended)
[520.088338] Starting subtest: extended
[520.090949] Starting dynamic subtest: rcs0
[520.181680] Dynamic subtest rcs0: SUCCESS (0.094s)
[520.193516] Starting dynamic subtest: bcs0
[520.223392] Dynamic subtest bcs0: SUCCESS (0.040s)
[520.234845] Starting dynamic subtest: vcs0
[521.940587] Dynamic subtest vcs0: SUCCESS (1.713s)
[521.952686] Starting dynamic subtest: vcs1
[523.924310] Dynamic subtest vcs1: SUCCESS (1.981s)
[523.936673] Starting dynamic subtest: vecs0
[525.908304] Dynamic subtest vecs0: SUCCESS (1.981s)
[525.920625] Starting dynamic subtest: ccs0
[525.954079] Dynamic subtest ccs0: SUCCESS (0.044s)
[525.956470] Subtest extended: SUCCESS (5.868s)
[526.220103] Closing watchdogs
results: parsing output: 0/ for test: kms_prop_blob
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: kms_color
results: parsing output: 3/ for test: gem_exec_reloc
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: i915_hangman
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: gem_tiled_wb
results: parsing output: 8/ for test: kms_plane_scaling
results: parsing output: 9/ for test: gem_mmap_wc
results: parsing output: 10/ for test: kms_busy
results: parsing output: 11/ for test: drm_read
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_psr2_sf
results: parsing output: 14/ for test: i915_pm_rpm
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_color
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: gem_ccs
results: parsing output: 19/ for test: gem_ctx_shared
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: kms_content_protection
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_chamelium_frames
results: parsing output: 25/ for test: kms_addfb_basic
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_vrr
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_async_flips
results: parsing output: 33/ for test: gem_mmap_wc
results: parsing output: 34/ for test: sw_sync
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_scaling_modes
results: parsing output: 38/ for test: gem_render_copy
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: perf
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: prime_mmap_coherency
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_cursor_edge_walk
results: parsing output: 45/ for test: kms_async_flips
results: parsing output: 46/ for test: gem_exec_schedule
results: parsing output: 47/ for test: gem_mmap_wc
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_wait
results: parsing output: 50/ for test: gem_exec_reloc
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_cursor_crc
results: parsing output: 53/ for test: kms_plane_scaling
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: gem_ctx_exec
results: parsing output: 56/ for test: kms_pm_rpm
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: perf
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: i915_drm_fdinfo
results: parsing output: 63/ for test: kms_psr2_sf
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_invalid_mode
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_draw_crc
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_pread
results: parsing output: 75/ for test: gem_mmap_offset
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_spin_batch
results: parsing output: 78/ for test: gem_reg_read
results: parsing output: 79/ for test: gen9_exec_parse
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_exec_whisper
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_addfb_basic
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_exec_async
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_addfb_basic
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_hdr
results: parsing output: 91/ for test: kms_pipe_b_c_ivb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_dsc
results: parsing output: 95/ for test: gem_partial_pwrite_pread
results: parsing output: 96/ for test: kms_atomic_interruptible
results: parsing output: 97/ for test: kms_colorop
results: parsing output: 98/ for test: kms_pipe_crc_basic
results: parsing output: 99/ for test: gem_ctx_persistence
results: parsing output: 100/ for test: kms_plane_alpha_blend
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: api_intel_bb
results: parsing output: 103/ for test: dmabuf_sync_file
results: parsing output: 104/ for test: perf_pmu
results: parsing output: 105/ for test: gem_tiled_blits
results: parsing output: 106/ for test: gem_render_copy
results: parsing output: 107/ for test: i915_query
results: parsing output: 108/ for test: sysfs_heartbeat_interval
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: i915_suspend
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: api_intel_allocator
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: gem_render_linear_blits
results: parsing output: 117/ for test: gem_exec_balancer
results: parsing output: 118/ for test: kms_colorop
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: prime_vgem
results: parsing output: 121/ for test: kms_flip_scaled_crc
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_chamelium_color
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: gem_create
results: parsing output: 127/ for test: gem_vm_create
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: gem_mmap_wc
results: parsing output: 130/ for test: kms_vblank
results: parsing output: 131/ for test: kms_pm_backlight
results: parsing output: 132/ for test: gem_busy
Done.