[57.241125] Initializing watchdogs
[57.241174]   /dev/watchdog0
[57.256319] [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.260693] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.263094] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.266743] [001/133] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[57.357669] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[57.360208] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[57.471376] [002/133] (960s left) kms_color (ctm-max)
[57.581888] Starting subtest: ctm-max
[57.584285] Starting dynamic subtest: pipe-A-eDP-1
[58.316546] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.733s)
[58.325555] Starting dynamic subtest: pipe-B-eDP-1
[59.772636] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.453s)
[59.780264] Starting dynamic subtest: pipe-C-eDP-1
[61.148949] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.376s)
[61.157461] Starting dynamic subtest: pipe-D-eDP-1
[62.480599] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.330s)
[62.483081] Subtest ctm-max: SUCCESS (4.893s)
[63.294705] [003/133] (954s left) gem_eio (in-flight-1us)
[63.465794] Starting subtest: in-flight-1us
[70.818645] Subtest in-flight-1us: SUCCESS (7.347s)
[70.990148] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[70.993106] [004/133] (946s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[71.120616] Starting subtest: basic-busy-flip-before-cursor-legacy
[71.125031] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[71.263778] [005/133] (946s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[71.363757] Starting subtest: fbc-psr2-sprite-plane-onoff
[71.365668] Starting dynamic subtest: eDP-1
[71.369729] Dynamic subtest eDP-1: SKIP (0.000s)
[71.373799] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.000s)
[71.434933] [006/133] (946s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[71.536962] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[71.539067] Starting dynamic subtest: pipe-A-eDP-1
[71.715923] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.167s)
[71.726786] Starting dynamic subtest: pipe-B-eDP-1
[71.747050] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[71.756889] Starting dynamic subtest: pipe-C-eDP-1
[71.777790] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[71.785726] Starting dynamic subtest: pipe-D-eDP-1
[71.805524] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[71.807798] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.204s)
[74.052546] [007/133] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[74.157118] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[74.161133] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[74.241623] [008/133] (943s left) kms_flip (flip-vs-panning)
[74.349335] Starting subtest: flip-vs-panning
[74.351507] Starting dynamic subtest: A-eDP1
[83.081616] Dynamic subtest A-eDP1: SUCCESS (8.731s)
[83.090807] Starting dynamic subtest: B-eDP1
[91.801766] Dynamic subtest B-eDP1: SUCCESS (8.718s)
[91.810248] Starting dynamic subtest: C-eDP1
[100.534922] Dynamic subtest C-eDP1: SUCCESS (8.731s)
[100.543074] Starting dynamic subtest: D-eDP1
[109.260508] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[109.264226] Subtest flip-vs-panning: SUCCESS (34.913s)
[110.415738] [009/133] (907s left) gem_exec_fence (submit3)
[110.600940] Starting subtest: submit3
[110.605719] Subtest submit3: SKIP (0.000s)
[111.980910] [010/133] (906s left) api_intel_allocator (gem-pool)
[112.047478] Starting subtest: gem-pool
[112.053321] Subtest gem-pool: SUCCESS (0.009s)
[112.145019] [011/133] (906s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[112.205588] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[112.274307] [012/133] (905s left) i915_pm_rpm (sysfs-read)
[114.001754] Starting subtest: sysfs-read
[115.151932] Subtest sysfs-read: SUCCESS (1.149s)
[115.555998] [013/133] (902s left) kms_cursor_crc (cursor-onscreen-64x64)
[115.742859] Starting subtest: cursor-onscreen-64x64
[115.747728] Starting dynamic subtest: pipe-A-eDP-1
[117.892297] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.145s)
[117.922274] Starting dynamic subtest: pipe-D-eDP-1
[120.713367] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.796s)
[120.715690] Subtest cursor-onscreen-64x64: SUCCESS (4.968s)
[121.601859] [014/133] (896s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[121.714494] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[121.718706] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[121.842255] [015/133] (896s left) gem_userptr_blits (forbidden-operations)
[121.914069] Starting subtest: forbidden-operations
[121.918293] Subtest forbidden-operations: SKIP (0.000s)
[122.052757] [016/133] (896s left) kms_big_fb (linear-64bpp-rotate-270)
[122.152387] Starting subtest: linear-64bpp-rotate-270
[122.171799] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[122.297976] [017/133] (896s left) kms_atomic_transition (plane-all-transition)
[122.411068] Starting subtest: plane-all-transition
[122.413220] Starting dynamic subtest: pipe-A-eDP-1
[124.906139] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[125.230117] Starting dynamic subtest: pipe-B-eDP-1
[128.443681] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.224s)
[128.743327] Subtest plane-all-transition: SUCCESS (6.331s)
[129.564727] [018/133] (888s left) api_intel_bb (reset-flags)
[129.635490] Starting subtest: reset-flags
[129.641133] Subtest reset-flags: SUCCESS (0.007s)
[129.773092] [019/133] (888s left) gem_sync (basic-many-each)
[129.846309] Starting subtest: basic-many-each
[132.131637] Subtest basic-many-each: SUCCESS (2.295s)
[132.314886] [020/133] (886s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[132.420363] Starting subtest: yf-tiled-8bpp-rotate-0
[132.424453] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[132.518376] [021/133] (886s left) gem_exec_balancer (nohangcheck)
[132.770233] Starting subtest: nohangcheck
[133.702031] Subtest nohangcheck: SUCCESS (0.931s)
[133.816306] [022/133] (884s left) kms_plane_alpha_blend (alpha-7efc)
[133.899931] Starting subtest: alpha-7efc
[134.181619] Starting dynamic subtest: pipe-A-eDP-1
[134.782118] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s)
[134.809413] Starting dynamic subtest: pipe-D-eDP-1
[136.097890] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.293s)
[136.100111] Subtest alpha-7efc: SUCCESS (2.198s)
[136.922527] [023/133] (881s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[137.016519] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[137.020626] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[137.119886] [024/133] (881s left) gem_softpin (evict-snoop-interruptible)
[137.183632] Starting subtest: evict-snoop-interruptible
[137.187616] Subtest evict-snoop-interruptible: SKIP (0.000s)
[137.296256] [025/133] (881s left) kms_hdmi_inject (inject-audio)
[137.633489] Starting subtest: inject-audio
[137.915067] Subtest inject-audio: SUCCESS (0.283s)
[138.487303] [026/133] (880s left) gem_bad_reloc (negative-reloc-bltcopy)
[138.540106] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[138.603430] [027/133] (880s left) gem_userptr_blits (unsync-overlap)
[138.665626] Subtest unsync-overlap: SKIP (0.000s)
[138.774675] [028/133] (880s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[138.903283] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[138.907706] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[139.047683] [029/133] (879s left) gem_eio (in-flight-internal-immediate)
[139.295769] Starting subtest: in-flight-internal-immediate
[140.973355] Subtest in-flight-internal-immediate: SUCCESS (1.675s)
[141.118065] [030/133] (877s left) kms_pm_backlight (brightness-with-dpms)
[141.211973] Starting subtest: brightness-with-dpms
[141.262052] Starting dynamic subtest: eDP-1
[143.710028] Dynamic subtest eDP-1: SUCCESS (2.448s)
[143.712648] Subtest brightness-with-dpms: SUCCESS (2.500s)
[144.581699] [031/133] (874s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[144.649309] Starting subtest: y-tiled-ccs-to-yf-tiled
[144.653443] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[144.838605] [032/133] (874s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[144.926334] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[144.930659] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[145.013163] [033/133] (874s left) dmabuf_sync_file (import-multiple-read-only)
[145.058115] Starting subtest: import-multiple-read-only
[145.060258] Subtest import-multiple-read-only: SUCCESS (0.003s)
[145.112921] [034/133] (873s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[145.159943] Starting subtest: framebuffer-vs-set-tiling
[145.164090] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[145.280207] [035/133] (873s left) gem_exec_capture (userptr)
[145.344867] Starting subtest: userptr
[146.185705] Subtest userptr: SUCCESS (0.842s)
[146.312620] [036/133] (872s left) fbdev (nullptr)
[146.352963] Starting subtest: nullptr
[146.355292] Subtest nullptr: SUCCESS (0.000s)
[146.405960] [037/133] (872s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[146.517376] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[152.160516] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.645s)
[153.291226] [038/133] (865s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[153.400578] Starting subtest: x-tiled-64bpp-rotate-270
[153.422724] Subtest x-tiled-64bpp-rotate-270: SKIP (0.022s)
[153.553453] [039/133] (865s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[153.664508] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[153.668865] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[153.757829] [040/133] (865s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[153.855115] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[153.859199] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[153.953633] [041/133] (865s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[154.042736] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[154.047188] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[154.115149] [042/133] (865s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[154.175372] Subtest writes-after-reads-uncached: SKIP (0.000s)
[154.244376] [043/133] (865s left) kms_hdr (static-toggle-dpms)
[154.334645] Starting subtest: static-toggle-dpms
[154.340222] Subtest static-toggle-dpms: SKIP (0.000s)
[154.405208] [044/133] (865s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[154.466378] Starting subtest: etime-multi-wait-for-submit-submitted
[154.571102] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.102s)
[154.646354] [045/133] (864s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[154.753248] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[154.757233] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[154.843895] [046/133] (864s left) perf_pmu (idle)
[154.884650] Starting subtest: idle
[154.886902] Starting dynamic subtest: rcs0
[155.448056] Dynamic subtest rcs0: SUCCESS (0.561s)
[155.457817] Starting dynamic subtest: bcs0
[155.969722] Dynamic subtest bcs0: SUCCESS (0.519s)
[155.979268] Starting dynamic subtest: vcs0
[156.498633] Dynamic subtest vcs0: SUCCESS (0.522s)
[156.509750] Starting dynamic subtest: vcs1
[157.018380] Dynamic subtest vcs1: SUCCESS (0.521s)
[157.029768] Starting dynamic subtest: vecs0
[157.543981] Dynamic subtest vecs0: SUCCESS (0.523s)
[157.556239] Starting dynamic subtest: ccs0
[158.060603] Dynamic subtest ccs0: SUCCESS (0.514s)
[158.063415] Subtest idle: SUCCESS (3.175s)
[158.152903] [047/133] (861s left) kms_chamelium_color (degamma)
[158.252070] Subtest degamma: SKIP (0.000s)
[158.296812] [048/133] (861s left) gem_pxp (create-regular-context-2)
[158.423915] Starting subtest: create-regular-context-2
[158.426414] Subtest create-regular-context-2: SUCCESS (0.002s)
[158.528142] [049/133] (861s left) gem_softpin (allocator-basic-reserve)
[158.588502] Starting subtest: allocator-basic-reserve
[158.656499] Subtest allocator-basic-reserve: SUCCESS (0.069s)
[158.790309] [050/133] (861s left) kms_plane_multiple (2x-tiling-none)
[158.876985] Starting subtest: 2x-tiling-none
[158.881241] Subtest 2x-tiling-none: SKIP (0.000s)
[158.950589] [051/133] (860s left) kms_feature_discovery (psr1)
[159.042841] Starting subtest: psr1
[159.045149] Subtest psr1: SUCCESS (0.000s)
[159.128608] [052/133] (860s left) kms_getfb (getfb-handle-protection)
[159.208544] Starting subtest: getfb-handle-protection
[159.229453] Subtest getfb-handle-protection: SUCCESS (0.022s)
[159.348425] [053/133] (860s left) kms_prop_blob (blob-prop-core)
[159.402300] Starting subtest: blob-prop-core
[159.404224] Subtest blob-prop-core: SUCCESS (0.000s)
[159.516127] [054/133] (860s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[159.603720] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[159.605714] Starting dynamic subtest: pipe-A
[160.651141] Dynamic subtest pipe-A: SUCCESS (1.050s)
[160.660082] Starting dynamic subtest: pipe-B
[162.745666] Dynamic subtest pipe-B: SUCCESS (2.094s)
[162.754478] Starting dynamic subtest: pipe-C
[163.894336] Dynamic subtest pipe-C: SUCCESS (1.147s)
[163.903689] Starting dynamic subtest: pipe-D
[165.095553] Dynamic subtest pipe-D: SUCCESS (1.200s)
[165.097942] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.495s)
[166.266823] [055/133] (853s left) gem_create (create-size-update)
[166.336710] Starting subtest: create-size-update
[166.338772] Subtest create-size-update: SUCCESS (0.000s)
[166.553022] [056/133] (853s left) syncobj_timeline (transfer-timeline-point)
[166.607674] Starting subtest: transfer-timeline-point
[166.609701] Subtest transfer-timeline-point: SUCCESS (0.000s)
[166.719893] [057/133] (853s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[166.815318] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[166.817607] Starting dynamic subtest: pipe-A-eDP-1
[167.327794] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.484s)
[167.337796] Starting dynamic subtest: pipe-B-eDP-1
[168.834361] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.505s)
[168.845191] Starting dynamic subtest: pipe-C-eDP-1
[170.219191] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.385s)
[170.231615] Starting dynamic subtest: pipe-D-eDP-1
[171.627411] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.408s)
[171.629665] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.786s)
[172.754390] [058/133] (847s left) vgem_basic (dmabuf-fence)
[172.797521] Starting subtest: dmabuf-fence
[172.799829] Subtest dmabuf-fence: SUCCESS (0.001s)
[172.855363] [059/133] (847s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[172.961795] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[172.965923] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[173.076490] [060/133] (847s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[173.175212] Starting subtest: random-ccs-data-y-tiled-ccs
[173.177705] Starting dynamic subtest: pipe-A-eDP-1
[173.202679] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[173.214100] Starting dynamic subtest: pipe-B-eDP-1
[173.237468] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[173.246410] Starting dynamic subtest: pipe-C-eDP-1
[173.269989] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[173.278608] Starting dynamic subtest: pipe-D-eDP-1
[173.302359] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[173.306729] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[175.460338] [061/133] (844s left) kms_addfb_basic (bad-pitch-63)
[175.511543] Starting subtest: bad-pitch-63
[175.513628] Subtest bad-pitch-63: SUCCESS (0.000s)
[175.651207] [062/133] (844s left) gem_exec_schedule (u-semaphore-user)
[175.716069] Starting subtest: u-semaphore-user
[175.768071] Subtest u-semaphore-user: SUCCESS (0.059s)
[176.954481] [063/133] (843s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[177.069074] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[177.071429] Starting dynamic subtest: pipe-A-eDP-1
[177.169817] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[177.183086] Starting dynamic subtest: pipe-B-eDP-1
[178.298800] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[178.303544] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.229s)
[179.558909] [064/133] (840s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[179.659546] Starting subtest: extended-modeset-hang-oldfb-with-reset
[179.661869] Starting dynamic subtest: pipe-A
[191.402865] Dynamic subtest pipe-A: SUCCESS (11.741s)
[191.412086] Starting dynamic subtest: pipe-D
[203.693316] Dynamic subtest pipe-D: SUCCESS (12.287s)
[203.695625] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.032s)
[204.552488] [065/133] (815s left) gem_mmap_gtt (cpuset-big-copy-xy)
[204.614029] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[204.677623] [066/133] (815s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[204.790147] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[215.968948] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.178s)
[216.849924] [067/133] (803s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[216.942298] Starting subtest: basic-modeset-with-all-modifiers-formats
[217.328623] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[218.103364] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.776s)
[218.112777] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[218.125278] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[218.134267] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[218.147843] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[218.156895] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[218.325831] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.177s)
[218.335085] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[218.347987] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[218.357278] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[218.370160] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[218.379068] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[218.392269] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.020s)
[218.401193] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[218.414510] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[218.423510] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[218.436824] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[218.445840] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[218.459045] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[218.467840] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[218.481221] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[218.489958] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[218.503487] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[218.512596] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[218.659075] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[218.668701] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[218.681364] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[218.695486] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[218.703592] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.021s)
[218.712607] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[218.725798] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.021s)
[218.734999] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[218.748019] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[218.762958] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[218.770234] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[218.782439] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[218.792589] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.019s)
[218.801394] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[218.814781] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[218.823428] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[218.836925] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[218.849064] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[219.026214] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.187s)
[219.035570] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[219.048465] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[219.057248] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[219.070801] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.020s)
[219.079668] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[219.092223] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[219.100927] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[219.114697] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[219.123271] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[219.136710] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[219.145150] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[219.158993] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[219.167703] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[219.181265] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.021s)
[219.189888] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[219.203553] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[219.603595] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.662s)
[220.458156] [068/133] (800s left) i915_query (query-regions-sanity-check)
[220.516102] Starting subtest: query-regions-sanity-check
[220.527362] Subtest query-regions-sanity-check: SUCCESS (0.014s)
[220.655914] [069/133] (799s left) gem_exec_whisper (basic-normal-all)
[220.724405] Starting subtest: basic-normal-all
[223.896895] Subtest basic-normal-all: SUCCESS (3.168s)
[224.096254] [070/133] (796s left) gem_mmap_offset (blt-coherency)
[224.160989] Starting subtest: blt-coherency
[224.171417] Subtest blt-coherency: SUCCESS (0.013s)
[224.300721] [071/133] (796s left) kms_flip (flip-vs-suspend)
[224.401083] Starting subtest: flip-vs-suspend
[224.403246] Starting dynamic subtest: A-eDP1
[257.227930] Dynamic subtest A-eDP1: SUCCESS (4.411s)
[257.242235] Starting dynamic subtest: B-eDP1
[306.236116] Dynamic subtest B-eDP1: SUCCESS (4.467s)
[306.247056] Starting dynamic subtest: C-eDP1
[355.224051] Dynamic subtest C-eDP1: SUCCESS (4.496s)
[355.234889] Starting dynamic subtest: D-eDP1
[404.239641] Dynamic subtest D-eDP1: SUCCESS (4.538s)
[404.245278] Subtest flip-vs-suspend: SUCCESS (17.922s)
[405.418135] [072/133] (615s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[405.550338] Starting subtest: basic-busy-flip-before-cursor-atomic
[405.555566] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[405.698187] [073/133] (615s left) kms_psr (psr-cursor-blt)
[405.789240] Starting subtest: psr-cursor-blt
[405.791813] Starting dynamic subtest: eDP-1
[407.479852] Dynamic subtest eDP-1: SUCCESS (1.680s)
[407.482429] Subtest psr-cursor-blt: SUCCESS (1.682s)
[408.308194] [074/133] (612s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[408.402962] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[408.405468] Starting dynamic subtest: pipe-A-eDP-1
[419.755538] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.329s)
[419.766470] Starting dynamic subtest: pipe-B-eDP-1
[431.759563] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.993s)
[431.762790] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.325s)
[432.557071] [075/133] (588s left) gem_exec_reloc (basic-write-read-active)
[432.628585] Subtest basic-write-read-active: SKIP (0.000s)
[432.710475] [076/133] (588s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[432.800485] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[437.398215] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.601s)
[438.554166] [077/133] (582s left) kms_flip (2x-nonexisting-fb)
[438.644335] Starting subtest: 2x-nonexisting-fb
[438.652644] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[438.740977] [078/133] (582s left) gem_ctx_isolation (preservation-reset)
[438.911987] Starting subtest: preservation-reset
[438.915147] Starting dynamic subtest: rcs0
[439.207942] Dynamic subtest rcs0: SUCCESS (0.296s)
[439.221178] Starting dynamic subtest: bcs0
[439.526730] Dynamic subtest bcs0: SUCCESS (0.318s)
[439.539606] Starting dynamic subtest: vcs0
[439.845992] Dynamic subtest vcs0: SUCCESS (0.318s)
[439.859166] Starting dynamic subtest: vcs1
[441.071643] Dynamic subtest vcs1: SUCCESS (1.225s)
[441.082164] Starting dynamic subtest: vecs0
[443.082982] Dynamic subtest vecs0: SUCCESS (2.010s)
[443.098602] Starting dynamic subtest: ccs0
[445.083584] Dynamic subtest ccs0: SUCCESS (2.000s)
[445.086172] Subtest preservation-reset: SUCCESS (6.175s)
[445.222607] [079/133] (575s left) syncobj_eventfd (invalid-bad-pad)
[445.273361] Starting subtest: invalid-bad-pad
[445.276375] Subtest invalid-bad-pad: SUCCESS (0.000s)
[445.356992] [080/133] (575s left) kms_universal_plane (cursor-fb-leak)
[445.460977] Starting subtest: cursor-fb-leak
[445.462924] Starting dynamic subtest: pipe-A-eDP-1
[445.826703] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.367s)
[445.841034] Starting dynamic subtest: pipe-B-eDP-1
[447.250807] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.421s)
[447.266293] Starting dynamic subtest: pipe-C-eDP-1
[448.628576] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[448.642077] Starting dynamic subtest: pipe-D-eDP-1
[450.025353] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.395s)
[450.028266] Subtest cursor-fb-leak: SUCCESS (4.567s)
[451.303472] [081/133] (569s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[451.446036] Starting subtest: cursor-vs-flip-varying-size
[482.804250] Subtest cursor-vs-flip-varying-size: SUCCESS (31.349s)
[483.659021] [082/133] (537s left) prime_busy (hang)
[483.728549] Starting subtest: hang
[483.731547] Starting dynamic subtest: rcs0
[510.256603] Dynamic subtest rcs0: SUCCESS (26.520s)
[510.269683] Starting dynamic subtest: bcs0
[517.406834] Dynamic subtest bcs0: SUCCESS (7.151s)
[517.417574] Starting dynamic subtest: vcs0
[524.447278] Dynamic subtest vcs0: SUCCESS (7.043s)
[524.457893] Starting dynamic subtest: vcs1
[531.426394] Dynamic subtest vcs1: SUCCESS (6.976s)
[531.436956] Starting dynamic subtest: vecs0
[538.394560] Dynamic subtest vecs0: SUCCESS (6.970s)
[538.405276] Starting dynamic subtest: ccs0
[552.301925] Dynamic subtest ccs0: SUCCESS (13.901s)
[552.304459] Subtest hang: SUCCESS (68.568s)
[552.425438] [083/133] (468s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[552.480601] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[552.483451] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[552.577294] [084/133] (468s left) gem_exec_balancer (parallel-keep-in-fence)
[552.832812] Starting subtest: parallel-keep-in-fence
[554.219595] Subtest parallel-keep-in-fence: SUCCESS (1.386s)
[554.315940] [085/133] (467s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[554.403484] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[554.408440] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[554.479918] [086/133] (466s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[554.600103] Starting subtest: flip-vs-cursor-legacy
[562.129424] Subtest flip-vs-cursor-legacy: SUCCESS (7.528s)
[562.945759] [087/133] (458s left) i915_hangman (gt-engine-error)
[563.008814] Starting subtest: gt-engine-error
[563.011374] Starting dynamic subtest: rcs0
[564.720594] Dynamic subtest rcs0: SUCCESS (1.712s)
[564.733995] Starting dynamic subtest: bcs0
[566.705017] Dynamic subtest bcs0: SUCCESS (1.981s)
[566.718322] Starting dynamic subtest: vcs0
[568.753102] Dynamic subtest vcs0: SUCCESS (2.044s)
[568.770134] Starting dynamic subtest: vcs1
[570.736650] Dynamic subtest vcs1: SUCCESS (1.980s)
[570.750557] Starting dynamic subtest: vecs0
[572.720736] Dynamic subtest vecs0: SUCCESS (1.980s)
[572.734151] Starting dynamic subtest: ccs0
[574.704073] Dynamic subtest ccs0: SUCCESS (1.980s)
[574.707135] Subtest gt-engine-error: SUCCESS (11.696s)
[574.793175] [088/133] (446s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[574.890451] Starting subtest: 4-tiled-64bpp-rotate-0
[576.437203] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.546s)
[576.615842] [089/133] (444s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[576.712056] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[576.716706] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[576.825171] [090/133] (444s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[576.915922] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[577.819016] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.903s)
[577.982054] [091/133] (443s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[578.052452] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[578.189353] [092/133] (443s left) prime_self_import (basic-with_one_bo_two_files)
[578.240367] Starting subtest: basic-with_one_bo_two_files
[578.252369] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[578.345312] [093/133] (443s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[578.391845] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[578.443260] [094/133] (443s left) gem_exec_params (invalid-batch-start-offset)
[578.495606] Starting subtest: invalid-batch-start-offset
[578.498013] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[578.604033] [095/133] (443s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[578.765471] Starting subtest: y-tiled-8bpp-rotate-270
[578.770958] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[578.883370] [096/133] (442s left) gem_eio (create)
[579.099884] Starting subtest: create
[579.419885] Subtest create: SUCCESS (0.318s)
[579.557430] [097/133] (442s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[579.643118] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[579.645305] Starting dynamic subtest: pipe-A-eDP-1
[579.667130] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[579.678338] Starting dynamic subtest: pipe-B-eDP-1
[579.700847] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[579.711258] Starting dynamic subtest: pipe-C-eDP-1
[579.733946] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[579.743808] Starting dynamic subtest: pipe-D-eDP-1
[579.766564] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[579.771174] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[581.854692] [098/133] (440s left) kms_psr (pr-primary-blt)
[581.949909] Starting subtest: pr-primary-blt
[581.954329] Subtest pr-primary-blt: SKIP (0.000s)
[582.023995] [099/133] (439s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[582.288895] Starting subtest: cursor-rapid-movement-256x256
[582.291310] Starting dynamic subtest: pipe-A-eDP-1
[582.850380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.555s)
[582.878573] Starting dynamic subtest: pipe-D-eDP-1
[584.041157] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.172s)
[584.043556] Subtest cursor-rapid-movement-256x256: SUCCESS (1.752s)
[584.851725] [100/133] (437s left) gem_exec_params (sol-reset-invalid)
[584.916330] Starting subtest: sol-reset-invalid
[584.919352] Subtest sol-reset-invalid: SUCCESS (0.002s)
[586.857548] [101/133] (435s left) gem_exec_params (invalid-fence-in)
[586.920558] Starting subtest: invalid-fence-in
[586.923396] Subtest invalid-fence-in: SUCCESS (0.000s)
[587.021793] [102/133] (435s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[587.124329] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[587.129439] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[587.220119] [103/133] (434s left) syncobj_wait (wait-all-complex)
[587.273214] Starting subtest: wait-all-complex
[587.489973] Subtest wait-all-complex: SUCCESS (0.217s)
[587.581376] [104/133] (434s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[587.678495] Starting subtest: fbcpsr-rgb101010-draw-render
[594.315249] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.639s)
[594.445892] [105/133] (427s left) kms_lease (lease-unleased-connector)
[594.534898] Starting subtest: lease-unleased-connector
[594.537132] Starting dynamic subtest: pipe-A-eDP-1
[594.539459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[594.550211] Starting dynamic subtest: pipe-B-eDP-1
[594.552585] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[594.563565] Starting dynamic subtest: pipe-C-eDP-1
[594.566448] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[594.577449] Starting dynamic subtest: pipe-D-eDP-1
[594.580192] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[594.582620] Subtest lease-unleased-connector: SUCCESS (0.033s)
[594.676216] [106/133] (427s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[595.057116] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[595.838735] [107/133] (426s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[595.937838] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[599.954818] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.022s)
[601.169747] [108/133] (421s left) kms_fbcon_fbt (fbc)
[601.231309] Starting subtest: fbc
[607.679555] Subtest fbc: SUCCESS (6.445s)
[608.834157] [109/133] (413s left) kms_vblank (wait-idle-hang)
[608.936412] Starting subtest: wait-idle-hang
[608.938899] Starting dynamic subtest: pipe-A-eDP-1
[635.537588] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.597s)
[635.556245] Starting dynamic subtest: pipe-D-eDP-1
[659.548455] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.016s)
[659.551384] Subtest wait-idle-hang: SUCCESS (50.617s)
[660.338099] [110/133] (362s left) kms_cursor_edge_walk (256x256-top-edge)
[660.428462] Starting subtest: 256x256-top-edge
[660.431279] Starting dynamic subtest: pipe-A-eDP-1
[663.879550] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[663.893273] Starting dynamic subtest: pipe-D-eDP-1
[667.067819] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.187s)
[667.070355] Subtest 256x256-top-edge: SUCCESS (6.640s)
[667.948112] [111/133] (354s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[668.015215] Starting subtest: multi-wait-all-for-submit-available-signaled
[668.018026] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[668.121343] [112/133] (354s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[668.227700] Starting subtest: fbc-psr2-cursor-plane-onoff
[668.230492] Starting dynamic subtest: eDP-1
[668.236116] Dynamic subtest eDP-1: SKIP (0.000s)
[668.241351] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[668.316388] [113/133] (354s left) kms_pm_rpm (modeset-lpsp)
[669.849803] Starting subtest: modeset-lpsp
[671.310566] Subtest modeset-lpsp: SUCCESS (1.464s)
[671.410872] [114/133] (351s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[671.529518] Starting subtest: cursorB-vs-flipB-atomic
[671.534446] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[671.680317] [115/133] (350s left) kms_vrr (seamless-rr-switch-drrs)
[671.767355] Starting subtest: seamless-rr-switch-drrs
[671.775009] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[671.842807] [116/133] (350s left) gem_exec_params (dr4-dirt)
[671.902811] Starting subtest: DR4-dirt
[671.905577] Subtest DR4-dirt: SUCCESS (0.000s)
[672.013671] [117/133] (350s left) gem_exec_schedule (preempt-other-chain)
[672.083017] Starting subtest: preempt-other-chain
[672.085259] Starting dynamic subtest: rcs0
[672.154723] Dynamic subtest rcs0: SUCCESS (0.083s)
[672.174242] Starting dynamic subtest: bcs0
[672.237175] Dynamic subtest bcs0: SUCCESS (0.081s)
[672.252256] Starting dynamic subtest: vcs0
[672.315730] Dynamic subtest vcs0: SUCCESS (0.079s)
[672.329356] Starting dynamic subtest: vcs1
[672.402148] Dynamic subtest vcs1: SUCCESS (0.085s)
[672.415112] Starting dynamic subtest: vecs0
[672.483803] Dynamic subtest vecs0: SUCCESS (0.081s)
[672.498765] Starting dynamic subtest: ccs0
[672.563342] Dynamic subtest ccs0: SUCCESS (0.078s)
[672.565944] Subtest preempt-other-chain: SUCCESS (0.493s)
[673.845324] [118/133] (348s left) i915_drm_fdinfo (memory-info-shared)
[673.894464] Starting subtest: memory-info-shared
[673.897558] Starting dynamic subtest: smem0
[673.937934] Dynamic subtest smem0: SUCCESS (0.046s)
[673.940843] Subtest memory-info-shared: SUCCESS (0.047s)
[674.019135] [119/133] (348s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[674.128323] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[680.819742] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.695s)
[680.948131] [120/133] (341s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[681.044976] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[688.016184] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.976s)
[688.122823] [121/133] (334s left) gem_ctx_persistence (legacy-engines-hostile)
[688.192833] Starting subtest: legacy-engines-hostile
[688.195555] Starting dynamic subtest: render
[688.331451] Dynamic subtest render: SUCCESS (0.138s)
[688.346868] Starting dynamic subtest: bsd1
[688.437051] Dynamic subtest bsd1: SUCCESS (0.102s)
[688.450641] Starting dynamic subtest: bsd2
[688.545965] Dynamic subtest bsd2: SUCCESS (0.106s)
[688.559670] Starting dynamic subtest: blt
[688.644136] Dynamic subtest blt: SUCCESS (0.095s)
[688.657387] Starting dynamic subtest: vebox
[688.737882] Dynamic subtest vebox: SUCCESS (0.091s)
[688.740918] Subtest legacy-engines-hostile: SUCCESS (0.546s)
[688.822777] [122/133] (334s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[688.908233] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[688.913174] Starting dynamic subtest: pipe-A-eDP-1
[688.936400] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[688.947454] Starting dynamic subtest: pipe-B-eDP-1
[688.968802] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[688.979500] Starting dynamic subtest: pipe-C-eDP-1
[689.000917] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[689.013791] Starting dynamic subtest: pipe-D-eDP-1
[689.037103] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[689.041306] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.005s)
[691.139050] [123/133] (331s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[691.197493] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[691.302572] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[691.386700] [124/133] (331s left) gem_sync (basic-each)
[691.459563] Starting subtest: basic-each
[693.779342] Subtest basic-each: SUCCESS (2.333s)
[693.971458] [125/133] (329s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[694.086287] Starting subtest: cursor-vs-flip-atomic-transitions
[724.555392] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.466s)
[725.359330] [126/133] (297s left) gem_mmap_gtt (basic-small-copy-xy)
[725.429311] Subtest basic-small-copy-XY: SKIP (0.000s)
[725.487065] [127/133] (297s left) gem_pxp (reject-modify-context-protection-off-3)
[725.604270] Starting subtest: reject-modify-context-protection-off-3
[725.607052] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[725.729212] [128/133] (297s left) sysfs_heartbeat_interval (nopreempt)
[725.792835] Starting subtest: nopreempt
[725.795425] Starting dynamic subtest: vecs0
[735.763472] Dynamic subtest vecs0: SUCCESS (9.965s)
[735.776148] Starting dynamic subtest: ccs0
[739.788136] Dynamic subtest ccs0: SUCCESS (4.024s)
[739.803600] Starting dynamic subtest: vcs0
[749.780960] Dynamic subtest vcs0: SUCCESS (9.991s)
[749.794499] Starting dynamic subtest: bcs0
[753.724491] Dynamic subtest bcs0: SUCCESS (3.939s)
[753.738340] Starting dynamic subtest: rcs0
[757.795492] Dynamic subtest rcs0: SUCCESS (4.069s)
[757.808805] Starting dynamic subtest: vcs1
[767.759463] Dynamic subtest vcs1: SUCCESS (9.960s)
[767.762433] Subtest nopreempt: SUCCESS (41.959s)
[767.841159] [129/133] (255s left) kms_content_protection (content-type-change)
[767.987482] Starting subtest: content-type-change
[767.993392] Subtest content-type-change: SKIP (0.000s)
[768.102599] [130/133] (255s left) kms_psr (psr-suspend)
[768.198468] Starting subtest: psr-suspend
[768.200959] Starting dynamic subtest: eDP-1
[785.658250] Dynamic subtest eDP-1: SUCCESS (2.923s)
[785.660664] Subtest psr-suspend: SUCCESS (2.924s)
[786.531996] [131/133] (236s left) gen9_exec_parse (batch-invalid-length)
[786.603114] Subtest batch-invalid-length: SKIP (0.000s)
[786.684404] [132/133] (236s left) kms_atomic_transition (modeset-transition)
[786.771540] Starting subtest: modeset-transition
[786.773451] Starting dynamic subtest: 1x-outputs
[797.658182] Dynamic subtest 1x-outputs: SUCCESS (10.901s)
[797.660992] Subtest modeset-transition: SUCCESS (10.903s)
[798.513161] [133/133] (224s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[798.601611] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[798.603912] Starting dynamic subtest: pipe-A-default-mode
[801.308049] Dynamic subtest pipe-A-default-mode: SUCCESS (2.707s)
[801.310458] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.708s)
[802.105657] Closing watchdogs
results: parsing output: 0/ for test: syncobj_wait
results: parsing output: 1/ for test: kms_color
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: gem_exec_fence
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_pipe_b_c_ivb
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: kms_cursor_crc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_userptr_blits
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_atomic_transition
results: parsing output: 17/ for test: api_intel_bb
results: parsing output: 18/ for test: gem_sync
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_plane_alpha_blend
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_softpin
results: parsing output: 24/ for test: kms_hdmi_inject
results: parsing output: 25/ for test: gem_bad_reloc
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: gem_eio
results: parsing output: 29/ for test: kms_pm_backlight
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: dmabuf_sync_file
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: gem_exec_capture
results: parsing output: 35/ for test: fbdev
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_plane
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: gem_partial_pwrite_pread
results: parsing output: 42/ for test: kms_hdr
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_chamelium_color
results: parsing output: 47/ for test: gem_pxp
results: parsing output: 48/ for test: gem_softpin
results: parsing output: 49/ for test: kms_plane_multiple
results: parsing output: 50/ for test: kms_feature_discovery
results: parsing output: 51/ for test: kms_getfb
results: parsing output: 52/ for test: kms_prop_blob
results: parsing output: 53/ for test: kms_plane_scaling
results: parsing output: 54/ for test: gem_create
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: vgem_basic
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_busy
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: kms_cursor_legacy
results: parsing output: 66/ for test: kms_async_flips
results: parsing output: 67/ for test: i915_query
results: parsing output: 68/ for test: gem_exec_whisper
results: parsing output: 69/ for test: gem_mmap_offset
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_psr2_sf
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: gem_ctx_isolation
results: parsing output: 78/ for test: syncobj_eventfd
results: parsing output: 79/ for test: kms_universal_plane
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: prime_busy
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: i915_hangman
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: gem_userptr_blits
results: parsing output: 91/ for test: prime_self_import
results: parsing output: 92/ for test: gem_lmem_swapping
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: gem_eio
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: gem_exec_params
results: parsing output: 100/ for test: gem_exec_params
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: syncobj_wait
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_lease
results: parsing output: 105/ for test: kms_chamelium_hpd
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_fbcon_fbt
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: kms_cursor_edge_walk
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_vrr
results: parsing output: 115/ for test: gem_exec_params
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: i915_drm_fdinfo
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_ctx_persistence
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: gem_sync
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gem_mmap_gtt
results: parsing output: 126/ for test: gem_pxp
results: parsing output: 127/ for test: sysfs_heartbeat_interval
results: parsing output: 128/ for test: kms_content_protection
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: gen9_exec_parse
results: parsing output: 131/ for test: kms_atomic_transition
results: parsing output: 132/ for test: kms_flip_scaled_crc
Done.