[57.960976] Initializing watchdogs
[57.961670]   /dev/watchdog0
[57.977546] [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]
[57.982547] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.984886] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.988203] [001/156] (960s left) kms_vblank (ts-continuation-idle-hang)
[58.110742] Starting subtest: ts-continuation-idle-hang
[58.112915] Starting dynamic subtest: pipe-A-eDP-1
[86.083111] Dynamic subtest pipe-A-eDP-1: SUCCESS (28.150s)
[86.096832] Starting dynamic subtest: pipe-D-eDP-1
[111.051787] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.962s)
[111.054061] Subtest ts-continuation-idle-hang: SUCCESS (53.115s)
[111.879525] [002/156] (906s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[112.000619] Starting subtest: flip-vs-cursor-crc-legacy
[112.717701] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.715s)
[113.622965] [003/156] (904s left) perf_pmu (init-sema)
[113.665366] Starting subtest: init-sema
[113.667704] Starting dynamic subtest: rcs0
[113.670100] Dynamic subtest rcs0: SUCCESS (0.002s)
[113.680081] Starting dynamic subtest: bcs0
[113.682096] Dynamic subtest bcs0: SUCCESS (0.000s)
[113.689597] Starting dynamic subtest: vcs0
[113.691492] Dynamic subtest vcs0: SUCCESS (0.000s)
[113.699023] Starting dynamic subtest: vcs1
[113.700898] Dynamic subtest vcs1: SUCCESS (0.001s)
[113.708327] Starting dynamic subtest: vecs0
[113.710215] Dynamic subtest vecs0: SUCCESS (0.001s)
[113.717809] Starting dynamic subtest: ccs0
[113.719634] Dynamic subtest ccs0: SUCCESS (0.001s)
[113.721527] Subtest init-sema: SUCCESS (0.007s)
[113.787673] [004/156] (904s left) gem_mmap_wc (read)
[113.844254] Subtest read: SKIP (0.000s)
[113.900527] [005/156] (904s left) gem_exec_capture (capture)
[113.961983] Starting subtest: capture
[113.964540] Starting dynamic subtest: rcs0-smem
[114.781328] Dynamic subtest rcs0-smem: SUCCESS (0.817s)
[114.790719] Starting dynamic subtest: bcs0-smem
[115.594066] Dynamic subtest bcs0-smem: SUCCESS (0.812s)
[115.603023] Starting dynamic subtest: vcs0-smem
[116.390404] Dynamic subtest vcs0-smem: SUCCESS (0.796s)
[116.401033] Starting dynamic subtest: vcs1-smem
[117.197325] Dynamic subtest vcs1-smem: SUCCESS (0.808s)
[117.208036] Starting dynamic subtest: vecs0-smem
[118.590726] Dynamic subtest vecs0-smem: FAIL (1.331s)
[118.600320] Starting dynamic subtest: ccs0-smem
[119.848780] Dynamic subtest ccs0-smem: SUCCESS (1.318s)
[119.851255] Subtest capture: FAIL (5.884s)
[119.980760] [006/156] (898s left) kms_psr (psr2-primary-blt)
[120.079927] Starting subtest: psr2-primary-blt
[120.082390] Starting dynamic subtest: eDP-1
[121.793228] Dynamic subtest eDP-1: SUCCESS (1.714s)
[121.795591] Subtest psr2-primary-blt: SUCCESS (1.715s)
[122.673941] [007/156] (896s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-blt)
[122.774259] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-blt
[122.778101] Subtest hdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[122.874892] [008/156] (895s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[122.968490] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[122.973092] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[123.053420] [009/156] (895s left) gem_tiled_swapping (non-threaded)
[123.117383] Subtest non-threaded: SKIP (0.000s)
[123.173340] [010/156] (895s left) kms_hdr (static-toggle)
[123.267149] Starting subtest: static-toggle
[123.269532] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[123.273727] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[123.283044] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[123.287429] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[123.291927] Subtest static-toggle: SKIP (0.002s)
[123.335384] [011/156] (895s left) gem_mmap_offset (ptrace)
[123.387794] Starting subtest: ptrace
[123.390105] Starting dynamic subtest: smem0-wb
[123.401511] Dynamic subtest smem0-wb: SUCCESS (0.012s)
[123.412524] Starting dynamic subtest: smem0-wc
[123.422802] Dynamic subtest smem0-wc: SUCCESS (0.021s)
[123.430875] Starting dynamic subtest: smem0-uc
[123.440632] Dynamic subtest smem0-uc: SUCCESS (0.017s)
[123.443093] Subtest ptrace: SUCCESS (0.055s)
[123.564064] [012/156] (895s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[123.666916] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[123.668951] Starting dynamic subtest: pipe-A
[124.827766] Dynamic subtest pipe-A: SUCCESS (1.162s)
[124.836818] Starting dynamic subtest: pipe-B
[127.049025] Dynamic subtest pipe-B: SUCCESS (2.221s)
[127.058255] Starting dynamic subtest: pipe-C
[128.266196] Dynamic subtest pipe-C: SUCCESS (1.216s)
[128.277075] Starting dynamic subtest: pipe-D
[129.492440] Dynamic subtest pipe-D: SUCCESS (1.226s)
[129.494907] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.826s)
[130.690585] [013/156] (888s left) gen3_render_linear_blits
[130.808783] [014/156] (888s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[130.918519] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[130.922797] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[131.019783] [015/156] (888s left) kms_pm_backlight (fade-with-dpms)
[131.112425] Starting subtest: fade-with-dpms
[133.264247] Starting dynamic subtest: eDP-1
[136.273672] Dynamic subtest eDP-1: SUCCESS (3.009s)
[136.276131] Subtest fade-with-dpms: SUCCESS (5.163s)
[137.147062] [016/156] (881s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[137.246242] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[137.250546] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[137.352387] [017/156] (881s left) gem_softpin (allocator-evict)
[137.406784] Starting subtest: allocator-evict
[137.409259] Starting dynamic subtest: rcs0
[161.105377] Dynamic subtest rcs0: SUCCESS (23.697s)
[161.117635] Starting dynamic subtest: bcs0
[184.781527] Dynamic subtest bcs0: SUCCESS (23.675s)
[184.790465] Starting dynamic subtest: vcs0
[208.763043] Dynamic subtest vcs0: SUCCESS (23.979s)
[208.772475] Starting dynamic subtest: vcs1
[232.828083] Dynamic subtest vcs1: SUCCESS (24.064s)
[232.837286] Starting dynamic subtest: vecs0
[256.483115] Dynamic subtest vecs0: SUCCESS (23.654s)
[256.492748] Starting dynamic subtest: ccs0
[280.114742] Dynamic subtest ccs0: SUCCESS (23.630s)
[280.117902] Subtest allocator-evict: SUCCESS (142.702s)
[280.198231] [018/156] (738s left) gem_mmap_wc (copy)
[280.307420] Subtest copy: SKIP (0.000s)
[280.373359] [019/156] (738s left) gem_mmap_gtt (close-race)
[280.430321] Subtest close-race: SKIP (0.000s)
[280.491355] [020/156] (738s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[280.601973] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[288.076166] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.480s)
[288.200940] [021/156] (731s left) kms_lease (lessee-list)
[288.295330] Starting subtest: lessee-list
[288.297474] Starting dynamic subtest: pipe-A-eDP-1
[288.299749] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[288.308810] Starting dynamic subtest: pipe-B-eDP-1
[288.310615] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[288.319102] Starting dynamic subtest: pipe-C-eDP-1
[288.320896] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[288.329124] Starting dynamic subtest: pipe-D-eDP-1
[288.330996] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[288.332805] Subtest lessee-list: SUCCESS (0.010s)
[288.415121] [022/156] (730s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[288.504991] Starting subtest: primary-x-tiled-reflect-x-0
[295.458569] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.956s)
[296.629416] [023/156] (722s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[296.691851] Starting subtest: wait-all-for-submit-delayed-submit
[296.795211] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[296.882780] [024/156] (722s left) kms_flip (flip-vs-fences)
[296.978164] Starting subtest: flip-vs-fences
[296.982507] Subtest flip-vs-fences: SKIP (0.000s)
[297.085063] [025/156] (722s left) gem_eio (context-create)
[297.326169] Starting subtest: context-create
[297.668984] Subtest context-create: SUCCESS (0.342s)
[297.782236] [026/156] (721s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[297.889521] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[297.892876] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[297.992900] [027/156] (721s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[298.084071] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[298.087464] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[298.172469] [028/156] (721s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[298.261698] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[298.263669] Starting dynamic subtest: pipe-A-default-mode
[300.958272] Dynamic subtest pipe-A-default-mode: SUCCESS (2.695s)
[300.960530] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.696s)
[301.835625] [029/156] (717s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[301.931375] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[301.943254] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[302.047918] [030/156] (717s left) kms_pm_rpm (fences-dpms)
[303.605320] Starting subtest: fences-dpms
[303.610673] Subtest fences-dpms: SKIP (0.000s)
[304.383261] [031/156] (715s left) gem_exec_nop (basic-series)
[304.454132] Starting subtest: basic-series
[320.323976] Subtest basic-series: SUCCESS (15.876s)
[320.554185] [032/156] (699s left) fbdev (nullptr)
[320.604041] Starting subtest: nullptr
[320.606337] Subtest nullptr: SUCCESS (0.000s)
[320.662008] [033/156] (699s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[320.765086] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[320.769190] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[320.831321] [034/156] (698s left) kms_prime (basic-crc-vgem)
[320.967904] Starting subtest: basic-crc-vgem
[320.970377] Starting dynamic subtest: second-to-first
[321.948829] Dynamic subtest second-to-first: SUCCESS (0.983s)
[321.950789] Subtest basic-crc-vgem: SUCCESS (0.983s)
[322.045801] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.vgem: true
[322.049096] [035/156] (697s left) kms_flip (flip-vs-suspend-interruptible)
[322.173580] Starting subtest: flip-vs-suspend-interruptible
[322.175697] Starting dynamic subtest: A-eDP1
[467.739105] Dynamic subtest A-eDP1: SUCCESS (11.429s)
[467.741585] Subtest flip-vs-suspend-interruptible: SUCCESS (11.431s)
[467.875878] [036/156] (552s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[467.979307] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[467.981619] Starting dynamic subtest: pipe-A-eDP-1
[468.003935] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[468.015993] Starting dynamic subtest: pipe-B-eDP-1
[468.036113] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[468.046444] Starting dynamic subtest: pipe-C-eDP-1
[468.065462] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[468.075526] Starting dynamic subtest: pipe-D-eDP-1
[468.096621] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[468.100815] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[470.189579] [037/156] (549s left) kms_addfb_basic (addfb25-bad-modifier)
[470.282296] Starting subtest: addfb25-bad-modifier
[470.284634] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[470.380191] [038/156] (549s left) syncobj_timeline (device-submit-unordered)
[470.430458] Starting subtest: device-submit-unordered
[470.432782] Subtest device-submit-unordered: SUCCESS (0.001s)
[470.546834] [039/156] (549s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[470.651177] Starting subtest: 4-tiled-16bpp-rotate-90
[470.670581] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.020s)
[470.773767] [040/156] (549s left) gem_exec_store (pages)
[470.831922] Starting subtest: pages
[470.834079] Starting dynamic subtest: rcs0
[470.910620] Dynamic subtest rcs0: SUCCESS (0.082s)
[470.924191] Starting dynamic subtest: bcs0
[470.980757] Dynamic subtest bcs0: SUCCESS (0.071s)
[470.992442] Starting dynamic subtest: vcs0
[471.048507] Dynamic subtest vcs0: SUCCESS (0.069s)
[471.059823] Starting dynamic subtest: vcs1
[471.119434] Dynamic subtest vcs1: SUCCESS (0.073s)
[471.131087] Starting dynamic subtest: vecs0
[471.183010] Dynamic subtest vecs0: SUCCESS (0.064s)
[471.194625] Starting dynamic subtest: ccs0
[471.255347] Dynamic subtest ccs0: SUCCESS (0.074s)
[471.257605] Subtest pages: SUCCESS (0.438s)
[471.441024] [041/156] (548s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[471.544493] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[471.548690] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[471.644174] [042/156] (548s left) gem_close_race (basic-threads)
[471.701381] Starting subtest: basic-threads
[473.037106] Subtest basic-threads: SUCCESS (1.369s)
[473.220056] [043/156] (546s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[473.313495] Starting subtest: bad-aux-stride-y-tiled-ccs
[473.315739] Starting dynamic subtest: pipe-A-eDP-1
[473.337650] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[473.348631] Starting dynamic subtest: pipe-B-eDP-1
[473.369104] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[473.378477] Starting dynamic subtest: pipe-C-eDP-1
[473.399705] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[473.409184] Starting dynamic subtest: pipe-D-eDP-1
[473.430649] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[473.434956] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[475.547184] [044/156] (544s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[475.929988] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[476.732597] [045/156] (543s left) gem_partial_pwrite_pread (write-display)
[476.792512] Subtest write-display: SKIP (0.000s)
[476.868731] [046/156] (543s left) kms_invalid_mode (bad-vsync-start)
[476.965733] Starting subtest: bad-vsync-start
[476.968002] Starting dynamic subtest: pipe-A-eDP-1
[477.246570] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[477.257046] Starting dynamic subtest: pipe-B-eDP-1
[477.258934] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[477.268257] Starting dynamic subtest: pipe-C-eDP-1
[477.270790] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[477.280357] Starting dynamic subtest: pipe-D-eDP-1
[477.282548] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[477.284464] Subtest bad-vsync-start: SUCCESS (0.294s)
[478.062591] [047/156] (542s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[478.121130] Starting subtest: x-tiled-to-vebox-y-tiled
[478.125796] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[478.306057] [048/156] (542s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[478.399809] Starting subtest: dsc-fractional-bpp-with-bpc
[478.429624] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[478.470934] [049/156] (541s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[478.575349] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[485.913538] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.393s)
[486.047654] [050/156] (534s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[486.160168] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[486.164664] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[486.272103] [051/156] (534s left) gem_exec_reloc (basic-cpu-noreloc)
[486.341713] Subtest basic-cpu-noreloc: SKIP (0.000s)
[486.416145] [052/156] (534s left) gem_userptr_blits (userfault)
[486.480773] Starting subtest: userfault
[486.482942] Subtest userfault: SUCCESS (0.007s)
[486.632147] [053/156] (533s left) perf (global-sseu-config-invalid)
[486.783430] Starting subtest: global-sseu-config-invalid
[486.788004] Subtest global-sseu-config-invalid: SKIP (0.000s)
[486.828124] [054/156] (533s left) gem_exercise_blt (fast-copy)
[486.884319] Starting subtest: fast-copy
[486.886300] Starting dynamic subtest: linear-smem-smem
[486.908037] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[486.918203] Starting dynamic subtest: xmajor-smem-smem
[486.926226] Dynamic subtest xmajor-smem-smem: SUCCESS (0.018s)
[486.936005] Starting dynamic subtest: tile4-smem-smem
[486.946672] Dynamic subtest tile4-smem-smem: SUCCESS (0.020s)
[486.956600] Starting dynamic subtest: tile64-smem-smem
[486.965311] Dynamic subtest tile64-smem-smem: SUCCESS (0.018s)
[486.967337] Subtest fast-copy: SUCCESS (0.082s)
[487.077826] [055/156] (533s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[487.180839] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[487.184191] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[487.252610] [056/156] (533s left) kms_flip (2x-wf_vblank-ts-check)
[487.343737] Starting subtest: 2x-wf_vblank-ts-check
[487.355214] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[487.434472] [057/156] (533s left) vgem_basic (dmabuf-mmap)
[487.476819] Starting subtest: dmabuf-mmap
[487.489625] Subtest dmabuf-mmap: SUCCESS (0.016s)
[487.559378] [058/156] (533s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[487.657349] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[487.660749] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[487.759388] [059/156] (533s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-render)
[487.862077] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-render
[487.865537] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[487.981694] [060/156] (532s left) gem_ctx_engines (independent)
[488.050711] Starting subtest: independent
[488.052879] Starting dynamic subtest: rcs0
[488.194828] Dynamic subtest rcs0: SUCCESS (0.142s)
[488.209477] Starting dynamic subtest: bcs0
[488.330496] Dynamic subtest bcs0: SUCCESS (0.136s)
[488.341593] Starting dynamic subtest: vcs0
[488.459052] Dynamic subtest vcs0: SUCCESS (0.127s)
[488.472114] Starting dynamic subtest: vcs1
[488.583804] Dynamic subtest vcs1: SUCCESS (0.124s)
[488.594598] Starting dynamic subtest: vecs0
[488.718371] Dynamic subtest vecs0: SUCCESS (0.134s)
[488.731315] Starting dynamic subtest: ccs0
[488.837763] Dynamic subtest ccs0: SUCCESS (0.119s)
[488.851062] Starting dynamic subtest: all-engines
[489.354369] Dynamic subtest all-engines: SUCCESS (0.518s)
[489.356798] Subtest independent: SUCCESS (1.307s)
[489.652573] [061/156] (531s left) gem_ctx_persistence (legacy-engines-cleanup)
[489.709927] Starting subtest: legacy-engines-cleanup
[489.712249] Starting dynamic subtest: render
[489.780350] Dynamic subtest render: SUCCESS (0.069s)
[489.800671] Starting dynamic subtest: bsd1
[489.834987] Dynamic subtest bsd1: SUCCESS (0.051s)
[489.847733] Starting dynamic subtest: bsd2
[489.879069] Dynamic subtest bsd2: SUCCESS (0.041s)
[489.891388] Starting dynamic subtest: blt
[489.900714] Dynamic subtest blt: SUCCESS (0.019s)
[489.910896] Starting dynamic subtest: vebox
[489.971016] Dynamic subtest vebox: SUCCESS (0.069s)
[489.973564] Subtest legacy-engines-cleanup: SUCCESS (0.263s)
[490.054716] [062/156] (530s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[490.108714] Starting subtest: y-tiled-to-vebox-yf-tiled
[490.112869] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[490.297377] [063/156] (530s left) kms_content_protection (uevent-hdcp14)
[490.447773] Starting subtest: uevent-hdcp14
[490.452456] Subtest uevent-hdcp14: SKIP (0.000s)
[490.567898] [064/156] (530s left) syncobj_timeline (invalid-signal-illegal-handle)
[490.616250] Starting subtest: invalid-signal-illegal-handle
[490.618599] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[490.738929] [065/156] (530s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[490.831451] Starting subtest: fbcpsr-rgb565-draw-blt
[497.938381] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.123s)
[498.083553] [066/156] (523s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-move)
[498.195995] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-move
[498.199986] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[498.300000] [067/156] (522s left) kms_psr (pr-primary-blt)
[498.382785] Starting subtest: pr-primary-blt
[498.386636] Subtest pr-primary-blt: SKIP (0.000s)
[498.471513] [068/156] (522s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[498.590882] Starting subtest: basic-flip-after-cursor-varying-size
[499.539752] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.947s)
[500.368706] [069/156] (520s left) kms_psr (fbc-psr2-primary-blt)
[500.466067] Starting subtest: fbc-psr2-primary-blt
[500.468303] Starting dynamic subtest: eDP-1
[500.472509] Dynamic subtest eDP-1: SKIP (0.000s)
[500.477155] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[500.556630] [070/156] (520s left) kms_psr (fbc-psr-cursor-render)
[500.652779] Starting subtest: fbc-psr-cursor-render
[500.654953] Starting dynamic subtest: eDP-1
[500.658991] Dynamic subtest eDP-1: SKIP (0.000s)
[500.662979] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[500.727487] [071/156] (520s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[500.833974] Starting subtest: x-tiled-8bpp-rotate-0
[502.048475] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.216s)
[502.175011] [072/156] (519s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[502.232802] Subtest no-spare-fences-busy: SKIP (0.000s)
[502.305217] [073/156] (519s left) vgem_basic (bad-fence)
[502.351848] Starting subtest: bad-fence
[502.353683] Subtest bad-fence: SUCCESS (0.000s)
[502.407215] [074/156] (519s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[502.514037] Starting subtest: plane-ctm3x4-lut1d
[502.690934] Starting dynamic subtest: pipe-A-plane-0
[502.867428] Dynamic subtest pipe-A-plane-0: SUCCESS (0.176s)
[502.878354] Starting dynamic subtest: pipe-A-plane-1
[503.034362] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[503.044731] Starting dynamic subtest: pipe-A-plane-2
[503.223280] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[504.505505] Starting dynamic subtest: pipe-B-plane-0
[504.696727] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[504.707832] Starting dynamic subtest: pipe-B-plane-1
[504.854208] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[504.866040] Starting dynamic subtest: pipe-B-plane-2
[505.041375] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[506.199285] Starting dynamic subtest: pipe-C-plane-0
[506.334151] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[506.345463] Starting dynamic subtest: pipe-C-plane-1
[506.489710] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[506.500648] Starting dynamic subtest: pipe-C-plane-2
[506.645238] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[507.823213] Starting dynamic subtest: pipe-D-plane-0
[507.958816] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[507.969606] Starting dynamic subtest: pipe-D-plane-1
[508.114954] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[508.126138] Starting dynamic subtest: pipe-D-plane-2
[508.272246] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[508.559044] Subtest plane-ctm3x4-lut1d: SUCCESS (6.048s)
[509.377755] [075/156] (512s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[509.484511] Starting subtest: fbc-rgb565-draw-mmap-wc
[513.509746] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.031s)
[514.693140] [076/156] (506s left) i915_suspend (debugfs-reader)
[514.741234] Starting subtest: debugfs-reader
[532.713303] Subtest debugfs-reader: SUCCESS (3.190s)
[532.830263] [077/156] (488s left) api_intel_bb (crc32)
[532.894038] Starting subtest: crc32
[532.896251] Starting dynamic subtest: rcs0-smem0
[533.185170] Dynamic subtest rcs0-smem0: SUCCESS (0.304s)
[533.197759] Starting dynamic subtest: bcs0-smem0
[533.493798] Dynamic subtest bcs0-smem0: SUCCESS (0.317s)
[533.505166] Starting dynamic subtest: vcs0-smem0
[533.872978] Dynamic subtest vcs0-smem0: SUCCESS (0.390s)
[533.885385] Starting dynamic subtest: vcs1-smem0
[534.251796] Dynamic subtest vcs1-smem0: SUCCESS (0.389s)
[534.264094] Starting dynamic subtest: vecs0-smem0
[534.549864] Dynamic subtest vecs0-smem0: SUCCESS (0.304s)
[534.563394] Starting dynamic subtest: ccs0-smem0
[534.893032] Dynamic subtest ccs0-smem0: SUCCESS (0.351s)
[534.896196] Subtest crc32: SUCCESS (2.071s)
[535.009772] [078/156] (486s left) kms_sequence (get-forked-busy)
[535.103078] Starting subtest: get-forked-busy
[535.105155] Starting dynamic subtest: pipe-A-eDP-1
[536.990145] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.937s)
[537.001273] Starting dynamic subtest: pipe-B-eDP-1
[539.588367] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.644s)
[539.599516] Starting dynamic subtest: pipe-C-eDP-1
[542.170925] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.616s)
[542.183153] Starting dynamic subtest: pipe-D-eDP-1
[544.773189] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.630s)
[544.776089] Subtest get-forked-busy: SUCCESS (9.832s)
[545.647234] [079/156] (476s left) gem_fenced_exec_thrash (2-spare-fences)
[545.709395] Subtest 2-spare-fences: SKIP (0.000s)
[545.781867] [080/156] (476s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[545.889494] Starting subtest: cursor-tearing-framebuffer-change
[545.892553] Starting dynamic subtest: pipe-A-eDP-1
[546.453997] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[546.466786] Starting dynamic subtest: pipe-D-eDP-1
[547.680362] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s)
[547.683270] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.803s)
[548.508285] [081/156] (473s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[548.611905] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite
[548.617229] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[548.709161] [082/156] (473s left) gem_readwrite (read-write)
[548.764825] Subtest read-write: SKIP (0.000s)
[548.832494] [083/156] (473s left) kms_properties (invalid-properties-legacy)
[548.926089] Starting subtest: invalid-properties-legacy
[549.027359] Subtest invalid-properties-legacy: SUCCESS (0.104s)
[549.125544] [084/156] (472s left) kms_addfb_basic (basic)
[549.175863] Starting subtest: basic
[549.178177] Subtest basic: SUCCESS (0.000s)
[549.315945] [085/156] (472s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-wc)
[549.422209] Starting subtest: hdr-rgb101010-draw-mmap-wc
[549.427184] Subtest hdr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[549.517312] [086/156] (472s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-onoff)
[549.620321] Starting subtest: hdr-2p-scndscrn-cur-indfb-onoff
[549.625221] Subtest hdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[549.724665] [087/156] (472s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-onoff)
[549.820163] Starting subtest: psrhdr-2p-primscrn-spr-indfb-onoff
[549.824086] Subtest psrhdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[549.910301] [088/156] (472s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-msflip-blt)
[550.009652] Starting subtest: fbchdr-2p-primscrn-indfb-msflip-blt
[550.013771] Subtest fbchdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[550.099722] [089/156] (472s left) gem_exec_parallel (contexts)
[550.162687] Starting subtest: contexts
[550.165032] Starting dynamic subtest: rcs0
[553.143038] Dynamic subtest rcs0: SUCCESS (2.989s)
[553.157081] Starting dynamic subtest: bcs0
[557.169549] Dynamic subtest bcs0: SUCCESS (4.034s)
[557.183401] Starting dynamic subtest: vcs0
[561.720308] Dynamic subtest vcs0: SUCCESS (4.556s)
[561.738146] Starting dynamic subtest: vcs1
[567.506425] Dynamic subtest vcs1: SUCCESS (5.783s)
[567.520809] Starting dynamic subtest: vecs0
[573.622193] Dynamic subtest vecs0: SUCCESS (6.060s)
[573.633543] Starting dynamic subtest: ccs0
[578.200106] Dynamic subtest ccs0: SUCCESS (4.626s)
[578.203037] Subtest contexts: SUCCESS (28.061s)
[578.391766] [090/156] (443s left) gem_exec_schedule (semaphore-codependency)
[578.475154] Starting subtest: semaphore-codependency
[578.487169] Subtest semaphore-codependency: SUCCESS (0.023s)
[578.839156] [091/156] (443s left) vgem_basic (second-client)
[578.888908] Starting subtest: second-client
[578.908286] Subtest second-client: SUCCESS (0.022s)
[578.966637] [092/156] (443s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-render)
[579.070913] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-render
[579.076142] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[579.154251] [093/156] (443s left) gem_exec_schedule (fifo)
[579.233390] Starting subtest: fifo
[579.236189] Starting dynamic subtest: rcs0
[579.319863] Dynamic subtest rcs0: SUCCESS (0.100s)
[579.331029] Starting dynamic subtest: vcs0
[579.404893] Dynamic subtest vcs0: SUCCESS (0.084s)
[579.415396] Starting dynamic subtest: vcs1
[579.492159] Dynamic subtest vcs1: SUCCESS (0.085s)
[579.505755] Starting dynamic subtest: vecs0
[579.565969] Dynamic subtest vecs0: SUCCESS (0.073s)
[579.579413] Starting dynamic subtest: ccs0
[579.656878] Dynamic subtest ccs0: SUCCESS (0.090s)
[579.659691] Subtest fifo: SUCCESS (0.438s)
[581.287729] [094/156] (441s left) kms_atomic_transition (plane-all-modeset-transition)
[581.378957] Starting subtest: plane-all-modeset-transition
[581.384321] Subtest plane-all-modeset-transition: SKIP (0.000s)
[581.470716] [095/156] (441s left) kms_chamelium_edid (vga-edid-read)
[581.860246] Subtest vga-edid-read: SKIP (0.000s)
[582.692361] [096/156] (439s left) kms_cursor_crc (cursor-random-256x85)
[582.955399] Starting subtest: cursor-random-256x85
[583.003148] Subtest cursor-random-256x85: SKIP (0.043s)
[583.147660] [097/156] (439s left) kms_flip (2x-plain-flip)
[583.238408] Starting subtest: 2x-plain-flip
[583.249639] Subtest 2x-plain-flip: SKIP (0.010s)
[583.331303] [098/156] (439s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render)
[583.436178] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render
[583.441327] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[583.532686] [099/156] (439s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[583.620088] Starting subtest: flip-vs-dpms-off-vs-modeset
[583.622608] Starting dynamic subtest: A-eDP1
[587.257865] Dynamic subtest A-eDP1: SUCCESS (3.634s)
[587.269672] Starting dynamic subtest: B-eDP1
[590.490391] Dynamic subtest B-eDP1: SUCCESS (3.234s)
[590.502590] Starting dynamic subtest: C-eDP1
[593.685815] Dynamic subtest C-eDP1: SUCCESS (3.194s)
[593.697632] Starting dynamic subtest: D-eDP1
[596.919926] Dynamic subtest D-eDP1: SUCCESS (3.234s)
[596.926379] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.305s)
[597.731710] [100/156] (424s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[597.835030] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[597.839609] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[597.937656] [101/156] (424s left) kms_busy (extended-pageflip-hang-newfb)
[598.049185] Starting subtest: extended-pageflip-hang-newfb
[598.051956] Starting dynamic subtest: pipe-A
[624.090225] Dynamic subtest pipe-A: SUCCESS (26.046s)
[624.101313] Starting dynamic subtest: pipe-D
[648.991085] Dynamic subtest pipe-D: SUCCESS (24.895s)
[648.993554] Subtest extended-pageflip-hang-newfb: SUCCESS (50.946s)
[649.905157] [102/156] (372s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-gtt)
[650.003522] Starting subtest: psrhdr-rgb565-draw-mmap-gtt
[650.008384] Subtest psrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[650.092381] [103/156] (372s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[650.214167] Starting subtest: y-tiled-32bpp-rotate-180
[650.219631] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[650.306997] [104/156] (372s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[650.361240] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[650.364194] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[650.457043] [105/156] (372s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[650.561529] Starting subtest: fbc-rgb101010-draw-blt
[653.109534] Subtest fbc-rgb101010-draw-blt: SUCCESS (2.555s)
[654.305546] [106/156] (368s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[654.396954] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[654.399383] Starting dynamic subtest: pipe-A-eDP-1
[654.409974] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[654.423895] Starting dynamic subtest: pipe-B-eDP-1
[654.434197] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[654.445437] Starting dynamic subtest: pipe-C-eDP-1
[654.456066] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[654.467019] Starting dynamic subtest: pipe-D-eDP-1
[654.477210] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[654.482372] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[656.641776] [107/156] (366s left) api_intel_bb (object-noreloc-purge-cache-simple)
[656.692750] Starting subtest: object-noreloc-purge-cache-simple
[656.696270] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[656.813334] [108/156] (366s left) gem_exec_balancer (hang)
[657.080976] Starting subtest: hang
[658.781668] Subtest hang: SUCCESS (1.704s)
[658.915799] [109/156] (364s left) gem_sync (basic-store-all)
[658.991187] Starting subtest: basic-store-all
[661.312804] Subtest basic-store-all: SUCCESS (2.316s)
[661.478827] [110/156] (361s left) kms_psr (psr-primary-page-flip)
[661.576088] Starting subtest: psr-primary-page-flip
[661.578643] Starting dynamic subtest: eDP-1
[663.348424] Dynamic subtest eDP-1: SUCCESS (1.770s)
[663.350831] Subtest psr-primary-page-flip: SUCCESS (1.771s)
[664.201190] [111/156] (359s left) gem_exec_schedule (preempt-hang)
[664.378262] Starting subtest: preempt-hang
[664.381106] Starting dynamic subtest: rcs0
[664.615143] Dynamic subtest rcs0: SUCCESS (0.237s)
[664.626698] Starting dynamic subtest: bcs0
[664.847459] Dynamic subtest bcs0: SUCCESS (0.232s)
[664.858452] Starting dynamic subtest: vcs0
[665.078605] Dynamic subtest vcs0: SUCCESS (0.230s)
[665.091186] Starting dynamic subtest: vcs1
[665.315324] Dynamic subtest vcs1: SUCCESS (0.236s)
[665.327403] Starting dynamic subtest: vecs0
[665.542169] Dynamic subtest vecs0: SUCCESS (0.226s)
[665.553636] Starting dynamic subtest: ccs0
[665.771212] Dynamic subtest ccs0: SUCCESS (0.229s)
[665.774200] Subtest preempt-hang: SUCCESS (1.395s)
[666.334054] [112/156] (356s left) kms_flip (2x-flip-vs-panning)
[666.430491] Starting subtest: 2x-flip-vs-panning
[666.439676] Subtest 2x-flip-vs-panning: SKIP (0.011s)
[666.519758] [113/156] (356s left) kms_frontbuffer_tracking (hdr-suspend)
[666.617928] Starting subtest: hdr-suspend
[666.622770] Subtest hdr-suspend: SKIP (0.000s)
[666.711862] [114/156] (356s left) syncobj_timeline (transfer-timeline-point)
[666.760306] Starting subtest: transfer-timeline-point
[666.763063] Subtest transfer-timeline-point: SUCCESS (0.000s)
[666.852235] [115/156] (356s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-move)
[666.952976] Starting subtest: hdr-1p-primscrn-cur-indfb-move
[666.958107] Subtest hdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[667.059838] [116/156] (356s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-pwrite)
[667.151328] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-pwrite
[667.156363] Subtest fbchdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[667.240867] [117/156] (356s left) kms_cursor_crc (cursor-offscreen-512x170)
[667.554971] Starting subtest: cursor-offscreen-512x170
[667.559218] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[667.714123] [118/156] (355s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-plflip-blt)
[667.814373] Starting subtest: psrhdr-1p-primscrn-indfb-plflip-blt
[667.819199] Subtest psrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[667.896797] [119/156] (355s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[667.997258] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[668.001825] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[668.095785] [120/156] (355s left) gem_ctx_persistence (engines-hostile)
[668.151057] Starting subtest: engines-hostile
[668.153553] Starting dynamic subtest: rcs0
[668.260839] Dynamic subtest rcs0: SUCCESS (0.110s)
[668.273979] Starting dynamic subtest: bcs0
[668.334027] Dynamic subtest bcs0: SUCCESS (0.071s)
[668.346306] Starting dynamic subtest: vcs0
[668.454410] Dynamic subtest vcs0: SUCCESS (0.119s)
[668.467281] Starting dynamic subtest: vcs1
[668.548999] Dynamic subtest vcs1: SUCCESS (0.092s)
[668.561012] Starting dynamic subtest: vecs0
[668.647825] Dynamic subtest vecs0: SUCCESS (0.096s)
[668.662408] Starting dynamic subtest: ccs0
[668.741569] Dynamic subtest ccs0: SUCCESS (0.091s)
[668.744298] Subtest engines-hostile: SUCCESS (0.592s)
[668.825780] [121/156] (354s left) i915_pm_rpm (debugfs-forcewake-user)
[670.347664] Starting subtest: debugfs-forcewake-user
[681.737099] Subtest debugfs-forcewake-user: SUCCESS (11.393s)
[682.128963] [122/156] (341s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt)
[682.227059] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt
[682.232303] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[682.331834] [123/156] (341s left) kms_frontbuffer_tracking (hdr-2p-rte)
[682.433391] Starting subtest: hdr-2p-rte
[682.438831] Subtest hdr-2p-rte: SKIP (0.000s)
[682.539208] [124/156] (341s left) kms_dp_link_training (uhbr-mst)
[682.629233] Starting subtest: uhbr-mst
[682.639994] Subtest uhbr-mst: SKIP (0.000s)
[682.703954] [125/156] (341s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[682.801301] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[682.806162] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[682.896093] [126/156] (340s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[682.987913] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[682.992803] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[683.089800] [127/156] (340s left) kms_plane_alpha_blend (coverage-7efc)
[683.181269] Starting subtest: coverage-7efc
[683.467928] Starting dynamic subtest: pipe-A-eDP-1
[684.050926] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.583s)
[684.089544] Starting dynamic subtest: pipe-D-eDP-1
[685.350170] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.278s)
[685.353313] Subtest coverage-7efc: SUCCESS (2.170s)
[686.192297] [128/156] (337s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[686.298366] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[686.303820] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[686.391737] [129/156] (337s left) syncobj_wait (single-wait-all-signaled)
[686.444994] Starting subtest: single-wait-all-signaled
[686.447860] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[686.546814] [130/156] (337s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[686.642898] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[686.648255] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[686.725801] [131/156] (337s left) gem_exec_fence (syncobj-invalid-wait)
[687.181292] Starting subtest: syncobj-invalid-wait
[687.184084] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[688.351144] [132/156] (335s left) kms_chamelium_color (ctm-green-to-red)
[688.456589] Subtest ctm-green-to-red: SKIP (0.000s)
[688.504149] [133/156] (335s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[688.598090] Starting subtest: plane-primary-overlay-mutable-zpos
[692.032146] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.434s)
[693.222657] [134/156] (330s left) prime_busy (hang-wait)
[693.286248] Starting subtest: hang-wait
[693.289253] Starting dynamic subtest: rcs0
[719.724394] Dynamic subtest rcs0: SUCCESS (26.433s)
[719.736975] Starting dynamic subtest: bcs0
[726.818667] Dynamic subtest bcs0: SUCCESS (7.094s)
[726.831211] Starting dynamic subtest: vcs0
[733.849010] Dynamic subtest vcs0: SUCCESS (7.035s)
[733.860416] Starting dynamic subtest: vcs1
[740.827310] Dynamic subtest vcs1: SUCCESS (6.977s)
[740.842397] Starting dynamic subtest: vecs0
[747.874456] Dynamic subtest vecs0: SUCCESS (7.037s)
[747.886404] Starting dynamic subtest: ccs0
[761.704235] Dynamic subtest ccs0: SUCCESS (13.838s)
[761.707268] Subtest hang-wait: SUCCESS (68.428s)
[761.816596] [135/156] (262s left) i915_hangman (detector)
[761.896383] Starting subtest: detector
[761.898850] Starting dynamic subtest: rcs0
[772.200672] Dynamic subtest rcs0: SUCCESS (10.300s)
[772.215335] Starting dynamic subtest: bcs0
[784.170565] Dynamic subtest bcs0: SUCCESS (11.964s)
[784.184785] Starting dynamic subtest: vcs0
[796.137300] Dynamic subtest vcs0: SUCCESS (11.962s)
[796.151384] Starting dynamic subtest: vcs1
[807.146423] Dynamic subtest vcs1: SUCCESS (11.003s)
[807.160730] Starting dynamic subtest: vecs0
[818.152822] Dynamic subtest vecs0: SUCCESS (11.003s)
[818.166863] Starting dynamic subtest: ccs0
[830.185066] Dynamic subtest ccs0: SUCCESS (12.029s)
[830.188216] Subtest detector: SUCCESS (68.279s)
[830.272707] [136/156] (194s left) dmabuf_sync_file (export-before-signal)
[830.323603] Starting subtest: export-before-signal
[830.326151] Subtest export-before-signal: SUCCESS (0.001s)
[830.380249] [137/156] (193s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[830.482521] Starting subtest: yf-tiled-16bpp-rotate-180
[830.487674] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[830.581191] [138/156] (193s left) i915_query (query-garbage)
[830.641523] Starting subtest: query-garbage
[830.644103] Subtest query-garbage: SUCCESS (0.000s)
[830.743384] [139/156] (193s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[830.836384] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[830.841985] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[830.911776] [140/156] (193s left) kms_getfb (getfb-addfb-different-handles)
[831.002219] Starting subtest: getfb-addfb-different-handles
[831.004753] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[831.103826] [141/156] (193s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[831.201592] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[831.204230] Starting dynamic subtest: pipe-A-eDP-1
[842.203085] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.007s)
[842.215664] Starting dynamic subtest: pipe-B-eDP-1
[853.947529] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.743s)
[853.950394] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.752s)
[854.769896] [142/156] (169s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[854.870666] Starting subtest: yf-tiled-64bpp-rotate-0
[854.875895] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[854.959301] [143/156] (169s left) gen9_exec_parse (cmd-crossing-page)
[855.019439] Subtest cmd-crossing-page: SKIP (0.000s)
[855.096872] [144/156] (169s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[855.210032] Starting subtest: flip-vs-cursor-busy-crc-legacy
[855.925306] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.713s)
[856.800807] [145/156] (167s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[856.912463] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[863.712074] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.807s)
[863.843173] [146/156] (160s left) kms_big_fb (linear-64bpp-rotate-270)
[863.942918] Starting subtest: linear-64bpp-rotate-270
[863.963283] Subtest linear-64bpp-rotate-270: SKIP (0.020s)
[864.080138] [147/156] (160s left) gem_create (create-valid-nonaligned)
[864.137135] Starting subtest: create-valid-nonaligned
[864.139848] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[864.326982] [148/156] (160s left) vgem_basic (debugfs)
[864.366406] Starting subtest: debugfs
[864.369267] Subtest debugfs: SUCCESS (0.000s)
[864.425597] [149/156] (160s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[864.522163] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[864.524629] Starting dynamic subtest: pipe-A-eDP-1
[864.551256] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[864.563187] Starting dynamic subtest: pipe-B-eDP-1
[864.590878] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[864.601164] Starting dynamic subtest: pipe-C-eDP-1
[864.630164] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[864.640663] Starting dynamic subtest: pipe-D-eDP-1
[864.669603] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[864.674587] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[866.792637] [150/156] (158s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[866.895177] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[870.923761] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.035s)
[872.107331] [151/156] (152s left) kms_addfb_basic (master-rmfb)
[872.159152] Starting subtest: master-rmfb
[872.161798] Subtest master-rmfb: SUCCESS (0.001s)
[872.286436] [152/156] (152s left) gem_mmap_gtt (hang-user)
[872.352894] Subtest hang-user: SKIP (0.000s)
[872.414083] [153/156] (152s left) kms_flip (2x-dpms-vs-vblank-race)
[872.505850] Starting subtest: 2x-dpms-vs-vblank-race
[872.516644] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[872.627812] [154/156] (152s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[872.726073] Starting subtest: fbc-pr-cursor-mmap-cpu
[872.731198] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[872.800155] [155/156] (152s left) i915_drm_fdinfo (virtual-busy-all)
[872.839006] Starting subtest: virtual-busy-all
[872.844231] Subtest virtual-busy-all: SKIP (0.000s)
[872.912212] [156/156] (152s left) syncobj_timeline (etime-multi-wait-submitted)
[872.969216] Starting subtest: etime-multi-wait-submitted
[873.069020] Subtest etime-multi-wait-submitted: SUCCESS (0.102s)
[873.145940] Closing watchdogs
results: parsing output: 0/ for test: kms_vblank
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: perf_pmu
results: parsing output: 3/ for test: gem_mmap_wc
results: parsing output: 4/ for test: gem_exec_capture
results: parsing output: 5/ for test: kms_psr
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_tiled_swapping
results: parsing output: 9/ for test: kms_hdr
results: parsing output: 10/ for test: gem_mmap_offset
results: parsing output: 11/ for test: kms_plane_scaling
results: parsing output: 12/ for test: gen3_render_linear_blits
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_pm_backlight
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_softpin
results: parsing output: 17/ for test: gem_mmap_wc
results: parsing output: 18/ for test: gem_mmap_gtt
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_lease
results: parsing output: 21/ for test: kms_rotation_crc
results: parsing output: 22/ for test: syncobj_wait
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: gem_eio
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_flip_scaled_crc
results: parsing output: 28/ for test: kms_flip
results: parsing output: 29/ for test: kms_pm_rpm
results: parsing output: 30/ for test: gem_exec_nop
results: parsing output: 31/ for test: fbdev
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: kms_prime
results: parsing output: 34/ for test: kms_flip
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_exec_store
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_close_race
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_chamelium_hpd
results: parsing output: 44/ for test: gem_partial_pwrite_pread
results: parsing output: 45/ for test: kms_invalid_mode
results: parsing output: 46/ for test: gem_render_copy
results: parsing output: 47/ for test: kms_dsc
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_reloc
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: perf
results: parsing output: 53/ for test: gem_exercise_blt
results: parsing output: 54/ for test: kms_plane
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: vgem_basic
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_ctx_engines
results: parsing output: 60/ for test: gem_ctx_persistence
results: parsing output: 61/ for test: gem_render_copy
results: parsing output: 62/ for test: kms_content_protection
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: gem_fenced_exec_thrash
results: parsing output: 72/ for test: vgem_basic
results: parsing output: 73/ for test: kms_color_pipeline
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: i915_suspend
results: parsing output: 76/ for test: api_intel_bb
results: parsing output: 77/ for test: kms_sequence
results: parsing output: 78/ for test: gem_fenced_exec_thrash
results: parsing output: 79/ for test: kms_cursor_crc
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_readwrite
results: parsing output: 82/ for test: kms_properties
results: parsing output: 83/ for test: kms_addfb_basic
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_parallel
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: vgem_basic
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: kms_atomic_transition
results: parsing output: 94/ for test: kms_chamelium_edid
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_busy
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: api_intel_bb
results: parsing output: 107/ for test: gem_exec_balancer
results: parsing output: 108/ for test: gem_sync
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: gem_exec_schedule
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_cursor_crc
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_ctx_persistence
results: parsing output: 120/ for test: i915_pm_rpm
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_dp_link_training
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_plane_alpha_blend
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_fence
results: parsing output: 131/ for test: kms_chamelium_color
results: parsing output: 132/ for test: kms_atomic
results: parsing output: 133/ for test: prime_busy
results: parsing output: 134/ for test: i915_hangman
results: parsing output: 135/ for test: dmabuf_sync_file
results: parsing output: 136/ for test: kms_big_fb
results: parsing output: 137/ for test: i915_query
results: parsing output: 138/ for test: kms_plane
results: parsing output: 139/ for test: kms_getfb
results: parsing output: 140/ for test: kms_psr2_sf
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: gen9_exec_parse
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_big_fb
results: parsing output: 146/ for test: gem_create
results: parsing output: 147/ for test: vgem_basic
results: parsing output: 148/ for test: kms_ccs
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_addfb_basic
results: parsing output: 151/ for test: gem_mmap_gtt
results: parsing output: 152/ for test: kms_flip
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: i915_drm_fdinfo
results: parsing output: 155/ for test: syncobj_timeline
Done.