[57.505416] Initializing watchdogs
[57.505532]   /dev/watchdog0
[57.519427] [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.523141] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.525349] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.528486] [001/129] (960s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[57.643304] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[57.646094] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[57.756177] [002/129] (960s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[57.869134] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[57.871432] Starting dynamic subtest: pipe-A-default-mode
[59.043594] Dynamic subtest pipe-A-default-mode: SKIP (1.174s)
[59.048384] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.175s)
[59.174829] [003/129] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[59.279585] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[59.283794] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[59.375632] [004/129] (958s left) device_reset (unbind-reset-rebind)
[59.433399] Starting subtest: unbind-reset-rebind
[62.553318] Subtest unbind-reset-rebind: SUCCESS (3.127s)
[62.590382] [005/129] (955s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[62.707714] Starting subtest: 4-tiled-8bpp-rotate-0
[63.823271] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.118s)
[63.956317] [006/129] (954s left) kms_flip (2x-flip-vs-fences)
[64.066123] Starting subtest: 2x-flip-vs-fences
[64.070290] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[64.169376] [007/129] (954s left) kms_dp_link_training (non-uhbr-sst)
[64.266783] Starting subtest: non-uhbr-sst
[64.274363] Subtest non-uhbr-sst: SKIP (0.000s)
[64.339972] [008/129] (953s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[64.886141] Starting subtest: syncobj-backward-timeline-chain-engines
[64.968718] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.084s)
[66.001707] [FACT gem_exec_fence (syncobj-backward-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[66.004714] [009/129] (952s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[66.109353] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[66.121325] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[66.226689] [010/129] (952s left) kms_async_flips (test-cursor-atomic)
[66.322039] Starting subtest: test-cursor-atomic
[66.326469] Subtest test-cursor-atomic: SKIP (0.000s)
[67.506511] [011/129] (950s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[67.611264] Starting subtest: invalidate-primary-flip-overlay
[78.312602] Subtest invalidate-primary-flip-overlay: SUCCESS (10.712s)
[78.431902] [012/129] (939s left) perf (missing-sample-flags)
[78.540216] Starting subtest: missing-sample-flags
[78.542068] Subtest missing-sample-flags: SUCCESS (0.000s)
[78.574739] [013/129] (939s left) syncobj_wait (wait-any-complex)
[78.625291] Starting subtest: wait-any-complex
[78.686801] Subtest wait-any-complex: SUCCESS (0.061s)
[78.752939] [014/129] (939s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[78.850926] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[78.854462] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[78.957666] [015/129] (939s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[79.057397] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[79.067592] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[79.181187] [016/129] (939s left) kms_feature_discovery (display-2x)
[79.276116] Starting subtest: display-2x
[79.279521] Subtest display-2x: SKIP (0.000s)
[79.338321] [017/129] (939s left) gem_bad_reloc (negative-reloc-bltcopy)
[79.395270] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[79.494373] [018/129] (939s left) syncobj_timeline (invalid-wait-illegal-handle)
[79.551962] Starting subtest: invalid-wait-illegal-handle
[79.554059] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[79.681111] [019/129] (938s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[79.789501] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[81.057782] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.270s)
[81.243149] [020/129] (937s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[81.373837] Starting subtest: cursor-rapid-movement-32x32
[81.413188] Subtest cursor-rapid-movement-32x32: SKIP (0.036s)
[81.663322] [021/129] (937s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[81.769239] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[85.194591] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.430s)
[85.325817] [022/129] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt)
[85.438923] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt
[85.442711] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[85.540046] [023/129] (933s left) gem_barrier_race (remote-request)
[85.599265] Starting subtest: remote-request
[85.600965] Starting dynamic subtest: rcs0
[90.782832] Dynamic subtest rcs0: SUCCESS (5.184s)
[90.786603] Subtest remote-request: SUCCESS (5.184s)
[90.887646] [024/129] (927s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[90.960837] Starting subtest: map-fixed-invalidate-overlap-busy
[90.964630] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[91.094085] [025/129] (927s left) kms_plane_scaling (intel-max-src-size)
[91.192521] Starting subtest: intel-max-src-size
[91.198310] Subtest intel-max-src-size: SKIP (0.000s)
[91.286177] [026/129] (927s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[91.669389] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[92.429789] [027/129] (926s left) kms_pm_rpm (basic-pci-d3-state)
[94.065874] Starting subtest: basic-pci-d3-state
[95.530102] Subtest basic-pci-d3-state: SUCCESS (1.465s)
[95.623281] [028/129] (923s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[95.729528] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[95.733902] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[95.843047] [029/129] (923s left) gem_ctx_bad_destroy (double-destroy)
[95.907586] Starting subtest: double-destroy
[95.909717] Subtest double-destroy: SUCCESS (0.000s)
[95.977192] [030/129] (923s left) kms_universal_plane (universal-plane-functional)
[96.071407] Starting subtest: universal-plane-functional
[96.073758] Starting dynamic subtest: pipe-A-eDP-1
[99.914038] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.840s)
[99.922856] Starting dynamic subtest: pipe-B-eDP-1
[104.270694] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.356s)
[104.279225] Starting dynamic subtest: pipe-C-eDP-1
[108.499153] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.227s)
[108.508539] Starting dynamic subtest: pipe-D-eDP-1
[112.787762] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.288s)
[112.790517] Subtest universal-plane-functional: SUCCESS (16.715s)
[113.568636] [031/129] (905s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[113.675110] Starting subtest: crc-primary-basic-yf-tiled-ccs
[113.677512] Starting dynamic subtest: pipe-A-eDP-1
[113.701077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[113.709572] Starting dynamic subtest: pipe-B-eDP-1
[113.731103] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[113.738095] Starting dynamic subtest: pipe-C-eDP-1
[113.759114] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[113.766261] Starting dynamic subtest: pipe-D-eDP-1
[113.787321] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[113.791165] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.004s)
[115.833373] [032/129] (903s left) gem_ctx_persistence (engines-cleanup)
[115.896303] Starting subtest: engines-cleanup
[115.898229] Starting dynamic subtest: rcs0
[116.008604] Dynamic subtest rcs0: SUCCESS (0.110s)
[116.018726] Starting dynamic subtest: bcs0
[116.078640] Dynamic subtest bcs0: SUCCESS (0.071s)
[116.089097] Starting dynamic subtest: vcs0
[116.152067] Dynamic subtest vcs0: SUCCESS (0.072s)
[116.161536] Starting dynamic subtest: vcs1
[116.242062] Dynamic subtest vcs1: SUCCESS (0.089s)
[116.251524] Starting dynamic subtest: vecs0
[116.312950] Dynamic subtest vecs0: SUCCESS (0.070s)
[116.322816] Starting dynamic subtest: ccs0
[116.339335] Dynamic subtest ccs0: SUCCESS (0.026s)
[116.341568] Subtest engines-cleanup: SUCCESS (0.443s)
[116.472250] [033/129] (902s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[116.582234] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[121.739659] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (5.160s)
[121.869364] [034/129] (897s left) kms_hdmi_inject (inject-4k)
[122.205116] Starting subtest: inject-4k
[122.506387] Subtest inject-4k: SUCCESS (0.303s)
[123.041522] [035/129] (896s left) gem_exec_reloc (basic-wc-cpu-active)
[123.104773] Subtest basic-wc-cpu-active: SKIP (0.000s)
[123.156253] [036/129] (896s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[123.253709] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[123.258088] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[123.355362] [037/129] (895s left) kms_cursor_crc (cursor-offscreen-512x170)
[123.614607] Starting subtest: cursor-offscreen-512x170
[123.619176] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[123.742514] [038/129] (895s left) perf_pmu (invalid-open)
[123.781864] Starting subtest: invalid-open
[123.784320] Subtest invalid-open: SUCCESS (0.000s)
[123.893280] [039/129] (895s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[128.992935] Starting subtest: fbc-pr-cursor-plane-update-sf
[128.997755] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.002s)
[129.810030] [040/129] (889s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[129.859514] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[129.916850] [041/129] (889s left) i915_query (hwconfig_table)
[129.975681] Starting subtest: hwconfig_table
[130.280552] Subtest hwconfig_table: SUCCESS (0.001s)
[130.312078] [042/129] (889s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[130.411083] Starting subtest: primary-yf-tiled-reflect-x-270
[130.415620] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[130.477662] [043/129] (888s left) prime_mmap (test_forked_cpu_write)
[130.524448] Starting subtest: test_forked_cpu_write
[130.526749] Starting dynamic subtest: test_forked_cpu_write-smem
[130.532650] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.010s)
[130.534738] Subtest test_forked_cpu_write: SUCCESS (0.010s)
[130.639806] [044/129] (888s left) i915_hangman (engine-error-state-capture)
[130.699720] Starting subtest: engine-error-state-capture
[130.702261] Starting dynamic subtest: rcs0
[133.896976] Dynamic subtest rcs0: SUCCESS (3.197s)
[133.908243] Starting dynamic subtest: bcs0
[139.912493] Dynamic subtest bcs0: SUCCESS (6.014s)
[139.922342] Starting dynamic subtest: vcs0
[145.864456] Dynamic subtest vcs0: SUCCESS (5.950s)
[145.874723] Starting dynamic subtest: vcs1
[151.879648] Dynamic subtest vcs1: SUCCESS (6.013s)
[151.890777] Starting dynamic subtest: vecs0
[157.895584] Dynamic subtest vecs0: SUCCESS (6.014s)
[157.909190] Starting dynamic subtest: ccs0
[163.912054] Dynamic subtest ccs0: SUCCESS (6.014s)
[163.914904] Subtest engine-error-state-capture: SUCCESS (33.213s)
[164.046334] [045/129] (855s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[164.161375] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[164.165273] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[164.274459] [046/129] (855s left) gem_watchdog (default-virtual)
[164.352632] Starting subtest: default-virtual
[170.625423] Subtest default-virtual: SUCCESS (6.283s)
[170.725061] [047/129] (848s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[170.784614] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[170.787134] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[170.895914] [048/129] (848s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[171.006296] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[171.010585] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[171.121926] [049/129] (848s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[171.220309] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[171.224748] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[171.327182] [050/129] (848s left) kms_frontbuffer_tracking (fbc-tiling-4)
[171.431030] Starting subtest: fbc-tiling-4
[174.616198] Subtest fbc-tiling-4: SUCCESS (3.190s)
[174.739029] [051/129] (844s left) gem_pwrite (basic-exhaustion)
[174.800027] Subtest basic-exhaustion: SKIP (0.000s)
[174.855984] [052/129] (844s left) gem_exec_schedule (u-semaphore-codependency)
[174.928396] Starting subtest: u-semaphore-codependency
[174.934763] Subtest u-semaphore-codependency: SUCCESS (0.012s)
[175.274377] [053/129] (844s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[175.377847] Starting subtest: fbc-pr-cursor-mmap-cpu
[175.382165] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[175.441868] [054/129] (844s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[175.537864] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[175.542357] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[175.632388] [055/129] (844s left) prime_busy (hang-wait)
[175.682142] Starting subtest: hang-wait
[175.684465] Starting dynamic subtest: rcs0
[201.407571] Dynamic subtest rcs0: SUCCESS (25.722s)
[201.414710] Starting dynamic subtest: bcs0
[208.503123] Dynamic subtest bcs0: SUCCESS (7.093s)
[208.510604] Starting dynamic subtest: vcs0
[215.605969] Dynamic subtest vcs0: SUCCESS (7.100s)
[215.612978] Starting dynamic subtest: vcs1
[222.519956] Dynamic subtest vcs1: SUCCESS (6.914s)
[222.527167] Starting dynamic subtest: vecs0
[229.556722] Dynamic subtest vecs0: SUCCESS (7.034s)
[229.566124] Starting dynamic subtest: ccs0
[243.391924] Dynamic subtest ccs0: SUCCESS (13.834s)
[243.394702] Subtest hang-wait: SUCCESS (67.707s)
[243.529535] [056/129] (776s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[243.632485] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[243.636737] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[243.746768] [057/129] (776s left) gem_ctx_engines (invalid-engines)
[243.809178] Starting subtest: invalid-engines
[243.816650] Subtest invalid-engines: SUCCESS (0.007s)
[244.061072] [058/129] (775s left) gem_render_copy (y-tiled-to-vebox-linear)
[244.126297] Starting subtest: y-tiled-to-vebox-linear
[244.131026] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[244.307126] [059/129] (775s left) kms_psr (psr2-basic)
[244.395361] Starting subtest: psr2-basic
[244.397697] Starting dynamic subtest: eDP-1
[246.035332] Dynamic subtest eDP-1: SUCCESS (1.636s)
[246.037676] Subtest psr2-basic: SUCCESS (1.637s)
[246.907379] [060/129] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[247.016289] Starting subtest: psr-2p-primscrn-spr-indfb-move
[247.020614] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[247.120644] [061/129] (772s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[247.229213] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[247.233511] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[247.342341] [062/129] (772s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[247.455268] Starting subtest: psr-rgb565-draw-mmap-wc
[252.002904] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (4.550s)
[252.131906] [063/129] (768s left) kms_cursor_crc (cursor-onscreen-64x64)
[252.300674] Starting subtest: cursor-onscreen-64x64
[252.305522] Starting dynamic subtest: pipe-A-eDP-1
[254.580133] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.275s)
[254.607333] Starting dynamic subtest: pipe-D-eDP-1
[257.436476] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.836s)
[257.438885] Subtest cursor-onscreen-64x64: SUCCESS (5.135s)
[258.297063] [064/129] (761s left) gem_userptr_blits (usage-restrictions)
[258.354266] Starting subtest: usage-restrictions
[258.356184] Subtest usage-restrictions: SUCCESS (0.000s)
[258.477793] [065/129] (761s left) drm_fdinfo (memory-info-shared)
[258.524971] Starting subtest: memory-info-shared
[258.527317] Starting dynamic subtest: smem0
[258.579863] Dynamic subtest smem0: SUCCESS (0.057s)
[258.582715] Subtest memory-info-shared: SUCCESS (0.058s)
[258.646540] [066/129] (761s left) syncobj_timeline (multi-wait-for-submit-signaled)
[258.705088] Starting subtest: multi-wait-for-submit-signaled
[258.707402] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[258.816163] [067/129] (761s left) api_intel_bb (simple-bb)
[258.870738] Starting subtest: simple-bb
[258.875933] Subtest simple-bb: SUCCESS (0.006s)
[259.015749] [068/129] (761s left) kms_psr (fbc-psr-primary-mmap-gtt)
[259.113070] Starting subtest: fbc-psr-primary-mmap-gtt
[259.114959] Starting dynamic subtest: eDP-1
[259.119056] Dynamic subtest eDP-1: SKIP (0.000s)
[259.123063] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[259.180466] [069/129] (761s left) gem_exec_params (rel-constants-invalid-ring)
[259.239467] Starting subtest: rel-constants-invalid-ring
[259.241880] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[259.345723] [070/129] (761s left) gem_ctx_persistence (legacy-engines-queued)
[259.416349] Starting subtest: legacy-engines-queued
[259.418215] Starting dynamic subtest: render
[259.500581] Dynamic subtest render: SUCCESS (0.086s)
[259.510159] Starting dynamic subtest: bsd1
[260.873056] Dynamic subtest bsd1: SUCCESS (1.368s)
[260.883984] Starting dynamic subtest: bsd2
[262.919140] Dynamic subtest bsd2: SUCCESS (2.043s)
[262.930249] Starting dynamic subtest: blt
[263.007998] Dynamic subtest blt: SUCCESS (0.087s)
[263.018303] Starting dynamic subtest: vebox
[264.904063] Dynamic subtest vebox: SUCCESS (1.892s)
[264.907033] Subtest legacy-engines-queued: SUCCESS (5.489s)
[264.979123] [071/129] (755s left) kms_plane_lowres (tiling-4)
[265.072171] Starting subtest: tiling-4
[265.074571] Starting dynamic subtest: pipe-A-eDP-1
[265.079375] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[265.375532] Starting dynamic subtest: pipe-B-eDP-1
[265.380220] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[265.389746] Starting dynamic subtest: pipe-C-eDP-1
[265.394382] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[265.401652] Starting dynamic subtest: pipe-D-eDP-1
[265.406286] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[265.410834] Subtest tiling-4: SKIP (0.301s)
[266.193919] [072/129] (754s left) kms_psr (fbc-psr2-sprite-blt)
[266.306736] Starting subtest: fbc-psr2-sprite-blt
[266.308676] Starting dynamic subtest: eDP-1
[266.312670] Dynamic subtest eDP-1: SKIP (0.000s)
[266.316669] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[266.375364] [073/129] (754s left) kms_psr (psr2-suspend)
[266.464445] Starting subtest: psr2-suspend
[266.466691] Starting dynamic subtest: eDP-1
[284.336806] Dynamic subtest eDP-1: SUCCESS (2.864s)
[284.339075] Subtest psr2-suspend: SUCCESS (2.864s)
[285.181656] [074/129] (750s left) syncobj_timeline (wait-all-for-submit-complex)
[285.246527] Starting subtest: wait-all-for-submit-complex
[285.461068] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[285.538386] [075/129] (750s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[285.649863] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[290.799296] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.189s)
[290.951856] [076/129] (744s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[291.063367] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[291.067196] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[291.152748] [077/129] (744s left) gem_userptr_blits (readonly-unsync)
[291.216217] Subtest readonly-unsync: SKIP (0.000s)
[291.366280] [078/129] (744s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[291.427077] Starting subtest: rel-constants-invalid-rel-gen5
[291.429316] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[291.530485] [079/129] (744s left) api_intel_allocator (two-level-inception-interruptible)
[291.585901] Starting subtest: two-level-inception-interruptible
[296.983065] Subtest two-level-inception-interruptible: SUCCESS (5.416s)
[297.111914] [080/129] (738s left) gem_fence_thrash (bo-write-verify-none)
[297.150379] Starting subtest: bo-write-verify-none
[297.177189] Subtest bo-write-verify-none: SKIP (0.026s)
[297.242869] [081/129] (738s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[297.354797] Starting subtest: x-tiled-32bpp-rotate-180
[299.096306] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.746s)
[299.257191] [082/129] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[299.361239] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[299.364656] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[299.477840] [083/129] (736s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[299.606534] Starting subtest: cursorA-vs-flipA-atomic
[310.740815] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.148s)
[311.604064] [084/129] (724s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[311.717029] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[311.719412] Starting dynamic subtest: A-eDP1
[315.319327] Dynamic subtest A-eDP1: SUCCESS (3.601s)
[315.328825] Starting dynamic subtest: B-eDP1
[318.615835] Dynamic subtest B-eDP1: SUCCESS (3.300s)
[318.624346] Starting dynamic subtest: C-eDP1
[321.914926] Dynamic subtest C-eDP1: SUCCESS (3.297s)
[321.925430] Starting dynamic subtest: D-eDP1
[325.212704] Dynamic subtest D-eDP1: SUCCESS (3.301s)
[325.215133] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.506s)
[326.081946] [085/129] (709s left) gem_userptr_blits (process-exit-busy)
[326.155723] Starting subtest: process-exit-busy
[326.169534] Subtest process-exit-busy: SUCCESS (0.014s)
[326.288082] [086/129] (709s left) gem_exec_schedule (pi-shared-iova)
[326.480570] Starting subtest: pi-shared-iova
[326.483038] Starting dynamic subtest: rcs0
[326.549434] Dynamic subtest rcs0: SUCCESS (0.073s)
[326.560921] Starting dynamic subtest: vcs0
[326.596265] Dynamic subtest vcs0: SUCCESS (0.046s)
[326.607472] Starting dynamic subtest: vcs1
[326.647704] Dynamic subtest vcs1: SUCCESS (0.050s)
[326.658619] Starting dynamic subtest: vecs0
[326.690998] Dynamic subtest vecs0: SUCCESS (0.043s)
[326.703041] Starting dynamic subtest: ccs0
[326.741216] Dynamic subtest ccs0: SUCCESS (0.050s)
[326.743620] Subtest pi-shared-iova: SUCCESS (0.265s)
[328.084556] [087/129] (707s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[328.185219] Starting subtest: plane-upscale-20x20-with-pixel-format
[328.187446] Starting dynamic subtest: pipe-A
[329.233927] Dynamic subtest pipe-A: SUCCESS (1.050s)
[329.242811] Starting dynamic subtest: pipe-B
[331.354672] Dynamic subtest pipe-B: SUCCESS (2.120s)
[331.362929] Starting dynamic subtest: pipe-C
[332.521245] Dynamic subtest pipe-C: SUCCESS (1.167s)
[332.530801] Starting dynamic subtest: pipe-D
[333.722373] Dynamic subtest pipe-D: SUCCESS (1.201s)
[333.724747] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.541s)
[334.875499] [088/129] (701s left) gem_exec_fence (expired-history)
[335.317333] Starting subtest: expired-history
[342.709922] Subtest expired-history: SUCCESS (7.397s)
[343.247342] [089/129] (692s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[343.346974] Starting subtest: bad-rotation-90-y-tiled-ccs
[343.349303] Starting dynamic subtest: pipe-A-eDP-1
[343.372035] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[343.381420] Starting dynamic subtest: pipe-B-eDP-1
[343.403216] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[343.411643] Starting dynamic subtest: pipe-C-eDP-1
[343.432403] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[343.440837] Starting dynamic subtest: pipe-D-eDP-1
[343.463326] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[343.467803] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.004s)
[345.568801] [090/129] (690s left) gem_pxp (verify-pxp-stale-buf-execution)
[345.691750] Starting subtest: verify-pxp-stale-buf-execution
[346.749030] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.059s)
[346.879649] [091/129] (689s left) kms_fence_pin_leak
[347.052154] [092/129] (689s left) kms_rotation_crc (sprite-rotation-270)
[347.147991] Starting subtest: sprite-rotation-270
[347.152640] Subtest sprite-rotation-270: SKIP (0.001s)
[347.215370] [093/129] (688s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[347.301186] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[347.303492] Starting dynamic subtest: pipe-A
[347.318376] Dynamic subtest pipe-A: SKIP (0.012s)
[347.326738] Starting dynamic subtest: pipe-B
[347.344694] Dynamic subtest pipe-B: SKIP (0.022s)
[347.354086] Starting dynamic subtest: pipe-C
[347.383843] Dynamic subtest pipe-C: SKIP (0.021s)
[347.392917] Starting dynamic subtest: pipe-D
[347.401330] Dynamic subtest pipe-D: SKIP (0.023s)
[347.405234] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.082s)
[347.495618] [094/129] (688s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[347.608498] Starting subtest: yf-tiled-32bpp-rotate-270
[347.612731] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[347.700521] [095/129] (688s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[352.735176] Starting subtest: pr-primary-plane-update-sf-dmg-area
[352.739672] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s)
[353.566886] [096/129] (682s left) gem_mmap_offset (blt-coherency)
[353.628793] Starting subtest: blt-coherency
[353.634113] Subtest blt-coherency: SUCCESS (0.007s)
[353.756060] [097/129] (682s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[353.804441] Starting subtest: etime-multi-wait-all-available-unsubmitted
[353.908079] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.101s)
[353.976901] [098/129] (682s left) gem_spin_batch (spin-each)
[354.049626] Starting subtest: spin-each
[357.321708] Subtest spin-each: SUCCESS (3.270s)
[357.989512] [099/129] (678s left) kms_legacy_colorkey (invalid-plane)
[358.092801] Starting subtest: invalid-plane
[358.096137] Starting dynamic subtest: zero-id
[358.098443] Dynamic subtest zero-id: SUCCESS (0.000s)
[358.112931] Starting dynamic subtest: outrange-id
[358.115720] Dynamic subtest outrange-id: SUCCESS (0.000s)
[358.118486] Subtest invalid-plane: SUCCESS (0.001s)
[358.162405] [100/129] (678s left) kms_big_fb (linear-16bpp-rotate-270)
[358.262932] Starting subtest: linear-16bpp-rotate-270
[358.282166] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[358.414021] [101/129] (677s left) gen9_exec_parse (bb-start-far)
[358.479922] Subtest bb-start-far: SKIP (0.000s)
[358.541195] [102/129] (677s left) gem_exec_whisper (basic-fds)
[358.607639] Starting subtest: basic-fds
[365.929826] Subtest basic-fds: SUCCESS (7.321s)
[366.110475] [103/129] (670s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[366.491556] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[367.332124] [104/129] (669s left) kms_vblank (wait-forked-busy-hang)
[367.433978] Starting subtest: wait-forked-busy-hang
[367.436467] Starting dynamic subtest: pipe-A-eDP-1
[393.668595] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.230s)
[393.683233] Starting dynamic subtest: pipe-D-eDP-1
[408.764589] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.090s)
[408.767047] Subtest wait-forked-busy-hang: SUCCESS (41.324s)
[409.557676] [105/129] (626s left) syncobj_wait (multi-wait-signaled)
[409.613798] Starting subtest: multi-wait-signaled
[409.616274] Subtest multi-wait-signaled: SUCCESS (0.000s)
[409.731344] [106/129] (626s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[414.822654] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[414.824916] Starting dynamic subtest: pipe-A-eDP-1
[415.606335] Dynamic subtest pipe-A-eDP-1: SKIP (0.779s)
[415.615691] Starting dynamic subtest: pipe-B-eDP-1
[416.709308] Dynamic subtest pipe-B-eDP-1: SKIP (1.102s)
[416.713839] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.885s)
[417.858041] [107/129] (618s left) kms_setmode (invalid-clone-single-crtc)
[417.918688] Starting subtest: invalid-clone-single-crtc
[417.925382] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[418.025499] [108/129] (618s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[418.131980] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[421.440669] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.314s)
[421.571971] [109/129] (615s left) perf_pmu (event-wait)
[421.614154] Starting subtest: event-wait
[421.616178] Starting dynamic subtest: rcs0
[421.620043] Dynamic subtest rcs0: SKIP (0.000s)
[421.623641] Subtest event-wait: SKIP (0.001s)
[421.727749] [110/129] (614s left) prime_mmap (test_forked)
[421.787807] Starting subtest: test_forked
[421.789809] Starting dynamic subtest: test_forked-smem
[421.825786] Dynamic subtest test_forked-smem: SUCCESS (0.040s)
[421.827967] Subtest test_forked: SUCCESS (0.041s)
[421.921170] [111/129] (614s left) kms_flip (2x-flip-vs-panning-interruptible)
[422.025040] Starting subtest: 2x-flip-vs-panning-interruptible
[422.037094] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s)
[422.111333] [112/129] (614s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[422.232481] Starting subtest: 2x-long-flip-vs-cursor-legacy
[422.236125] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[422.325211] [113/129] (614s left) kms_lease (atomic-implicit-crtc)
[422.425762] Starting subtest: atomic-implicit-crtc
[422.427810] Starting dynamic subtest: pipe-A-eDP-1
[422.437278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[422.447047] Starting dynamic subtest: pipe-B-eDP-1
[422.456770] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[422.466224] Starting dynamic subtest: pipe-C-eDP-1
[422.475120] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[422.482778] Starting dynamic subtest: pipe-D-eDP-1
[422.491739] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[422.493376] Subtest atomic-implicit-crtc: SUCCESS (0.026s)
[422.549753] [114/129] (614s left) gem_exec_params (invalid-batch-start-offset)
[422.602248] Starting subtest: invalid-batch-start-offset
[422.604340] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[422.715661] [115/129] (614s left) gem_exec_flush (basic-wb-rw-before-default)
[422.776450] Starting subtest: basic-wb-rw-before-default
[428.249625] Subtest basic-wb-rw-before-default: SUCCESS (5.473s)
[428.431288] [116/129] (608s left) gem_exec_reloc (basic-softpin)
[428.490430] Subtest basic-softpin: SKIP (0.000s)
[428.569988] [117/129] (608s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[428.669203] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[428.671417] Starting dynamic subtest: pipe-A-eDP-1
[428.691576] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[428.700523] Starting dynamic subtest: pipe-B-eDP-1
[428.719853] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[428.727898] Starting dynamic subtest: pipe-C-eDP-1
[428.747170] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[428.755608] Starting dynamic subtest: pipe-D-eDP-1
[428.775375] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[428.777572] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[430.850358] [118/129] (606s left) kms_chamelium_edid (hdmi-edid-read)
[431.237160] Subtest hdmi-edid-read: SKIP (0.000s)
[432.030496] [119/129] (605s left) gem_exec_parallel (basic)
[432.096398] Starting subtest: basic
[432.098757] Starting dynamic subtest: rcs0
[433.553234] Dynamic subtest rcs0: SUCCESS (1.457s)
[433.569716] Starting dynamic subtest: bcs0
[435.036520] Dynamic subtest bcs0: SUCCESS (1.481s)
[435.048215] Starting dynamic subtest: vcs0
[436.936161] Dynamic subtest vcs0: SUCCESS (1.897s)
[436.946277] Starting dynamic subtest: vcs1
[438.919243] Dynamic subtest vcs1: SUCCESS (1.982s)
[438.929279] Starting dynamic subtest: vecs0
[440.903885] Dynamic subtest vecs0: SUCCESS (1.983s)
[440.915087] Starting dynamic subtest: ccs0
[442.410831] Dynamic subtest ccs0: SUCCESS (1.507s)
[442.413245] Subtest basic: SUCCESS (10.317s)
[442.598027] [120/129] (594s left) gem_exec_balancer (full-pulse)
[442.665410] Starting subtest: full-pulse
[447.681721] Subtest full-pulse: SUCCESS (5.017s)
[447.983878] [121/129] (589s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[448.092231] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[448.094356] Starting dynamic subtest: pipe-A-eDP-1
[448.114236] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[448.124255] Starting dynamic subtest: pipe-B-eDP-1
[448.143254] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[448.151391] Starting dynamic subtest: pipe-C-eDP-1
[448.170944] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[448.179593] Starting dynamic subtest: pipe-D-eDP-1
[448.199318] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[448.201531] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[450.342110] [122/129] (586s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[450.444303] Starting subtest: random-ccs-data-y-tiled-ccs
[450.446620] Starting dynamic subtest: pipe-A-eDP-1
[450.471221] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[450.480007] Starting dynamic subtest: pipe-B-eDP-1
[450.503523] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[450.512086] Starting dynamic subtest: pipe-C-eDP-1
[450.537032] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[450.545183] Starting dynamic subtest: pipe-D-eDP-1
[450.568830] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[450.572828] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[452.647427] [123/129] (584s left) drm_read (fault-buffer)
[452.808476] Starting subtest: fault-buffer
[452.810949] Subtest fault-buffer: SUCCESS (0.001s)
[452.950320] [124/129] (584s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[453.058860] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[457.575689] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (4.520s)
[457.691839] [125/129] (579s left) gem_lmem_swapping (heavy-verify-random)
[457.736782] Subtest heavy-verify-random: SKIP (0.000s)
[457.778519] [126/129] (579s left) kms_cursor_legacy (forked-bo)
[457.905864] Starting subtest: forked-bo
[457.908078] Starting dynamic subtest: pipe-A
[463.395718] Dynamic subtest pipe-A: SUCCESS (5.487s)
[463.410760] Starting dynamic subtest: pipe-B
[468.893804] Dynamic subtest pipe-B: SUCCESS (5.500s)
[468.905264] Starting dynamic subtest: pipe-C
[474.383909] Dynamic subtest pipe-C: SUCCESS (5.487s)
[474.396812] Starting dynamic subtest: pipe-D
[479.868822] Dynamic subtest pipe-D: SUCCESS (5.487s)
[479.880169] Starting dynamic subtest: all-pipes
[485.418685] Dynamic subtest all-pipes: SUCCESS (5.549s)
[485.420899] Subtest forked-bo: SUCCESS (27.520s)
[485.548745] [127/129] (551s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[485.655292] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[485.657515] Starting dynamic subtest: pipe-A-default-mode
[486.757135] Dynamic subtest pipe-A-default-mode: SKIP (1.098s)
[486.762024] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.099s)
[486.895330] [128/129] (550s left) i915_pm_rpm (system-suspend)
[489.026893] Starting subtest: system-suspend
[507.727860] Subtest system-suspend: SUCCESS (2.904s)
[508.073574] [129/129] (545s left) kms_flip (dpms-vs-vblank-race)
[508.174918] Starting subtest: dpms-vs-vblank-race
[508.177237] Starting dynamic subtest: A-eDP1
[512.099956] Dynamic subtest A-eDP1: SUCCESS (3.956s)
[512.108855] Starting dynamic subtest: B-eDP1
[515.564966] Dynamic subtest B-eDP1: SUCCESS (3.484s)
[515.575329] Starting dynamic subtest: C-eDP1
[519.054354] Dynamic subtest C-eDP1: SUCCESS (3.500s)
[519.063946] Starting dynamic subtest: D-eDP1
[523.630170] Dynamic subtest D-eDP1: SUCCESS (4.585s)
[523.632503] Subtest dpms-vs-vblank-race: SUCCESS (15.533s)
[524.474272] Closing watchdogs
Done.