[93.389982] Initializing watchdogs
[93.390042]   /dev/watchdog0
[93.405342] [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]
[93.410086] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.412344] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[93.416220] [001/132] (960s left) gem_mmap_wc (write-prefaulted)
[93.508355] Subtest write-prefaulted: SKIP (0.000s)
[93.567872] [002/132] (960s left) kms_sequence (get-forked)
[93.662728] Starting subtest: get-forked
[93.665094] Starting dynamic subtest: pipe-A-eDP-1
[95.560056] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.899s)
[95.573381] Starting dynamic subtest: pipe-B-eDP-1
[98.127229] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.569s)
[98.137457] Starting dynamic subtest: pipe-C-eDP-1
[100.636524] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.508s)
[100.645311] Starting dynamic subtest: pipe-D-eDP-1
[103.157736] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.522s)
[103.159745] Subtest get-forked: SUCCESS (9.501s)
[104.006163] [003/132] (949s left) gem_mmap_gtt (basic-write-gtt)
[104.066070] Subtest basic-write-gtt: SKIP (0.000s)
[104.123417] [004/132] (949s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[104.225341] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[104.227696] Starting dynamic subtest: pipe-A-eDP-1
[106.543076] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.320s)
[106.551036] Starting dynamic subtest: pipe-B-eDP-1
[109.026940] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.483s)
[109.034930] Starting dynamic subtest: pipe-C-eDP-1
[111.234461] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.207s)
[111.242919] Starting dynamic subtest: pipe-D-eDP-1
[113.508507] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.273s)
[113.510708] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (9.287s)
[114.763431] [005/132] (939s left) gem_exec_reloc (basic-write-wc)
[114.829287] Subtest basic-write-wc: SKIP (0.000s)
[114.898497] [006/132] (939s left) kms_cursor_crc (cursor-sliding-64x64)
[115.075296] Starting subtest: cursor-sliding-64x64
[115.080990] Starting dynamic subtest: pipe-A-eDP-1
[118.309699] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.229s)
[118.340635] Starting dynamic subtest: pipe-D-eDP-1
[122.246068] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.915s)
[122.248458] Subtest cursor-sliding-64x64: SUCCESS (7.171s)
[123.106430] [007/132] (931s left) kms_lease (lease-get)
[123.198280] Starting subtest: lease-get
[123.200469] Starting dynamic subtest: pipe-A-eDP-1
[123.202739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[123.210946] Starting dynamic subtest: pipe-B-eDP-1
[123.212863] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[123.220781] Starting dynamic subtest: pipe-C-eDP-1
[123.222602] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[123.230455] Starting dynamic subtest: pipe-D-eDP-1
[123.232316] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[123.234199] Subtest lease-get: SUCCESS (0.008s)
[123.297469] [008/132] (930s left) syncobj_eventfd (invalid-bad-pad)
[123.348713] Starting subtest: invalid-bad-pad
[123.351040] Subtest invalid-bad-pad: SUCCESS (0.000s)
[123.455790] [009/132] (930s left) gem_softpin (evict-active)
[123.521145] Starting subtest: evict-active
[123.527720] Subtest evict-active: SUCCESS (0.006s)
[123.653977] [010/132] (930s left) gem_mmap_gtt (fault-concurrent)
[123.713973] Subtest fault-concurrent: SKIP (0.000s)
[123.781199] [011/132] (930s left) kms_prop_blob (blob-multiple)
[123.828066] Starting subtest: blob-multiple
[123.830469] Subtest blob-multiple: SUCCESS (0.002s)
[123.927781] [012/132] (930s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[124.021780] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[124.023644] Starting dynamic subtest: pipe-A
[124.085074] Dynamic subtest pipe-A: SUCCESS (0.065s)
[124.095200] Starting dynamic subtest: pipe-B
[125.236800] Dynamic subtest pipe-B: SUCCESS (1.151s)
[125.244924] Starting dynamic subtest: pipe-C
[126.356057] Dynamic subtest pipe-C: SUCCESS (1.119s)
[126.364226] Starting dynamic subtest: pipe-D
[127.481934] Dynamic subtest pipe-D: SUCCESS (1.125s)
[127.484378] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.462s)
[128.632965] [013/132] (925s left) gem_close_race (multigpu-basic-threads)
[128.693457] Starting subtest: multigpu-basic-threads
[128.698134] Subtest multigpu-basic-threads: SKIP (0.000s)
[128.911170] [014/132] (925s left) prime_mmap (test_correct_cpu_write)
[128.965150] Starting subtest: test_correct_cpu_write
[128.967521] Starting dynamic subtest: test_correct_cpu_write-smem
[128.969799] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[128.972086] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[129.060977] [015/132] (925s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[129.167721] Starting subtest: 4-tiled-64bpp-rotate-90
[129.187866] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.020s)
[129.310987] [016/132] (925s left) gem_close_race (basic-process)
[129.373246] Starting subtest: basic-process
[129.420857] Subtest basic-process: SUCCESS (0.047s)
[129.603688] [017/132] (924s left) gem_softpin (allocator-nopin-reserve)
[129.660292] Starting subtest: allocator-nopin-reserve
[129.749717] Subtest allocator-nopin-reserve: SUCCESS (0.092s)
[129.871257] [018/132] (924s left) kms_cdclk (plane-scaling)
[129.965856] Starting subtest: plane-scaling
[129.968282] Starting dynamic subtest: pipe-A-eDP-1
[130.256261] Dynamic subtest pipe-A-eDP-1: SKIP (0.289s)
[130.265849] Starting dynamic subtest: pipe-B-eDP-1
[130.270681] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[130.278864] Starting dynamic subtest: pipe-C-eDP-1
[130.283533] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[130.293698] Starting dynamic subtest: pipe-D-eDP-1
[130.297686] Dynamic subtest pipe-D-eDP-1: SKIP (0.002s)
[130.301695] Subtest plane-scaling: SKIP (0.296s)
[131.081761] [019/132] (923s left) kms_hdr (bpc-switch-suspend)
[131.184750] Starting subtest: bpc-switch-suspend
[131.187041] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[152.587896] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.225s)
[152.599219] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[173.597042] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (6.155s)
[173.599499] Subtest bpc-switch-suspend: SUCCESS (12.383s)
[182.725942] [020/132] (880s left) sw_sync (sync_multi_consumer)
[182.774786] Starting subtest: sync_multi_consumer
[183.191336] Subtest sync_multi_consumer: SUCCESS (0.415s)
[183.250244] [021/132] (880s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[183.346164] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[183.350525] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[183.427918] [022/132] (880s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[183.526565] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[183.530764] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[183.634013] [023/132] (880s left) gem_mmap_offset (bad-extensions)
[183.689966] Starting subtest: bad-extensions
[183.692083] Subtest bad-extensions: SUCCESS (0.000s)
[183.797411] [024/132] (880s left) gem_pxp (reject-modify-context-protection-off-2)
[183.919781] Starting subtest: reject-modify-context-protection-off-2
[183.922035] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[184.019326] [025/132] (879s left) syncobj_timeline (wait-all-for-submit-snapshot)
[184.080856] Starting subtest: wait-all-for-submit-snapshot
[184.156971] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[184.231348] [026/132] (879s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[184.324188] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[184.327770] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[184.428913] [027/132] (879s left) kms_cursor_crc (cursor-offscreen-256x85)
[184.648851] Starting subtest: cursor-offscreen-256x85
[184.700132] Subtest cursor-offscreen-256x85: SKIP (0.047s)
[184.846887] [028/132] (879s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[184.939674] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[184.941662] Starting dynamic subtest: pipe-A-eDP-1
[184.961161] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[184.971365] Starting dynamic subtest: pipe-B-eDP-1
[184.989548] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[184.998056] Starting dynamic subtest: pipe-C-eDP-1
[185.015654] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[185.025916] Starting dynamic subtest: pipe-D-eDP-1
[185.043862] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[185.047122] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[187.164549] [029/132] (876s left) kms_addfb_basic (addfb25-4-tiled)
[187.253410] Starting subtest: addfb25-4-tiled
[187.255294] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[187.363654] [030/132] (876s left) gem_softpin (noreloc)
[187.419819] Starting subtest: noreloc
[192.772242] Subtest noreloc: SUCCESS (5.395s)
[192.887126] [031/132] (871s left) gem_exec_whisper (basic-queues-forked-all)
[192.942614] Starting subtest: basic-queues-forked-all
[212.846540] Subtest basic-queues-forked-all: SUCCESS (19.947s)
[213.029812] [032/132] (851s left) perf_pmu (most-busy-idle-check-all)
[213.071664] Starting subtest: most-busy-idle-check-all
[213.074050] Starting dynamic subtest: rcs0
[214.385644] Dynamic subtest rcs0: SUCCESS (1.313s)
[214.398695] Starting dynamic subtest: bcs0
[216.370044] Dynamic subtest bcs0: SUCCESS (1.983s)
[216.382964] Starting dynamic subtest: vcs0
[218.353905] Dynamic subtest vcs0: SUCCESS (1.980s)
[218.366602] Starting dynamic subtest: vcs1
[220.337590] Dynamic subtest vcs1: SUCCESS (1.981s)
[220.350750] Starting dynamic subtest: vecs0
[222.384375] Dynamic subtest vecs0: SUCCESS (2.044s)
[222.414166] Starting dynamic subtest: ccs0
[224.369892] Dynamic subtest ccs0: SUCCESS (1.982s)
[224.373407] Subtest most-busy-idle-check-all: SUCCESS (11.298s)
[224.480934] [033/132] (839s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[224.581417] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[224.593949] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[224.691707] [034/132] (839s left) gem_exec_schedule (u-fairslice)
[224.769080] Starting subtest: u-fairslice
[224.770986] Starting dynamic subtest: rcs0
[226.782812] Dynamic subtest rcs0: SUCCESS (2.020s)
[226.792936] Starting dynamic subtest: bcs0
[228.801250] Dynamic subtest bcs0: SUCCESS (2.018s)
[228.815163] Starting dynamic subtest: vcs0
[230.819257] Dynamic subtest vcs0: SUCCESS (2.017s)
[230.828588] Starting dynamic subtest: vcs1
[232.844295] Dynamic subtest vcs1: SUCCESS (2.022s)
[232.853984] Starting dynamic subtest: vecs0
[234.861918] Dynamic subtest vecs0: SUCCESS (2.018s)
[234.873855] Starting dynamic subtest: ccs0
[236.886463] Dynamic subtest ccs0: SUCCESS (2.024s)
[236.888798] Subtest u-fairslice: SUCCESS (12.124s)
[237.201489] [035/132] (827s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[237.253778] Starting subtest: etime-multi-wait-for-submit-submitted
[237.356192] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.102s)
[237.445484] [036/132] (826s left) kms_fbcon_fbt (psr-suspend)
[237.493683] Starting subtest: psr-suspend
[288.436512] Subtest psr-suspend: SUCCESS (21.517s)
[290.515079] [037/132] (775s left) gem_ctx_persistence (processes)
[290.581346] Starting subtest: processes
[291.863510] Subtest processes: SUCCESS (1.292s)
[291.944789] [038/132] (774s left) gem_exec_balancer (sliced)
[292.007769] Starting subtest: sliced
[292.011847] Subtest sliced: SKIP (0.000s)
[292.310966] [039/132] (774s left) gem_exec_params (rs-invalid)
[292.365363] Starting subtest: rs-invalid
[292.370051] Subtest rs-invalid: SUCCESS (0.005s)
[292.476105] [040/132] (774s left) perf_pmu (all-busy-check-all)
[292.516702] Starting subtest: all-busy-check-all
[293.896659] Subtest all-busy-check-all: SUCCESS (1.386s)
[294.004449] [041/132] (772s left) gem_ctx_shared (single-timeline)
[294.065359] Starting subtest: single-timeline
[294.092121] Subtest single-timeline: SUCCESS (0.023s)
[294.316145] [042/132] (772s left) gem_ctx_isolation (preservation-reset)
[294.490102] Starting subtest: preservation-reset
[294.493444] Starting dynamic subtest: rcs0
[294.778370] Dynamic subtest rcs0: SUCCESS (0.289s)
[294.790457] Starting dynamic subtest: bcs0
[295.116847] Dynamic subtest bcs0: SUCCESS (0.339s)
[295.129813] Starting dynamic subtest: vcs0
[295.431224] Dynamic subtest vcs0: SUCCESS (0.315s)
[295.441450] Starting dynamic subtest: vcs1
[297.166952] Dynamic subtest vcs1: SUCCESS (1.742s)
[297.180207] Starting dynamic subtest: vecs0
[299.154219] Dynamic subtest vecs0: SUCCESS (1.993s)
[299.167031] Starting dynamic subtest: ccs0
[301.158787] Dynamic subtest ccs0: SUCCESS (2.009s)
[301.161605] Subtest preservation-reset: SUCCESS (6.696s)
[301.298719] [043/132] (765s left) prime_self_import (basic-llseek-size)
[301.333472] Starting subtest: basic-llseek-size
[301.345446] Subtest basic-llseek-size: SUCCESS (0.013s)
[301.461935] [044/132] (765s left) syncobj_wait (wait-for-submit-complex)
[301.507428] Starting subtest: wait-for-submit-complex
[301.568377] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[301.651045] [045/132] (765s left) kms_flip (absolute-wf_vblank)
[301.747258] Starting subtest: absolute-wf_vblank
[301.749188] Starting dynamic subtest: A-eDP1
[310.451303] Dynamic subtest A-eDP1: SUCCESS (8.719s)
[310.463246] Starting dynamic subtest: B-eDP1
[319.171329] Dynamic subtest B-eDP1: SUCCESS (8.725s)
[319.182210] Starting dynamic subtest: C-eDP1
[327.888973] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[327.902602] Starting dynamic subtest: D-eDP1
[336.621104] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[336.623474] Subtest absolute-wf_vblank: SUCCESS (34.897s)
[337.837633] [046/132] (728s left) gem_render_copy_redux (normal)
[337.891016] Starting subtest: normal
[338.185290] Subtest normal: SUCCESS (0.293s)
[338.298273] [047/132] (728s left) gem_lmem_swapping (smem-oom)
[338.339580] Subtest smem-oom: SKIP (0.000s)
[338.379012] [048/132] (728s left) api_intel_allocator (default-alignment)
[338.443166] Starting subtest: default-alignment
[338.445713] Subtest default-alignment: SUCCESS (0.003s)
[338.568552] [049/132] (728s left) kms_invalid_mode (bad-htotal)
[338.655078] Starting subtest: bad-htotal
[338.657717] Starting dynamic subtest: pipe-A-eDP-1
[338.941452] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[338.952117] Starting dynamic subtest: pipe-B-eDP-1
[338.954415] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[338.964647] Starting dynamic subtest: pipe-C-eDP-1
[338.967038] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[338.977201] Starting dynamic subtest: pipe-D-eDP-1
[338.979641] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[338.982614] Subtest bad-htotal: SUCCESS (0.293s)
[339.772075] [050/132] (727s left) dumb_buffer (invalid-bpp)
[339.831157] Starting subtest: invalid-bpp
[339.833432] Subtest invalid-bpp: SUCCESS (0.000s)
[339.927047] [051/132] (727s left) gem_ringfill (basic-all)
[345.867038] Starting subtest: basic-all
[347.024443] Subtest basic-all: SUCCESS (1.158s)
[347.169559] [FACT gem_ringfill (basic-all)] new: kernel.kmod_is_loaded.vgem: true
[347.173802] [052/132] (719s left) i915_pm_rpm (system-suspend-execbuf)
[348.998566] Starting subtest: system-suspend-execbuf
[407.005369] Subtest system-suspend-execbuf: SUCCESS (43.200s)
[407.407300] [053/132] (659s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[407.501830] Starting subtest: y-tiled-addfb-size-offset-overflow
[407.506549] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[407.600514] [054/132] (659s left) gem_mmap_gtt (basic-small-copy-xy)
[407.664410] Subtest basic-small-copy-XY: SKIP (0.000s)
[407.729737] [055/132] (659s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[407.824327] Starting subtest: primary-4-tiled-reflect-x-0
[414.830379] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.004s)
[416.066068] [056/132] (651s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[416.161561] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[416.166013] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[416.248963] [057/132] (650s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[416.342425] Starting subtest: 2x-flip-vs-modeset-vs-hang
[416.346793] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.002s)
[416.450489] [058/132] (650s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[416.546281] Starting subtest: pr-primary-plane-update-sf-dmg-area
[416.550725] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[416.620892] [059/132] (650s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[416.721680] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[416.725644] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[416.823284] [060/132] (650s left) kms_cursor_legacy (torture-move)
[416.931691] Starting subtest: torture-move
[416.934009] Starting dynamic subtest: pipe-A
[423.141393] Dynamic subtest pipe-A: SUCCESS (6.205s)
[423.156116] Starting dynamic subtest: pipe-B
[429.270069] Dynamic subtest pipe-B: SUCCESS (6.128s)
[429.288425] Starting dynamic subtest: pipe-C
[436.055654] Dynamic subtest pipe-C: SUCCESS (6.785s)
[436.076360] Starting dynamic subtest: pipe-D
[442.536100] Dynamic subtest pipe-D: SUCCESS (6.481s)
[442.549374] Starting dynamic subtest: all-pipes
[448.388524] Dynamic subtest all-pipes: SUCCESS (5.850s)
[448.393200] Subtest torture-move: SUCCESS (31.459s)
[448.547577] [061/132] (618s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[448.658173] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[455.352743] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.698s)
[455.503606] [062/132] (611s left) kms_addfb_basic (no-handle)
[455.555773] Starting subtest: no-handle
[455.558079] Subtest no-handle: SUCCESS (0.000s)
[455.705844] [063/132] (611s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[455.802554] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[455.804703] Starting dynamic subtest: pipe-A-eDP-1
[455.826769] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[455.838045] Starting dynamic subtest: pipe-B-eDP-1
[455.859689] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[455.870141] Starting dynamic subtest: pipe-C-eDP-1
[455.891899] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[455.904751] Starting dynamic subtest: pipe-D-eDP-1
[455.926932] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[455.931569] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[458.133867] [064/132] (609s left) kms_psr (pr-cursor-render)
[458.221623] Starting subtest: pr-cursor-render
[458.226190] Subtest pr-cursor-render: SKIP (0.000s)
[458.296004] [065/132] (609s left) kms_getfb (getfb-handle-zero)
[458.393723] Starting subtest: getfb-handle-zero
[458.396003] Subtest getfb-handle-zero: SUCCESS (0.000s)
[458.519413] [066/132] (609s left) kms_psr (fbc-psr2-no-drrs)
[458.613646] Starting subtest: fbc-psr2-no-drrs
[458.615930] Starting dynamic subtest: eDP-1
[458.620174] Dynamic subtest eDP-1: SKIP (0.000s)
[458.624699] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[458.687567] [067/132] (608s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[458.738551] Starting subtest: multi-wait-all-for-submit-available-signaled
[458.740989] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[458.851768] [068/132] (608s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[458.944286] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[466.368150] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.430s)
[466.519003] [069/132] (601s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[466.616684] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[466.620740] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[466.692739] [070/132] (601s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[466.778695] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[466.788658] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.011s)
[466.881003] [071/132] (600s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[466.976167] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[466.980075] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[467.076927] [072/132] (600s left) gem_userptr_blits (nohangcheck)
[467.145809] Starting subtest: nohangcheck
[467.405910] Subtest nohangcheck: SUCCESS (0.255s)
[467.539101] [073/132] (600s left) perf (whitelisted-registers-userspace-config)
[467.663887] Starting subtest: whitelisted-registers-userspace-config
[467.666139] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[467.709785] [074/132] (600s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[467.804520] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[468.550123] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.748s)
[468.730206] [075/132] (599s left) kms_setmode (invalid-clone-single-crtc-stealing)
[468.782688] Starting subtest: invalid-clone-single-crtc-stealing
[468.788848] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s)
[468.880782] [076/132] (599s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[468.983917] Starting subtest: x-tiled-8bpp-rotate-0
[470.208071] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.226s)
[470.327426] [077/132] (597s left) kms_content_protection (dp-mst-lic-type-0)
[470.472063] Starting subtest: dp-mst-lic-type-0
[470.476275] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[470.600206] [078/132] (597s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[470.690275] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[478.112888] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.423s)
[478.202864] [079/132] (589s left) gem_exec_balancer (invalid-balancer)
[478.269669] Starting subtest: invalid-balancer
[479.207608] Subtest invalid-balancer: SUCCESS (0.936s)
[479.514081] [080/132] (588s left) kms_plane_alpha_blend (constant-alpha-mid)
[479.617469] Starting subtest: constant-alpha-mid
[479.902994] Starting dynamic subtest: pipe-A-eDP-1
[480.822622] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.919s)
[480.834723] Starting dynamic subtest: pipe-D-eDP-1
[482.352231] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.525s)
[482.354600] Subtest constant-alpha-mid: SUCCESS (2.736s)
[483.182793] [081/132] (585s left) kms_atomic_interruptible (legacy-dpms)
[483.270682] Starting subtest: legacy-dpms
[483.273022] Starting dynamic subtest: pipe-A-eDP-1
[490.701440] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.427s)
[490.703751] Subtest legacy-dpms: SUCCESS (7.429s)
[491.529929] [082/132] (576s left) kms_psr (fbc-psr-cursor-plane-onoff)
[491.615614] Starting subtest: fbc-psr-cursor-plane-onoff
[491.617978] Starting dynamic subtest: eDP-1
[491.622385] Dynamic subtest eDP-1: SKIP (0.000s)
[491.626780] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[491.691620] [083/132] (576s left) kms_pm_dc (dc5-psr)
[492.794621] Starting subtest: dc5-psr
[495.789885] Subtest dc5-psr: SUCCESS (2.994s)
[496.657191] [084/132] (571s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[496.760795] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[496.765451] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[496.846040] [085/132] (571s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[496.912798] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[496.990901] [086/132] (571s left) gem_caching (reads)
[497.042737] Subtest reads: SKIP (0.000s)
[497.113780] [087/132] (571s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[497.213863] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[497.217846] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[497.330350] [088/132] (571s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[497.423136] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[497.427314] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[497.523854] [089/132] (571s left) fbdev (info)
[497.561294] Starting subtest: info
[497.563413] Subtest info: SUCCESS (0.000s)
[497.633126] [090/132] (570s left) kms_async_flips (invalid-async-flip)
[497.716001] Starting subtest: invalid-async-flip
[498.100079] Starting dynamic subtest: pipe-A-eDP-1
[498.899582] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.801s)
[499.274675] Starting dynamic subtest: pipe-B-eDP-1
[500.136576] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.872s)
[500.496320] Starting dynamic subtest: pipe-C-eDP-1
[501.360032] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.875s)
[501.724609] Starting dynamic subtest: pipe-D-eDP-1
[502.588720] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.874s)
[502.591067] Subtest invalid-async-flip: SUCCESS (4.874s)
[503.753424] [091/132] (564s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[503.844820] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[503.849094] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[503.966949] [092/132] (564s left) perf_pmu (busy-double-start)
[504.009243] Starting subtest: busy-double-start
[504.011613] Starting dynamic subtest: rcs0
[507.646872] Dynamic subtest rcs0: FAIL (3.579s)
[507.658917] Starting dynamic subtest: bcs0
[511.182465] Dynamic subtest bcs0: SUCCESS (3.592s)
[511.194288] Starting dynamic subtest: vcs0
[515.401199] Dynamic subtest vcs0: SUCCESS (4.217s)
[515.413508] Starting dynamic subtest: vcs1
[519.367082] Dynamic subtest vcs1: SUCCESS (3.964s)
[519.382408] Starting dynamic subtest: vecs0
[523.400196] Dynamic subtest vecs0: SUCCESS (4.031s)
[523.413137] Starting dynamic subtest: ccs0
[527.040784] Dynamic subtest ccs0: FAIL (3.568s)
[527.043154] Subtest busy-double-start: FAIL (22.962s)
[527.092754] [093/132] (541s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[527.200472] Starting subtest: plane-lut1d-pre-ctm3x4
[527.359266] Starting dynamic subtest: pipe-A-plane-0
[527.558250] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[527.570266] Starting dynamic subtest: pipe-A-plane-1
[527.735314] Dynamic subtest pipe-A-plane-1: SUCCESS (0.177s)
[527.746822] Starting dynamic subtest: pipe-A-plane-2
[527.902783] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[529.195625] Starting dynamic subtest: pipe-B-plane-0
[529.376837] Dynamic subtest pipe-B-plane-0: SUCCESS (0.189s)
[529.388065] Starting dynamic subtest: pipe-B-plane-1
[529.566511] Dynamic subtest pipe-B-plane-1: SUCCESS (0.187s)
[529.577601] Starting dynamic subtest: pipe-B-plane-2
[529.720048] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[530.972062] Starting dynamic subtest: pipe-C-plane-0
[531.106893] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[531.117693] Starting dynamic subtest: pipe-C-plane-1
[531.262735] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[531.274349] Starting dynamic subtest: pipe-C-plane-2
[531.417913] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[532.623339] Starting dynamic subtest: pipe-D-plane-0
[532.759756] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[532.772888] Starting dynamic subtest: pipe-D-plane-1
[532.903180] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[532.914253] Starting dynamic subtest: pipe-D-plane-2
[533.058063] Dynamic subtest pipe-D-plane-2: SUCCESS (0.154s)
[533.346865] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (6.146s)
[534.191127] [094/132] (534s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[534.298954] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[534.303248] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[534.406287] [095/132] (534s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[534.505572] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[534.510024] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[534.592292] [096/132] (534s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[534.713002] Starting subtest: cursorB-vs-flipA-atomic
[534.717598] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[534.859651] [097/132] (534s left) kms_psr (psr2-no-drrs)
[534.956108] Starting subtest: psr2-no-drrs
[534.958458] Starting dynamic subtest: eDP-1
[536.570272] Dynamic subtest eDP-1: SUCCESS (1.613s)
[536.572595] Subtest psr2-no-drrs: SUCCESS (1.614s)
[537.374607] [098/132] (531s left) kms_vblank (wait-forked-busy)
[537.477582] Starting subtest: wait-forked-busy
[537.479576] Starting dynamic subtest: pipe-A-eDP-1
[539.378180] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.900s)
[539.394132] Starting dynamic subtest: pipe-D-eDP-1
[541.933443] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.554s)
[541.935980] Subtest wait-forked-busy: SUCCESS (4.458s)
[542.730583] [099/132] (526s left) gem_exec_schedule (lateslice)
[542.812465] Starting subtest: lateslice
[542.814852] Starting dynamic subtest: rcs0
[542.821445] Dynamic subtest rcs0: SUCCESS (0.019s)
[542.833964] Starting dynamic subtest: bcs0
[542.838886] Dynamic subtest bcs0: SUCCESS (0.017s)
[542.849617] Starting dynamic subtest: vcs0
[542.856891] Dynamic subtest vcs0: SUCCESS (0.017s)
[542.867417] Starting dynamic subtest: vcs1
[542.875631] Dynamic subtest vcs1: SUCCESS (0.018s)
[542.886460] Starting dynamic subtest: vecs0
[542.891628] Dynamic subtest vecs0: SUCCESS (0.015s)
[542.902420] Starting dynamic subtest: ccs0
[542.908783] Dynamic subtest ccs0: SUCCESS (0.017s)
[542.911023] Subtest lateslice: SUCCESS (0.107s)
[544.927023] [100/132] (524s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[545.022726] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[545.025400] Starting dynamic subtest: pipe-A
[545.043133] Dynamic subtest pipe-A: SKIP (0.017s)
[545.054220] Starting dynamic subtest: pipe-B
[545.077269] Dynamic subtest pipe-B: SKIP (0.031s)
[545.090014] Starting dynamic subtest: pipe-C
[545.107274] Dynamic subtest pipe-C: SKIP (0.032s)
[545.118072] Starting dynamic subtest: pipe-D
[545.141505] Dynamic subtest pipe-D: SKIP (0.032s)
[545.146152] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.115s)
[545.232752] [101/132] (523s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[545.320725] Starting subtest: primary-yf-tiled-reflect-x-180
[545.380380] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[545.486410] [102/132] (523s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[545.590310] Starting subtest: fbcpsr-2p-rte
[545.594654] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[545.694020] [103/132] (523s left) kms_cursor_crc (cursor-random-max-size)
[546.003867] Starting subtest: cursor-random-max-size
[546.008918] Subtest cursor-random-max-size: SKIP (0.000s)
[546.119722] [104/132] (523s left) vgem_basic (bad-fence)
[546.158126] Starting subtest: bad-fence
[546.159761] Subtest bad-fence: SUCCESS (0.000s)
[546.224818] [105/132] (523s left) gem_userptr_blits (access-control)
[546.285359] Starting subtest: access-control
[546.289092] Subtest access-control: SKIP (0.000s)
[546.385072] [106/132] (522s left) syncobj_wait (invalid-reset-one-illegal-handle)
[546.440820] Starting subtest: invalid-reset-one-illegal-handle
[546.442636] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[546.538646] [107/132] (522s left) gem_exec_flush (basic-wb-rw-default)
[546.595817] Starting subtest: basic-wb-rw-default
[552.051225] Subtest basic-wb-rw-default: SUCCESS (5.456s)
[552.242089] [108/132] (517s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[552.345961] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[552.350305] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[552.459596] [109/132] (516s left) i915_drm_fdinfo (memory-info-resident)
[552.501249] Starting subtest: memory-info-resident
[552.503563] Starting dynamic subtest: smem0
[555.166052] Dynamic subtest smem0: SUCCESS (2.667s)
[555.168167] Subtest memory-info-resident: SUCCESS (2.668s)
[555.243527] [110/132] (514s left) gem_exec_schedule (u-submit-late-slice)
[555.312597] Starting subtest: u-submit-late-slice
[555.314266] Starting dynamic subtest: rcs0
[555.362651] Dynamic subtest rcs0: SUCCESS (0.054s)
[555.375839] Starting dynamic subtest: bcs0
[555.417244] Dynamic subtest bcs0: SUCCESS (0.054s)
[555.428824] Starting dynamic subtest: vcs0
[555.461765] Dynamic subtest vcs0: SUCCESS (0.044s)
[555.472848] Starting dynamic subtest: vcs1
[555.513642] Dynamic subtest vcs1: SUCCESS (0.051s)
[555.528163] Starting dynamic subtest: vecs0
[555.564744] Dynamic subtest vecs0: SUCCESS (0.050s)
[555.577230] Starting dynamic subtest: ccs0
[555.611243] Dynamic subtest ccs0: SUCCESS (0.045s)
[555.613571] Subtest u-submit-late-slice: SUCCESS (0.303s)
[556.977339] [111/132] (512s left) gen9_exec_parse (bb-start-out)
[557.037199] Subtest bb-start-out: SKIP (0.000s)
[557.105476] [112/132] (512s left) gem_eio (wait-10ms)
[557.324845] Starting subtest: wait-10ms
[557.723224] Subtest wait-10ms: SUCCESS (0.399s)
[557.862371] [113/132] (511s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[557.956790] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[557.958958] Starting dynamic subtest: pipe-A-eDP-1
[558.400597] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s)
[558.414944] Starting dynamic subtest: pipe-B-eDP-1
[558.435900] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[558.448412] Starting dynamic subtest: pipe-C-eDP-1
[558.464226] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[558.474222] Starting dynamic subtest: pipe-D-eDP-1
[558.490287] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[558.492308] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.460s)
[560.695567] [114/132] (508s left) gem_linear_blits (basic)
[561.160193] Starting subtest: basic
[561.169191] Subtest basic: SUCCESS (0.009s)
[561.318217] [115/132] (508s left) api_intel_bb (render-ccs)
[561.424442] Starting subtest: render-ccs
[561.790525] Subtest render-ccs: SUCCESS (0.366s)
[561.887242] [116/132] (507s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[561.998209] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[563.143606] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.146s)
[563.260327] [117/132] (506s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[563.647369] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[564.509220] [118/132] (505s left) kms_chamelium_frames (dp-crc-multiple)
[564.886191] Subtest dp-crc-multiple: SKIP (0.000s)
[565.729095] [119/132] (504s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[565.835584] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[565.838012] Starting dynamic subtest: pipe-A-eDP-1
[571.728547] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.897s)
[571.740509] Starting dynamic subtest: pipe-B-eDP-1
[578.406113] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.678s)
[578.408365] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.579s)
[579.296388] [120/132] (490s left) gem_exec_reloc (basic-cpu-wc-active)
[579.361759] Subtest basic-cpu-wc-active: SKIP (0.000s)
[579.436406] [121/132] (490s left) gem_exec_fence (long-history)
[579.869525] Starting subtest: long-history
[586.596011] Subtest long-history: SUCCESS (6.729s)
[587.094776] [122/132] (482s left) gem_mmap_gtt (big-bo-tiledx)
[587.161726] Subtest big-bo-tiledX: SKIP (0.000s)
[587.232130] [123/132] (482s left) i915_suspend (forcewake)
[587.288455] Starting subtest: forcewake
[620.451885] Subtest forcewake: SUCCESS (3.070s)
[629.539178] [124/132] (449s left) kms_prop_blob (invalid-set-prop)
[629.616072] Starting subtest: invalid-set-prop
[629.618451] Subtest invalid-set-prop: SUCCESS (0.000s)
[629.741205] [125/132] (449s left) kms_plane_multiple (2x-tiling-4)
[629.847176] Starting subtest: 2x-tiling-4
[629.851446] Subtest 2x-tiling-4: SKIP (0.000s)
[629.935856] [126/132] (449s left) kms_feature_discovery (psr1)
[630.026684] Starting subtest: psr1
[630.028772] Subtest psr1: SUCCESS (0.000s)
[630.105497] [127/132] (449s left) i915_drm_fdinfo (virtual-busy-idle-all)
[630.146574] Starting subtest: virtual-busy-idle-all
[630.151146] Subtest virtual-busy-idle-all: SKIP (0.000s)
[630.219157] [128/132] (449s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[630.322342] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[630.326308] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[630.432242] [129/132] (448s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[630.532155] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[637.751819] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.297s)
[637.879147] [130/132] (441s left) syncobj_timeline (multi-wait-available-submitted)
[637.952616] Starting subtest: multi-wait-available-submitted
[637.955229] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[638.060868] [131/132] (441s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[638.146141] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[638.150171] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[638.243368] [132/132] (441s left) prime_busy (hang-wait)
[638.290818] Starting subtest: hang-wait
[638.293204] Starting dynamic subtest: rcs0
[664.412534] Dynamic subtest rcs0: SUCCESS (26.164s)
[664.428170] Starting dynamic subtest: bcs0
[671.565427] Dynamic subtest bcs0: SUCCESS (7.149s)
[671.576329] Starting dynamic subtest: vcs0
[678.539556] Dynamic subtest vcs0: SUCCESS (6.972s)
[678.550599] Starting dynamic subtest: vcs1
[685.510760] Dynamic subtest vcs1: SUCCESS (6.972s)
[685.524945] Starting dynamic subtest: vecs0
[692.551436] Dynamic subtest vecs0: SUCCESS (7.039s)
[692.562375] Starting dynamic subtest: ccs0
[706.387529] Dynamic subtest ccs0: SUCCESS (13.838s)
[706.390026] Subtest hang-wait: SUCCESS (68.149s)
[706.521539] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_wc
results: parsing output: 1/ for test: kms_sequence
results: parsing output: 2/ for test: gem_mmap_gtt
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: gem_exec_reloc
results: parsing output: 5/ for test: kms_cursor_crc
results: parsing output: 6/ for test: kms_lease
results: parsing output: 7/ for test: syncobj_eventfd
results: parsing output: 8/ for test: gem_softpin
results: parsing output: 9/ for test: gem_mmap_gtt
results: parsing output: 10/ for test: kms_prop_blob
results: parsing output: 11/ for test: kms_plane_scaling
results: parsing output: 12/ for test: gem_close_race
results: parsing output: 13/ for test: prime_mmap
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: gem_close_race
results: parsing output: 16/ for test: gem_softpin
results: parsing output: 17/ for test: kms_cdclk
results: parsing output: 18/ for test: kms_hdr
results: parsing output: 19/ for test: sw_sync
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_mmap_offset
results: parsing output: 23/ for test: gem_pxp
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_addfb_basic
results: parsing output: 29/ for test: gem_softpin
results: parsing output: 30/ for test: gem_exec_whisper
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_fbcon_fbt
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: gem_exec_balancer
results: parsing output: 38/ for test: gem_exec_params
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: gem_ctx_shared
results: parsing output: 41/ for test: gem_ctx_isolation
results: parsing output: 42/ for test: prime_self_import
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: gem_render_copy_redux
results: parsing output: 46/ for test: gem_lmem_swapping
results: parsing output: 47/ for test: api_intel_allocator
results: parsing output: 48/ for test: kms_invalid_mode
results: parsing output: 49/ for test: dumb_buffer
results: parsing output: 50/ for test: gem_ringfill
results: parsing output: 51/ for test: i915_pm_rpm
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_cursor_legacy
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_addfb_basic
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_getfb
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_userptr_blits
results: parsing output: 72/ for test: perf
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: kms_setmode
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_content_protection
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: kms_plane_alpha_blend
results: parsing output: 80/ for test: kms_atomic_interruptible
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_pm_dc
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: gem_caching
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: fbdev
results: parsing output: 89/ for test: kms_async_flips
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: perf_pmu
results: parsing output: 92/ for test: kms_color_pipeline
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: kms_vblank
results: parsing output: 98/ for test: gem_exec_schedule
results: parsing output: 99/ for test: kms_plane_scaling
results: parsing output: 100/ for test: kms_rotation_crc
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: vgem_basic
results: parsing output: 104/ for test: gem_userptr_blits
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: gem_exec_flush
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: i915_drm_fdinfo
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: gen9_exec_parse
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: kms_ccs
results: parsing output: 113/ for test: gem_linear_blits
results: parsing output: 114/ for test: api_intel_bb
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: kms_chamelium_hpd
results: parsing output: 117/ for test: kms_chamelium_frames
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: gem_exec_reloc
results: parsing output: 120/ for test: gem_exec_fence
results: parsing output: 121/ for test: gem_mmap_gtt
results: parsing output: 122/ for test: i915_suspend
results: parsing output: 123/ for test: kms_prop_blob
results: parsing output: 124/ for test: kms_plane_multiple
results: parsing output: 125/ for test: kms_feature_discovery
results: parsing output: 126/ for test: i915_drm_fdinfo
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: prime_busy
Done.