[57.593795] Initializing watchdogs
[57.593842]   /dev/watchdog0
[57.608158] [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.612958] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.615291] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.618712] [001/133] (960s left) perf (disabled-read-error)
[57.784686] Starting subtest: disabled-read-error
[57.802077] Subtest disabled-read-error: SUCCESS (0.019s)
[57.850397] [002/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[57.954592] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[57.959064] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[58.064345] [003/133] (960s left) kms_properties (get_properties-sanity-atomic)
[58.162091] Starting subtest: get_properties-sanity-atomic
[58.164500] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[58.250083] [004/133] (959s left) gem_fenced_exec_thrash (2-spare-fences)
[58.310434] Subtest 2-spare-fences: SKIP (0.000s)
[58.372543] [005/133] (959s left) api_intel_allocator (gem-pool)
[58.426879] Starting subtest: gem-pool
[58.435542] Subtest gem-pool: SUCCESS (0.009s)
[58.543816] [006/133] (959s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[58.630615] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[58.634084] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[58.718184] [007/133] (959s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[58.808436] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[58.812294] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[58.884618] [008/133] (959s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[59.290321] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[60.106584] [009/133] (958s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[60.210627] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[60.214920] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[60.301987] [010/133] (958s left) i915_drm_fdinfo (busy-check-all)
[60.338502] Starting subtest: busy-check-all
[60.340581] Starting dynamic subtest: rcs0
[60.344869] Dynamic subtest rcs0: SKIP (0.004s)
[60.351755] Starting dynamic subtest: bcs0
[60.355692] Dynamic subtest bcs0: SKIP (0.000s)
[60.362556] Starting dynamic subtest: vcs0
[60.366679] Dynamic subtest vcs0: SKIP (0.000s)
[60.373107] Starting dynamic subtest: vcs1
[60.377313] Dynamic subtest vcs1: SKIP (0.000s)
[60.383713] Starting dynamic subtest: vecs0
[60.387783] Dynamic subtest vecs0: SKIP (0.000s)
[60.396768] Starting dynamic subtest: ccs0
[60.401240] Dynamic subtest ccs0: SKIP (0.000s)
[60.405454] Subtest busy-check-all: SKIP (0.007s)
[60.440026] [011/133] (958s left) i915_pm_freq_api (freq-suspend)
[60.492464] Starting subtest: freq-suspend
[60.494934] Starting dynamic subtest: gt0
[77.019519] Dynamic subtest gt0: SUCCESS (2.491s)
[77.033244] Starting dynamic subtest: gt1
[94.025772] Dynamic subtest gt1: SUCCESS (1.640s)
[94.028086] Subtest freq-suspend: SUCCESS (4.133s)
[101.097337] [012/133] (924s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[101.195114] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[101.197351] Starting dynamic subtest: pipe-A-default-mode
[102.341795] Dynamic subtest pipe-A-default-mode: SKIP (1.136s)
[102.345654] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.137s)
[102.500021] [013/133] (923s left) gem_pwrite (basic-random)
[102.564587] Subtest basic-random: SKIP (0.000s)
[102.625715] [014/133] (923s left) kms_atomic_transition (plane-all-modeset-transition)
[102.722105] Starting subtest: plane-all-modeset-transition
[102.726565] Subtest plane-all-modeset-transition: SKIP (0.000s)
[102.791417] [015/133] (922s left) syncobj_timeline (etime-multi-wait-submitted)
[102.848938] Starting subtest: etime-multi-wait-submitted
[102.956770] Subtest etime-multi-wait-submitted: SUCCESS (0.107s)
[103.041520] [016/133] (922s left) kms_content_protection (lic-type-0-hdcp14)
[103.186205] Starting subtest: lic-type-0-hdcp14
[103.190551] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[103.309048] [017/133] (922s left) kms_vrr (max-min)
[103.395986] Starting subtest: max-min
[103.402549] Subtest max-min: SKIP (0.000s)
[103.466315] [018/133] (922s left) i915_pm_rpm (system-suspend-execbuf)
[105.422525] Starting subtest: system-suspend-execbuf
[163.560703] Subtest system-suspend-execbuf: SUCCESS (42.953s)
[163.953154] [019/133] (861s left) gem_exec_whisper (basic-normal-all)
[164.027842] Starting subtest: basic-normal-all
[166.286108] Subtest basic-normal-all: SUCCESS (2.260s)
[166.474722] [020/133] (859s left) gem_exec_params (dr1-dirt)
[166.529817] Starting subtest: DR1-dirt
[166.532330] Subtest DR1-dirt: SUCCESS (0.000s)
[166.635869] [021/133] (859s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[166.732936] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[166.735363] Starting dynamic subtest: pipe-A
[166.753058] Dynamic subtest pipe-A: SKIP (0.017s)
[166.762241] Starting dynamic subtest: pipe-B
[166.788217] Dynamic subtest pipe-B: SKIP (0.035s)
[166.796560] Starting dynamic subtest: pipe-C
[166.823871] Dynamic subtest pipe-C: SKIP (0.036s)
[166.831973] Starting dynamic subtest: pipe-D
[166.861306] Dynamic subtest pipe-D: SKIP (0.036s)
[166.865834] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.125s)
[166.953711] [022/133] (859s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[167.081404] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[167.086853] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[167.224556] [023/133] (858s left) gem_fence_thrash (bo-write-verify-y)
[167.269412] Starting subtest: bo-write-verify-y
[167.288370] Subtest bo-write-verify-y: SKIP (0.019s)
[167.347646] [024/133] (858s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[167.458425] Starting subtest: x-tiled-16bpp-rotate-90
[167.478239] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[167.590906] [025/133] (858s left) kms_plane (pixel-format-y-tiled-modifier)
[167.678569] Starting subtest: pixel-format-y-tiled-modifier
[167.683243] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[167.747868] [026/133] (858s left) syncobj_timeline (device-signal-unordered)
[167.808984] Starting subtest: device-signal-unordered
[167.811302] Subtest device-signal-unordered: SUCCESS (0.000s)
[167.890971] [027/133] (858s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[167.992292] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[167.996453] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[168.077144] [028/133] (858s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[168.197384] Starting subtest: flip-vs-cursor-busy-crc-legacy
[169.011473] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.813s)
[169.878174] [029/133] (856s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[169.973828] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[169.979219] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[170.065940] [030/133] (856s left) kms_plane_lowres (tiling-x)
[170.157208] Starting subtest: tiling-x
[170.159730] Starting dynamic subtest: pipe-A-eDP-1
[170.165482] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[170.451240] Starting dynamic subtest: pipe-B-eDP-1
[170.456332] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[170.464347] Starting dynamic subtest: pipe-C-eDP-1
[170.469353] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[170.477414] Starting dynamic subtest: pipe-D-eDP-1
[170.482821] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[170.488185] Subtest tiling-x: SKIP (0.294s)
[171.305863] [031/133] (855s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[171.358451] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[171.361328] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[171.471948] [032/133] (854s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[171.571296] Starting subtest: y-tiled-64bpp-rotate-270
[171.576382] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[171.673065] [033/133] (854s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[171.783368] Starting subtest: yf-tiled-16bpp-rotate-270
[171.788380] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[171.906869] [034/133] (854s left) gem_mmap_gtt (fault-concurrent-x)
[171.959600] Subtest fault-concurrent-X: SKIP (0.000s)
[172.015377] [035/133] (854s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[172.131161] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[172.136349] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[172.236277] [036/133] (854s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[172.335056] Starting subtest: bad-pixel-format-y-tiled-ccs
[172.336900] Starting dynamic subtest: pipe-A-eDP-1
[172.356186] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[172.364418] Starting dynamic subtest: pipe-B-eDP-1
[172.384307] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[172.391879] Starting dynamic subtest: pipe-C-eDP-1
[172.412305] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[172.419525] Starting dynamic subtest: pipe-D-eDP-1
[172.439143] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[172.443113] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[174.569628] [037/133] (851s left) gem_bad_reloc (negative-reloc-lut)
[174.639118] Subtest negative-reloc-lut: SKIP (0.000s)
[174.700678] [038/133] (851s left) kms_busy (extended-modeset-hang-oldfb)
[174.796012] Starting subtest: extended-modeset-hang-oldfb
[174.799040] Starting dynamic subtest: pipe-A
[186.746519] Dynamic subtest pipe-A: SUCCESS (11.951s)
[186.756262] Starting dynamic subtest: pipe-D
[199.031843] Dynamic subtest pipe-D: SUCCESS (12.282s)
[199.035035] Subtest extended-modeset-hang-oldfb: SUCCESS (24.236s)
[199.835054] [039/133] (826s left) kms_pm_dc (dc6-psr)
[200.939188] Starting subtest: dc6-psr
[203.840901] Subtest dc6-psr: SUCCESS (2.901s)
[204.691426] [040/133] (821s left) perf_pmu (pmu-read)
[204.740271] Starting subtest: pmu-read
[204.742815] Subtest pmu-read: SUCCESS (0.003s)
[204.836533] [041/133] (821s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[205.389630] Starting subtest: syncobj-timeline-invalid-flags
[205.391853] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[206.439608] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[206.443269] [042/133] (820s left) gem_softpin (allocator-nopin)
[206.505604] Starting subtest: allocator-nopin
[206.685347] Subtest allocator-nopin: SUCCESS (0.178s)
[206.815318] [043/133] (819s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[206.907797] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[206.910237] Starting dynamic subtest: pipe-A-eDP-1
[206.975358] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[206.986047] Starting dynamic subtest: pipe-B-eDP-1
[207.054335] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[207.063320] Starting dynamic subtest: pipe-C-eDP-1
[207.130549] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[207.140019] Starting dynamic subtest: pipe-D-eDP-1
[207.206534] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[207.211312] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[209.248250] [044/133] (817s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[209.334574] Starting subtest: basic-max-pipe-crc-check
[209.339464] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[209.383768] [045/133] (817s left) gem_ctx_isolation (preservation-s3)
[209.443148] Starting subtest: preservation-S3
[209.445873] Starting dynamic subtest: rcs0
[226.055662] Dynamic subtest rcs0: SUCCESS (1.715s)
[226.064353] Starting dynamic subtest: bcs0
[243.052485] Dynamic subtest bcs0: SUCCESS (1.689s)
[243.061593] Starting dynamic subtest: vcs0
[260.039785] Dynamic subtest vcs0: SUCCESS (3.047s)
[260.048819] Starting dynamic subtest: vcs1
[278.053296] Dynamic subtest vcs1: SUCCESS (4.930s)
[278.062993] Starting dynamic subtest: vecs0
[296.056126] Dynamic subtest vecs0: SUCCESS (5.088s)
[296.065080] Starting dynamic subtest: ccs0
[314.057348] Dynamic subtest ccs0: SUCCESS (3.093s)
[314.059632] Subtest preservation-S3: SUCCESS (19.565s)
[320.337600] [046/133] (712s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[320.456997] Starting subtest: yf-tiled-64bpp-rotate-0
[320.461062] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[320.573499] [047/133] (712s left) kms_chamelium_frames (dp-crc-single)
[320.949842] Subtest dp-crc-single: SKIP (0.000s)
[321.780475] [048/133] (711s left) gem_tiled_swapping (non-threaded)
[321.846896] Subtest non-threaded: SKIP (0.000s)
[321.908294] [049/133] (711s left) gem_ctx_shared (disjoint-timelines)
[321.958063] Starting subtest: disjoint-timelines
[321.976182] Subtest disjoint-timelines: SUCCESS (0.018s)
[322.192300] [050/133] (710s left) i915_suspend (forcewake)
[322.251375] Starting subtest: forcewake
[355.023373] Subtest forcewake: SUCCESS (3.076s)
[368.114568] [051/133] (678s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[368.190499] Starting subtest: y-tiled-ccs-to-y-tiled
[368.195437] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[368.379285] [052/133] (677s left) kms_flip (2x-nonexisting-fb)
[368.477714] Starting subtest: 2x-nonexisting-fb
[368.489905] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[368.571754] [053/133] (677s left) gem_exec_fence (basic-await)
[368.745939] Starting subtest: basic-await
[368.748493] Starting dynamic subtest: rcs0
[368.822717] Dynamic subtest rcs0: SUCCESS (0.076s)
[368.834960] Starting dynamic subtest: bcs0
[368.896604] Dynamic subtest bcs0: SUCCESS (0.073s)
[368.908414] Starting dynamic subtest: vcs0
[368.970309] Dynamic subtest vcs0: SUCCESS (0.072s)
[368.981594] Starting dynamic subtest: vcs1
[369.043606] Dynamic subtest vcs1: SUCCESS (0.072s)
[369.054770] Starting dynamic subtest: vecs0
[369.117036] Dynamic subtest vecs0: SUCCESS (0.072s)
[369.128331] Starting dynamic subtest: ccs0
[369.191281] Dynamic subtest ccs0: SUCCESS (0.073s)
[369.193929] Subtest basic-await: SUCCESS (0.446s)
[371.149231] [054/133] (675s left) gem_ctx_persistence (legacy-engines-queued)
[371.225096] Starting subtest: legacy-engines-queued
[371.227389] Starting dynamic subtest: render
[371.302458] Dynamic subtest render: SUCCESS (0.077s)
[371.313795] Starting dynamic subtest: bsd1
[373.089643] Dynamic subtest bsd1: SUCCESS (1.782s)
[373.101119] Starting dynamic subtest: bsd2
[375.070529] Dynamic subtest bsd2: SUCCESS (1.978s)
[375.081657] Starting dynamic subtest: blt
[375.110604] Dynamic subtest blt: SUCCESS (0.039s)
[375.122635] Starting dynamic subtest: vebox
[377.119446] Dynamic subtest vebox: SUCCESS (2.004s)
[377.122047] Subtest legacy-engines-queued: SUCCESS (5.892s)
[377.201586] [055/133] (669s left) kms_cursor_legacy (torture-bo)
[377.345833] Starting subtest: torture-bo
[377.347850] Starting dynamic subtest: pipe-A
[383.979798] Dynamic subtest pipe-A: SUCCESS (6.597s)
[383.991024] Starting dynamic subtest: pipe-B
[391.378074] Dynamic subtest pipe-B: SUCCESS (7.378s)
[391.389680] Starting dynamic subtest: pipe-C
[398.708135] Dynamic subtest pipe-C: SUCCESS (7.321s)
[398.717038] Starting dynamic subtest: pipe-D
[405.850058] Dynamic subtest pipe-D: SUCCESS (7.134s)
[405.865407] Starting dynamic subtest: all-pipes
[413.398013] Dynamic subtest all-pipes: SUCCESS (7.543s)
[413.400475] Subtest torture-bo: SUCCESS (35.980s)
[413.555228] [056/133] (632s left) kms_cursor_crc (cursor-random-256x256)
[413.814326] Starting subtest: cursor-random-256x256
[413.819498] Starting dynamic subtest: pipe-A-eDP-1
[417.162272] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.342s)
[417.189281] Starting dynamic subtest: pipe-D-eDP-1
[421.159187] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.976s)
[421.161021] Subtest cursor-random-256x256: SUCCESS (7.346s)
[422.008019] [057/133] (624s left) kms_addfb_basic (addfb25-bad-modifier)
[422.101631] Starting subtest: addfb25-bad-modifier
[422.103776] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[422.189364] [058/133] (624s left) syncobj_wait (wait-zero-handles)
[422.248799] Starting subtest: wait-zero-handles
[422.251067] Subtest wait-zero-handles: SUCCESS (0.000s)
[422.345506] [059/133] (624s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[422.449497] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[429.245263] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.798s)
[429.408762] [060/133] (617s left) kms_async_flips (test-cursor)
[429.508093] Starting subtest: test-cursor
[429.512138] Subtest test-cursor: SKIP (0.000s)
[430.668410] [061/133] (615s left) kms_pm_rpm (pm-caching)
[432.295890] Starting subtest: pm-caching
[432.300682] Subtest pm-caching: SKIP (0.000s)
[432.913849] [062/133] (613s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[433.030507] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[433.034890] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[433.126752] [063/133] (613s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[433.234184] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[433.236473] Starting dynamic subtest: pipe-A-eDP-1
[433.307863] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[433.316755] Starting dynamic subtest: pipe-B-eDP-1
[434.468207] Dynamic subtest pipe-B-eDP-1: SKIP (1.158s)
[434.472801] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.239s)
[435.651254] [064/133] (611s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[435.765683] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[435.769621] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[435.861015] [065/133] (610s left) kms_selftest (drm_format)
[435.907534] Starting subtest: drm_format
[435.933352] Starting dynamic subtest: drm_test_format_block_width_invalid
[436.042744] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.110s)
[436.050880] Starting dynamic subtest: drm_test_format_block_width_one_plane
[436.130939] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.088s)
[436.140739] Starting dynamic subtest: drm_test_format_block_width_two_plane
[436.230563] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.099s)
[436.238746] Starting dynamic subtest: drm_test_format_block_width_three_plane
[436.316313] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.085s)
[436.325745] Starting dynamic subtest: drm_test_format_block_width_tiled
[436.392279] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.075s)
[436.401732] Starting dynamic subtest: drm_test_format_block_height_invalid
[436.477991] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.085s)
[436.486615] Starting dynamic subtest: drm_test_format_block_height_one_plane
[436.567354] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.088s)
[436.577273] Starting dynamic subtest: drm_test_format_block_height_two_plane
[436.648534] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.080s)
[436.658249] Starting dynamic subtest: drm_test_format_block_height_three_plane
[436.743784] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.094s)
[436.753753] Starting dynamic subtest: drm_test_format_block_height_tiled
[436.824092] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.081s)
[436.834377] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[436.916027] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.090s)
[436.925624] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[437.006149] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.089s)
[437.015871] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[437.086281] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.080s)
[437.095741] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[437.168172] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.080s)
[437.177757] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[437.257955] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.089s)
[437.267883] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[437.354029] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.097s)
[437.363607] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[437.456192] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.100s)
[437.466114] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[437.557448] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.100s)
[437.559289] Subtest drm_format: SUCCESS (1.652s)
[437.713244] [066/133] (609s left) kms_psr (fbc-pr-basic)
[437.814570] Starting subtest: fbc-pr-basic
[437.818215] Subtest fbc-pr-basic: SKIP (0.000s)
[437.886805] [067/133] (608s left) kms_hdr (static-swap)
[437.985010] Starting subtest: static-swap
[437.990099] Subtest static-swap: SKIP (0.000s)
[438.056801] [068/133] (608s left) kms_color (ctm-0-50)
[438.154321] Starting subtest: ctm-0-50
[438.155931] Starting dynamic subtest: pipe-A-eDP-1
[441.688173] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.533s)
[441.697126] Starting dynamic subtest: pipe-B-eDP-1
[445.987960] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.298s)
[445.996608] Starting dynamic subtest: pipe-C-eDP-1
[449.999520] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.010s)
[450.008901] Starting dynamic subtest: pipe-D-eDP-1
[453.990040] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.988s)
[453.992448] Subtest ctm-0-50: SUCCESS (15.832s)
[454.820390] [069/133] (592s left) gem_mmap_gtt (basic-small-copy-odd)
[454.890842] Subtest basic-small-copy-odd: SKIP (0.000s)
[454.953686] [070/133] (592s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[455.049135] Starting subtest: dsc-fractional-bpp-with-bpc
[455.078653] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[455.122772] [071/133] (591s left) kms_flip (2x-wf_vblank-ts-check)
[455.222295] Starting subtest: 2x-wf_vblank-ts-check
[455.233777] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[455.323638] [072/133] (591s left) gen9_exec_parse (batch-invalid-length)
[455.381590] Subtest batch-invalid-length: SKIP (0.000s)
[455.453698] [073/133] (591s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[455.550402] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[462.595909] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.046s)
[462.744173] [074/133] (584s left) gem_huc_copy (huc-copy)
[462.802529] Starting subtest: huc-copy
[462.807477] Subtest huc-copy: SUCCESS (0.003s)
[462.925929] [075/133] (584s left) kms_psr (fbc-psr-sprite-plane-move)
[463.029734] Starting subtest: fbc-psr-sprite-plane-move
[463.031909] Starting dynamic subtest: eDP-1
[463.036177] Dynamic subtest eDP-1: SKIP (0.000s)
[463.040342] Subtest fbc-psr-sprite-plane-move: SKIP (0.002s)
[463.105242] [076/133] (584s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[463.206112] Starting subtest: 4-tiled-16bpp-rotate-90
[463.225396] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.018s)
[463.343547] [077/133] (583s left) gem_exec_reloc (basic-gtt-read-active)
[463.401572] Subtest basic-gtt-read-active: SKIP (0.000s)
[463.461243] [078/133] (583s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[463.530439] Subtest tiled-to-untiled: SKIP (0.000s)
[463.593508] [079/133] (583s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[463.682693] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[467.934906] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.252s)
[469.063336] [080/133] (578s left) gem_madvise (dontneed-before-mmap)
[469.103762] Starting subtest: dontneed-before-mmap
[469.122131] Subtest dontneed-before-mmap: SUCCESS (0.021s)
[469.211942] [081/133] (578s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[469.304668] Starting subtest: basic-modeset-with-all-modifiers-formats
[469.690301] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[470.438897] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.749s)
[470.447800] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[470.461211] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[470.472009] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[470.483177] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[470.491963] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[470.638709] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[470.648226] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[470.661021] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[470.669674] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[470.683248] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[470.691530] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[470.705386] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.021s)
[470.713365] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[470.727730] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[470.735745] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[470.749911] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[470.757932] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[470.772222] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[470.780364] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[470.794392] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[470.803171] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[470.816574] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[470.825035] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[470.972223] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[470.981006] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[470.994439] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[471.002639] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[471.016742] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[471.025039] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[471.038965] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[471.047047] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[471.061091] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[471.069117] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[471.083218] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[471.091049] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[471.105272] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[471.113160] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[471.127780] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[471.136138] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[471.149764] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[471.158164] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[471.305693] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[471.316414] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[471.327848] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.020s)
[471.342176] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[471.350226] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[471.360505] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[471.372442] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[471.382923] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[471.394628] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[471.403685] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[471.416632] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[471.427030] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[471.439047] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.020s)
[471.447032] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[471.461284] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[471.469359] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[471.483537] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[471.868360] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.565s)
[472.637629] [082/133] (574s left) kms_addfb_basic (invalid-get-prop)
[472.699063] Starting subtest: invalid-get-prop
[472.700979] Subtest invalid-get-prop: SUCCESS (0.000s)
[472.827318] [083/133] (574s left) kms_cursor_crc (cursor-sliding-256x85)
[473.079144] Starting subtest: cursor-sliding-256x85
[473.129935] Subtest cursor-sliding-256x85: SKIP (0.047s)
[473.295409] [084/133] (574s left) perf (short-reads)
[473.392194] Starting subtest: short-reads
[473.403658] Subtest short-reads: SUCCESS (0.012s)
[473.445873] [085/133] (574s left) kms_cursor_crc (cursor-onscreen-128x42)
[473.660268] Starting subtest: cursor-onscreen-128x42
[473.707697] Subtest cursor-onscreen-128x42: SKIP (0.043s)
[473.906949] [086/133] (573s left) gem_exec_schedule (u-semaphore-noskip)
[473.967417] Starting subtest: u-semaphore-noskip
[474.384261] Subtest u-semaphore-noskip: SUCCESS (0.418s)
[474.728373] [087/133] (572s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[474.820877] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[474.825248] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[475.960573] [088/133] (571s left) gem_exec_reloc (basic-write-cpu-noreloc)
[476.017006] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[476.088926] [089/133] (571s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[476.209209] Starting subtest: protected-encrypted-src-copy-not-readible
[476.320034] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.112s)
[476.446774] [090/133] (571s left) gem_exec_schedule (out-order)
[476.511230] Starting subtest: out-order
[476.513544] Starting dynamic subtest: rcs0
[476.598582] Dynamic subtest rcs0: SUCCESS (0.095s)
[476.607675] Starting dynamic subtest: vcs0
[476.681694] Dynamic subtest vcs0: SUCCESS (0.080s)
[476.693448] Starting dynamic subtest: vcs1
[476.763906] Dynamic subtest vcs1: SUCCESS (0.082s)
[476.775619] Starting dynamic subtest: vecs0
[476.848208] Dynamic subtest vecs0: SUCCESS (0.083s)
[476.859945] Starting dynamic subtest: ccs0
[476.936193] Dynamic subtest ccs0: SUCCESS (0.088s)
[476.938420] Subtest out-order: SUCCESS (0.433s)
[478.235274] [091/133] (569s left) kms_fbcon_fbt (psr)
[478.294670] Starting subtest: psr
[485.599854] Subtest psr: SUCCESS (7.302s)
[485.675820] [092/133] (562s left) kms_joiner (basic-force-big-joiner)
[485.784652] Starting subtest: basic-force-big-joiner
[485.788700] Subtest basic-force-big-joiner: SKIP (0.000s)
[485.843580] [093/133] (562s left) gem_exec_params (larger-than-life-batch)
[485.905698] Starting subtest: larger-than-life-batch
[488.260062] Subtest larger-than-life-batch: SUCCESS (2.355s)
[489.240439] [094/133] (558s left) syncobj_timeline (invalid-query-one-illegal-handle)
[489.348010] Starting subtest: invalid-query-one-illegal-handle
[489.350801] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[489.424705] [095/133] (558s left) syncobj_basic (bad-flags-fd-to-handle)
[489.472507] Starting subtest: bad-flags-fd-to-handle
[489.474475] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[489.566937] [096/133] (558s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[489.679864] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[490.800871] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.523s)
[490.843754] [097/133] (557s left) kms_psr (fbc-pr-sprite-plane-onoff)
[490.932721] Starting subtest: fbc-pr-sprite-plane-onoff
[490.937191] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[491.008433] [098/133] (557s left) core_getversion (basic)
[491.048772] Starting subtest: basic
[491.052251] Subtest basic: SUCCESS (0.000s)
[491.120777] [099/133] (557s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[491.212827] Starting subtest: fbcpsr-tiling-linear
[497.875655] Subtest fbcpsr-tiling-linear: SUCCESS (6.663s)
[497.996825] [100/133] (550s left) kms_flip (2x-flip-vs-suspend)
[498.089982] Starting subtest: 2x-flip-vs-suspend
[498.099220] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[498.185354] [101/133] (550s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[498.272077] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[498.298336] Starting dynamic subtest: pipe-A-plane-0
[498.587000] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[498.597085] Starting dynamic subtest: pipe-A-plane-3
[498.842030] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[499.947023] Starting dynamic subtest: pipe-B-plane-0
[500.250700] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[500.262353] Starting dynamic subtest: pipe-B-plane-3
[500.517545] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[500.827142] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.555s)
[501.630389] [102/133] (546s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[501.685963] Starting subtest: yf-tiled-to-vebox-yf-tiled
[501.690032] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[501.868744] [103/133] (546s left) kms_psr (psr2-cursor-plane-onoff)
[501.952022] Starting subtest: psr2-cursor-plane-onoff
[501.954257] Starting dynamic subtest: eDP-1
[503.626433] Dynamic subtest eDP-1: SUCCESS (1.674s)
[503.628332] Subtest psr2-cursor-plane-onoff: SUCCESS (1.675s)
[504.420473] [104/133] (544s left) gem_exec_reloc (basic-gtt)
[504.475600] Subtest basic-gtt: SKIP (0.000s)
[504.542733] [105/133] (543s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[504.604213] Subtest writes-after-reads-snoop: SKIP (0.000s)
[504.672373] [106/133] (543s left) syncobj_wait (wait-all-snapshot)
[504.721254] Starting subtest: wait-all-snapshot
[504.801526] Subtest wait-all-snapshot: SUCCESS (0.081s)
[504.889296] [107/133] (543s left) prime_vgem (wait)
[504.964464] Starting subtest: wait
[504.966770] Starting dynamic subtest: rcs0
[505.041564] Dynamic subtest rcs0: SUCCESS (0.076s)
[505.052352] Starting dynamic subtest: bcs0
[505.105477] Dynamic subtest bcs0: SUCCESS (0.062s)
[505.114617] Starting dynamic subtest: vcs0
[505.147858] Dynamic subtest vcs0: SUCCESS (0.042s)
[505.157121] Starting dynamic subtest: vcs1
[505.196102] Dynamic subtest vcs1: SUCCESS (0.047s)
[505.205313] Starting dynamic subtest: vecs0
[505.241134] Dynamic subtest vecs0: SUCCESS (0.044s)
[505.250383] Starting dynamic subtest: ccs0
[505.288362] Dynamic subtest ccs0: SUCCESS (0.046s)
[505.290655] Subtest wait: SUCCESS (0.324s)
[505.409360] [108/133] (543s left) kms_vblank (query-idle)
[505.493286] Starting subtest: query-idle
[505.495051] Starting dynamic subtest: pipe-A-eDP-1
[507.240540] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.747s)
[507.255230] Starting dynamic subtest: pipe-D-eDP-1
[509.655617] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.413s)
[509.658015] Subtest query-idle: SUCCESS (4.163s)
[510.477078] [109/133] (538s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[510.582260] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[514.720231] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.140s)
[515.881263] [110/133] (532s left) syncobj_basic (bad-handle-to-fd)
[515.954528] Starting subtest: bad-handle-to-fd
[515.956475] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[516.063235] [111/133] (532s left) gem_mmap_offset (basic-uaf)
[516.113293] Starting subtest: basic-uaf
[516.115154] Subtest basic-uaf: SUCCESS (0.001s)
[516.210035] [112/133] (532s left) gem_eio (suspend)
[516.443265] Starting subtest: suspend
[528.728430] Subtest suspend: SUCCESS (12.284s)
[538.901171] [113/133] (519s left) api_intel_bb (intel-bb-blit-x)
[538.975406] Starting subtest: intel-bb-blit-x
[542.492015] Subtest intel-bb-blit-x: SUCCESS (3.516s)
[542.612772] [114/133] (516s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[542.718773] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[542.723094] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[542.841979] [115/133] (516s left) gem_fenced_exec_thrash (too-many-fences)
[542.899219] Subtest too-many-fences: SKIP (0.000s)
[542.966900] [116/133] (515s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[543.067133] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[546.851722] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.788s)
[548.012859] [117/133] (510s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[548.123344] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[548.145619] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.023s)
[548.243252] [118/133] (510s left) gem_wait (write-wait)
[548.316472] Starting subtest: write-wait
[548.318727] Starting dynamic subtest: all-engines
[549.380844] Dynamic subtest all-engines: SUCCESS (1.066s)
[549.398853] Starting dynamic subtest: rcs0
[550.436529] Dynamic subtest rcs0: SUCCESS (1.053s)
[550.454039] Starting dynamic subtest: bcs0
[551.492947] Dynamic subtest bcs0: SUCCESS (1.054s)
[551.510045] Starting dynamic subtest: vcs0
[552.547579] Dynamic subtest vcs0: SUCCESS (1.053s)
[552.567692] Starting dynamic subtest: vcs1
[553.600364] Dynamic subtest vcs1: SUCCESS (1.051s)
[553.616726] Starting dynamic subtest: vecs0
[554.654629] Dynamic subtest vecs0: SUCCESS (1.052s)
[554.671333] Starting dynamic subtest: ccs0
[555.710233] Dynamic subtest ccs0: SUCCESS (1.053s)
[555.713011] Subtest write-wait: SUCCESS (7.398s)
[555.912760] [119/133] (503s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[556.026858] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[556.029144] Starting dynamic subtest: pipe-A-eDP-1
[556.127622] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[556.138272] Starting dynamic subtest: pipe-B-eDP-1
[557.285117] Dynamic subtest pipe-B-eDP-1: SKIP (1.154s)
[557.289714] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.257s)
[558.467087] [120/133] (500s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[558.570704] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[558.573058] Starting dynamic subtest: pipe-A-eDP-1
[559.445488] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.869s)
[559.456636] Starting dynamic subtest: pipe-B-eDP-1
[561.067130] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.618s)
[561.077712] Starting dynamic subtest: pipe-C-eDP-1
[562.589025] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.522s)
[562.598658] Starting dynamic subtest: pipe-D-eDP-1
[564.128728] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.536s)
[564.130568] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.551s)
[565.308996] [121/133] (493s left) gem_mmap_gtt (basic-read)
[565.372067] Subtest basic-read: SKIP (0.000s)
[565.442461] [122/133] (493s left) kms_cdclk (mode-transition-all-outputs)
[565.535004] Starting subtest: mode-transition-all-outputs
[565.830093] Subtest mode-transition-all-outputs: SKIP (0.293s)
[566.642414] [123/133] (492s left) gem_exec_nop (basic-parallel)
[566.720377] Starting subtest: basic-parallel
[583.107254] Subtest basic-parallel: SUCCESS (16.395s)
[583.338615] [124/133] (475s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[583.439748] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[583.443984] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[583.534297] [125/133] (475s left) syncobj_timeline (wait-all-for-submit-snapshot)
[583.593354] Starting subtest: wait-all-for-submit-snapshot
[583.673891] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[583.766464] [126/133] (475s left) gem_exec_balancer (full-late)
[583.819159] Starting subtest: full-late
[587.999098] Subtest full-late: SUCCESS (4.179s)
[588.330057] [127/133] (471s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[588.451541] Starting subtest: flip-vs-dpms-off-vs-modeset
[588.454025] Starting dynamic subtest: A-eDP1
[592.074060] Dynamic subtest A-eDP1: SUCCESS (3.618s)
[592.084685] Starting dynamic subtest: B-eDP1
[595.279733] Dynamic subtest B-eDP1: SUCCESS (3.206s)
[595.292648] Starting dynamic subtest: C-eDP1
[598.383608] Dynamic subtest C-eDP1: SUCCESS (3.103s)
[598.394402] Starting dynamic subtest: D-eDP1
[601.492043] Dynamic subtest D-eDP1: SUCCESS (3.104s)
[601.494184] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.041s)
[602.294439] [128/133] (457s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[602.383997] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[609.779192] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.397s)
[609.926935] [129/133] (449s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[610.029274] Starting subtest: flip-primary-invalidate-overlay
[621.697315] Subtest flip-primary-invalidate-overlay: SUCCESS (11.668s)
[621.824032] [130/133] (437s left) kms_psr (psr2-cursor-blt)
[621.921279] Starting subtest: psr2-cursor-blt
[621.923063] Starting dynamic subtest: eDP-1
[623.631632] Dynamic subtest eDP-1: SUCCESS (1.711s)
[623.634036] Subtest psr2-cursor-blt: SUCCESS (1.712s)
[624.428156] [131/133] (435s left) gem_ctx_exec (basic-invalid-context)
[624.491528] Starting subtest: basic-invalid-context
[624.496696] Subtest basic-invalid-context: SUCCESS (0.004s)
[624.628464] [132/133] (435s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[624.720008] Starting subtest: primary-y-tiled-reflect-x-180
[624.790989] Subtest primary-y-tiled-reflect-x-180: SKIP (0.071s)
[624.899408] [133/133] (434s left) gem_eio (wait-10ms)
[625.134565] Starting subtest: wait-10ms
[625.509171] Subtest wait-10ms: SUCCESS (0.375s)
[625.649660] Closing watchdogs
results: parsing output: 0/ for test: perf
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_properties
results: parsing output: 3/ for test: gem_fenced_exec_thrash
results: parsing output: 4/ for test: api_intel_allocator
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_joiner
results: parsing output: 7/ for test: kms_chamelium_hpd
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: i915_drm_fdinfo
results: parsing output: 10/ for test: i915_pm_freq_api
results: parsing output: 11/ for test: kms_flip_scaled_crc
results: parsing output: 12/ for test: gem_pwrite
results: parsing output: 13/ for test: kms_atomic_transition
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_content_protection
results: parsing output: 16/ for test: kms_vrr
results: parsing output: 17/ for test: i915_pm_rpm
results: parsing output: 18/ for test: gem_exec_whisper
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: gem_fence_thrash
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_plane
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_plane_lowres
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: gem_bad_reloc
results: parsing output: 37/ for test: kms_busy
results: parsing output: 38/ for test: kms_pm_dc
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: gem_exec_fence
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_multipipe_modeset
results: parsing output: 44/ for test: gem_ctx_isolation
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_chamelium_frames
results: parsing output: 47/ for test: gem_tiled_swapping
results: parsing output: 48/ for test: gem_ctx_shared
results: parsing output: 49/ for test: i915_suspend
results: parsing output: 50/ for test: gem_render_copy
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: gem_ctx_persistence
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: syncobj_wait
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_async_flips
results: parsing output: 60/ for test: kms_pm_rpm
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_selftest
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_hdr
results: parsing output: 67/ for test: kms_color
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_dsc
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: gen9_exec_parse
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_huc_copy
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: gem_set_tiling_vs_blt
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_madvise
results: parsing output: 80/ for test: kms_async_flips
results: parsing output: 81/ for test: kms_addfb_basic
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: gem_exec_reloc
results: parsing output: 88/ for test: gem_pxp
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: kms_fbcon_fbt
results: parsing output: 91/ for test: kms_joiner
results: parsing output: 92/ for test: gem_exec_params
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: syncobj_basic
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: core_getversion
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_plane
results: parsing output: 101/ for test: gem_render_copy
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: gem_exec_reloc
results: parsing output: 104/ for test: gem_partial_pwrite_pread
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: prime_vgem
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: syncobj_basic
results: parsing output: 110/ for test: gem_mmap_offset
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: api_intel_bb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_fenced_exec_thrash
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: gem_wait
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: gem_mmap_gtt
results: parsing output: 121/ for test: kms_cdclk
results: parsing output: 122/ for test: gem_exec_nop
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: gem_exec_balancer
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_psr_stress_test
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: gem_ctx_exec
results: parsing output: 131/ for test: kms_rotation_crc
results: parsing output: 132/ for test: gem_eio
Done.