[56.926189] Initializing watchdogs
[56.926237]   /dev/watchdog0
[56.954128] [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]
[56.958579] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.961048] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.966503] [001/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[57.096365] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[57.100678] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[57.187969] [002/133] (960s left) kms_properties (plane-properties-legacy)
[57.286136] Starting subtest: plane-properties-legacy
[57.288477] Starting dynamic subtest: pipe-A-eDP-1
[57.876894] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.592s)
[57.885689] Starting dynamic subtest: pipe-B-eDP-1
[59.154489] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.275s)
[59.162729] Starting dynamic subtest: pipe-C-eDP-1
[60.386316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.232s)
[60.394784] Starting dynamic subtest: pipe-D-eDP-1
[61.631448] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.244s)
[61.633922] Subtest plane-properties-legacy: SUCCESS (4.347s)
[62.444509] [003/133] (955s left) api_intel_allocator (reopen)
[62.512874] Starting subtest: reopen
[62.517411] Subtest reopen: SUCCESS (0.005s)
[62.643329] [004/133] (954s left) kms_vblank (ts-continuation-idle)
[62.737605] Starting subtest: ts-continuation-idle
[62.739813] Starting dynamic subtest: pipe-A-eDP-1
[63.139680] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.401s)
[63.152829] Starting dynamic subtest: pipe-D-eDP-1
[64.230226] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.088s)
[64.232172] Subtest ts-continuation-idle: SUCCESS (1.492s)
[65.085928] [005/133] (952s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[65.181290] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[65.186072] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[66.313702] [006/133] (951s left) gem_ctx_exec (basic-nohangcheck)
[66.364638] Starting subtest: basic-nohangcheck
[66.601952] Subtest basic-nohangcheck: SUCCESS (0.238s)
[66.702407] [007/133] (950s left) gem_exec_params (no-blt)
[66.758673] Starting subtest: no-blt
[66.763374] Subtest no-blt: SUCCESS (0.003s)
[66.862670] [008/133] (950s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[66.964676] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[66.969193] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[67.028910] [009/133] (950s left) i915_pm_freq_api (freq-suspend)
[67.092868] Starting subtest: freq-suspend
[67.095362] Starting dynamic subtest: gt0
[83.178844] Dynamic subtest gt0: SUCCESS (1.335s)
[83.192028] Starting dynamic subtest: gt1
[99.176645] Dynamic subtest gt1: SUCCESS (1.205s)
[99.181616] Subtest freq-suspend: SUCCESS (2.542s)
[100.253809] [010/133] (918s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[100.351435] Starting subtest: compare-crc-sanitycheck-xr24
[100.353790] Starting dynamic subtest: pipe-A-eDP-1
[101.016331] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.664s)
[101.025985] Starting dynamic subtest: pipe-B-eDP-1
[102.352568] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.337s)
[102.362331] Starting dynamic subtest: pipe-C-eDP-1
[103.600979] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.249s)
[103.608609] Starting dynamic subtest: pipe-D-eDP-1
[104.818916] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.216s)
[104.821290] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.470s)
[105.668253] [011/133] (913s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[105.726814] Starting subtest: invalid-smem-bo-on-discrete
[105.731542] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[105.856695] [012/133] (913s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[105.958959] Starting subtest: 2x-flip-vs-rmfb-interruptible
[105.970060] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[106.079960] [013/133] (912s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[106.178836] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[106.183138] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[106.252492] [014/133] (912s left) gem_exec_schedule (preemptive-hang)
[106.419311] Starting subtest: preemptive-hang
[106.422280] Starting dynamic subtest: rcs0
[115.979219] Dynamic subtest rcs0: SUCCESS (9.560s)
[115.989598] Starting dynamic subtest: bcs0
[124.934120] Dynamic subtest bcs0: SUCCESS (8.955s)
[124.942041] Starting dynamic subtest: vcs0
[133.958400] Dynamic subtest vcs0: SUCCESS (9.024s)
[133.966206] Starting dynamic subtest: vcs1
[142.919121] Dynamic subtest vcs1: SUCCESS (8.958s)
[142.929335] Starting dynamic subtest: vecs0
[151.936822] Dynamic subtest vecs0: SUCCESS (9.019s)
[151.944521] Starting dynamic subtest: ccs0
[160.972308] Dynamic subtest ccs0: SUCCESS (9.031s)
[160.974453] Subtest preemptive-hang: SUCCESS (54.553s)
[161.210120] [015/133] (857s left) sysfs_heartbeat_interval (invalid)
[161.280051] Starting subtest: invalid
[161.282419] Starting dynamic subtest: vecs0
[161.284652] Dynamic subtest vecs0: SUCCESS (0.000s)
[161.292462] Starting dynamic subtest: ccs0
[161.294406] Dynamic subtest ccs0: SUCCESS (0.000s)
[161.301640] Starting dynamic subtest: vcs0
[161.303545] Dynamic subtest vcs0: SUCCESS (0.000s)
[161.312068] Starting dynamic subtest: bcs0
[161.314305] Dynamic subtest bcs0: SUCCESS (0.000s)
[161.322130] Starting dynamic subtest: rcs0
[161.324049] Dynamic subtest rcs0: SUCCESS (0.000s)
[161.331468] Starting dynamic subtest: vcs1
[161.333819] Dynamic subtest vcs1: SUCCESS (0.000s)
[161.335962] Subtest invalid: SUCCESS (0.003s)
[161.375499] [016/133] (857s left) gem_userptr_blits (unsync-unmap-after-close)
[161.442505] Subtest unsync-unmap-after-close: SKIP (0.000s)
[161.555171] [017/133] (857s left) kms_lease (cursor-implicit-plane)
[161.650607] Starting subtest: cursor-implicit-plane
[161.653019] Starting dynamic subtest: pipe-A-eDP-1
[162.117410] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[162.126493] Starting dynamic subtest: pipe-B-eDP-1
[163.208952] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.091s)
[163.219479] Starting dynamic subtest: pipe-C-eDP-1
[164.323227] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.111s)
[164.332967] Starting dynamic subtest: pipe-D-eDP-1
[165.450422] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[165.452379] Subtest cursor-implicit-plane: SUCCESS (3.797s)
[166.306357] [018/133] (852s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[166.408797] Starting subtest: fbc-rgb565-draw-blt
[170.543382] Subtest fbc-rgb565-draw-blt: SUCCESS (4.139s)
[171.698819] [019/133] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[171.800781] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[171.804987] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[171.894057] [020/133] (847s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[171.975539] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[171.977865] Starting dynamic subtest: pipe-A-default-mode
[173.156313] Dynamic subtest pipe-A-default-mode: SKIP (1.178s)
[173.160851] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.179s)
[173.314167] [021/133] (845s left) kms_psr (fbc-pr-no-drrs)
[173.404738] Starting subtest: fbc-pr-no-drrs
[173.409030] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[173.473127] [022/133] (845s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs)
[173.569853] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs
[173.572182] Starting dynamic subtest: pipe-A-eDP-1
[173.595418] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[173.605864] Starting dynamic subtest: pipe-B-eDP-1
[173.627431] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[173.636030] Starting dynamic subtest: pipe-C-eDP-1
[173.658161] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[173.665854] Starting dynamic subtest: pipe-D-eDP-1
[173.687614] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[173.692335] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[175.728070] [023/133] (843s left) gem_ctx_persistence (many-contexts)
[175.782620] Starting subtest: many-contexts
[213.455315] Subtest many-contexts: SUCCESS (37.671s)
[213.544044] [024/133] (805s left) i915_query (query-garbage)
[213.596350] Starting subtest: query-garbage
[213.598248] Subtest query-garbage: SUCCESS (0.000s)
[213.701861] [025/133] (805s left) vgem_basic (dmabuf-fence-before)
[213.790676] Starting subtest: dmabuf-fence-before
[213.792756] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[213.862766] [FACT vgem_basic (dmabuf-fence-before)] new: kernel.kmod_is_loaded.vgem: true
[213.865878] [026/133] (805s left) gem_unref_active_buffers
[214.083616] [027/133] (805s left) kms_cursor_crc (cursor-onscreen-64x21)
[214.267519] Starting subtest: cursor-onscreen-64x21
[214.313230] Subtest cursor-onscreen-64x21: SKIP (0.040s)
[214.546807] [028/133] (804s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[214.660517] Starting subtest: x-tiled-64bpp-rotate-90
[214.679811] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[214.781963] [029/133] (804s left) gem_mmap_gtt (zero-extend)
[214.844292] Subtest zero-extend: SKIP (0.000s)
[214.930453] [030/133] (804s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[215.050997] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[215.054795] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[215.200417] [031/133] (804s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[215.303377] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[215.306688] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[215.393222] [032/133] (804s left) i915_pm_rc6_residency (rc6-accuracy)
[215.454149] Starting subtest: rc6-accuracy
[215.456342] Starting dynamic subtest: gt0
[218.625984] Dynamic subtest gt0: SUCCESS (3.169s)
[218.636994] Starting dynamic subtest: gt1
[221.797037] Dynamic subtest gt1: SUCCESS (3.169s)
[221.799640] Subtest rc6-accuracy: SUCCESS (6.342s)
[221.872934] [033/133] (797s left) kms_pm_rpm (package-g7)
[224.015339] Starting subtest: package-g7
[224.020111] Subtest package-g7: SKIP (0.000s)
[224.413291] [034/133] (795s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[224.508802] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[229.695351] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.191s)
[230.901615] [035/133] (788s left) gem_exec_capture (pi)
[230.981608] Starting subtest: pi
[230.983992] Starting dynamic subtest: rcs0
[235.124002] Dynamic subtest rcs0: SUCCESS (4.143s)
[235.135831] Starting dynamic subtest: bcs0
[239.011085] Dynamic subtest bcs0: SUCCESS (3.885s)
[239.022651] Starting dynamic subtest: vcs0
[242.847797] Dynamic subtest vcs0: SUCCESS (3.836s)
[242.859344] Starting dynamic subtest: vcs1
[246.690013] Dynamic subtest vcs1: SUCCESS (3.841s)
[246.709036] Starting dynamic subtest: vecs0
[250.532616] Dynamic subtest vecs0: SUCCESS (3.841s)
[250.546191] Starting dynamic subtest: ccs0
[254.383776] Dynamic subtest ccs0: SUCCESS (3.849s)
[254.386562] Subtest pi: SUCCESS (23.402s)
[254.466572] [036/133] (765s left) kms_cursor_crc (cursor-sliding-64x21)
[254.709148] Starting subtest: cursor-sliding-64x21
[254.755172] Subtest cursor-sliding-64x21: SKIP (0.040s)
[254.969240] [037/133] (764s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[255.070027] Starting subtest: fbcpsr-tiling-linear
[261.688401] Subtest fbcpsr-tiling-linear: SUCCESS (6.619s)
[261.813554] [038/133] (758s left) gem_ctx_param (get-priority-new-ctx)
[261.865545] Starting subtest: get-priority-new-ctx
[261.867974] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[261.972008] [039/133] (758s left) kms_psr (psr-primary-mmap-gtt)
[262.066867] Starting subtest: psr-primary-mmap-gtt
[262.069184] Starting dynamic subtest: eDP-1
[263.500798] Dynamic subtest eDP-1: SKIP (1.431s)
[263.505895] Subtest psr-primary-mmap-gtt: SKIP (1.432s)
[263.665352] [040/133] (756s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[263.762443] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[263.764666] Starting dynamic subtest: pipe-A-eDP-1
[263.787705] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[263.799233] Starting dynamic subtest: pipe-B-eDP-1
[263.820857] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[263.829425] Starting dynamic subtest: pipe-C-eDP-1
[263.850406] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[263.859193] Starting dynamic subtest: pipe-D-eDP-1
[263.881444] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[263.885514] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[265.909092] [041/133] (754s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[266.026244] Starting subtest: basic-flip-after-cursor-legacy
[266.975353] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.947s)
[267.844032] [042/133] (752s left) prime_mmap_coherency (write)
[267.904661] Starting subtest: write
[273.281863] Subtest write: SUCCESS (5.375s)
[273.412810] [043/133] (746s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[273.995360] Starting subtest: syncobj-timeline-multiple-ext-nodes
[274.000848] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.005s)
[275.902053] [044/133] (744s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[275.997567] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[276.001543] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[276.093808] [045/133] (744s left) kms_async_flips (test-time-stamp)
[276.182138] Starting subtest: test-time-stamp
[276.571602] Starting dynamic subtest: pipe-A-eDP-1
[277.353694] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.780s)
[277.720572] Starting dynamic subtest: pipe-B-eDP-1
[278.481880] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.766s)
[278.851225] Starting dynamic subtest: pipe-C-eDP-1
[279.613898] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.768s)
[279.986552] Starting dynamic subtest: pipe-D-eDP-1
[280.742541] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.761s)
[280.745035] Subtest test-time-stamp: SUCCESS (4.560s)
[281.911357] [046/133] (738s left) gem_exec_nop (basic-parallel)
[281.991345] Starting subtest: basic-parallel
[297.758999] Subtest basic-parallel: SUCCESS (15.777s)
[298.007205] [047/133] (722s left) kms_psr (psr2-primary-page-flip)
[298.098493] Starting subtest: psr2-primary-page-flip
[298.100707] Starting dynamic subtest: eDP-1
[299.789581] Dynamic subtest eDP-1: SUCCESS (1.690s)
[299.791890] Subtest psr2-primary-page-flip: SUCCESS (1.690s)
[300.669043] [048/133] (719s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[300.785732] Starting subtest: psr-rgb565-draw-pwrite
[307.743813] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.958s)
[307.859351] [049/133] (712s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[308.240047] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[309.031756] [050/133] (711s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[309.120952] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[316.425739] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.308s)
[316.549201] [051/133] (703s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[316.609115] Starting subtest: etime-single-wait-available-unsubmitted
[316.715081] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.105s)
[316.802713] [052/133] (703s left) perf (invalid-oa-format-id)
[316.944991] Starting subtest: invalid-oa-format-id
[316.954558] Subtest invalid-oa-format-id: SUCCESS (0.010s)
[317.000158] [053/133] (703s left) drm_mm (drm_mm)
[317.039802] Starting subtest: drm_mm
[317.049805] Starting dynamic subtest: drm_test_mm_init
[317.150287] Dynamic subtest drm_test_mm_init: SUCCESS (0.100s)
[317.158956] Starting dynamic subtest: drm_test_mm_debug
[317.250736] Dynamic subtest drm_test_mm_debug: SUCCESS (0.099s)
[317.261221] Starting dynamic subtest: drm_test_mm_align32
[317.375249] Dynamic subtest drm_test_mm_align32: SUCCESS (0.123s)
[317.385399] Starting dynamic subtest: drm_test_mm_align64
[317.470800] Dynamic subtest drm_test_mm_align64: SUCCESS (0.096s)
[317.480970] Starting dynamic subtest: drm_test_mm_lowest
[317.578785] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.106s)
[317.587818] Starting dynamic subtest: drm_test_mm_highest
[317.682558] Dynamic subtest drm_test_mm_highest: SUCCESS (0.102s)
[317.684795] Subtest drm_mm: SUCCESS (0.643s)
[317.854101] [054/133] (702s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[317.947339] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[317.951773] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[318.048727] [055/133] (702s left) gem_wait (busy)
[318.115212] Starting subtest: busy
[318.117477] Starting dynamic subtest: all-engines
[319.837597] Dynamic subtest all-engines: SUCCESS (1.721s)
[319.848477] Starting dynamic subtest: rcs0
[320.380339] Dynamic subtest rcs0: SUCCESS (0.542s)
[320.391262] Starting dynamic subtest: bcs0
[320.920694] Dynamic subtest bcs0: SUCCESS (0.539s)
[320.932248] Starting dynamic subtest: vcs0
[322.845643] Dynamic subtest vcs0: SUCCESS (1.923s)
[322.857210] Starting dynamic subtest: vcs1
[324.829182] Dynamic subtest vcs1: SUCCESS (1.982s)
[324.840724] Starting dynamic subtest: vecs0
[326.813267] Dynamic subtest vecs0: SUCCESS (1.982s)
[326.824925] Starting dynamic subtest: ccs0
[327.355917] Dynamic subtest ccs0: SUCCESS (0.542s)
[327.358361] Subtest busy: SUCCESS (9.241s)
[327.570706] [056/133] (693s left) syncobj_timeline (multi-wait-all-available-submitted)
[327.632831] Starting subtest: multi-wait-all-available-submitted
[327.635665] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s)
[327.751932] [057/133] (692s left) prime_busy (hang)
[327.809117] Starting subtest: hang
[327.811461] Starting dynamic subtest: rcs0
[354.361864] Dynamic subtest rcs0: SUCCESS (26.546s)
[354.370767] Starting dynamic subtest: bcs0
[361.518458] Dynamic subtest bcs0: SUCCESS (7.154s)
[361.529144] Starting dynamic subtest: vcs0
[368.486489] Dynamic subtest vcs0: SUCCESS (6.972s)
[368.498636] Starting dynamic subtest: vcs1
[375.468525] Dynamic subtest vcs1: SUCCESS (6.977s)
[375.477813] Starting dynamic subtest: vecs0
[382.504398] Dynamic subtest vecs0: SUCCESS (7.034s)
[382.515229] Starting dynamic subtest: ccs0
[396.350550] Dynamic subtest ccs0: SUCCESS (13.846s)
[396.353182] Subtest hang: SUCCESS (68.536s)
[396.485735] [058/133] (624s left) kms_busy (basic)
[396.591906] Starting subtest: basic
[396.594311] Starting dynamic subtest: flip
[397.219638] Dynamic subtest flip: SUCCESS (0.627s)
[397.234056] Starting dynamic subtest: modeset
[400.602360] Dynamic subtest modeset: SUCCESS (3.384s)
[400.604520] Subtest basic: SUCCESS (4.013s)
[401.405785] [059/133] (619s left) perf (short-reads)
[401.503001] Starting subtest: short-reads
[401.513940] Subtest short-reads: SUCCESS (0.011s)
[401.556385] [060/133] (619s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[401.648016] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[401.652079] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[401.722327] [061/133] (619s left) kms_prop_blob (blob-prop-core)
[401.787204] Starting subtest: blob-prop-core
[401.789137] Subtest blob-prop-core: SUCCESS (0.000s)
[401.885929] [062/133] (619s left) gem_ctx_param (invalid-ctx-set)
[401.948634] Starting subtest: invalid-ctx-set
[401.950976] Subtest invalid-ctx-set: SUCCESS (0.001s)
[402.059079] [063/133] (618s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[402.157383] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[402.948718] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.792s)
[403.108164] [064/133] (617s left) api_intel_bb (full-batch)
[403.157757] Starting subtest: full-batch
[403.162359] Subtest full-batch: SUCCESS (0.005s)
[403.286702] [065/133] (617s left) gem_render_copy_redux (flink-interruptible)
[403.337436] Starting subtest: flink-interruptible
[404.113075] Subtest flink-interruptible: SUCCESS (0.776s)
[404.238737] [066/133] (616s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[404.337298] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[404.340567] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[404.430205] [067/133] (616s left) kms_scaling_modes (scaling-mode-none)
[404.517683] Starting subtest: scaling-mode-none
[404.519653] Starting dynamic subtest: pipe-A-eDP-1
[404.615949] Dynamic subtest pipe-A-eDP-1: SKIP (0.096s)
[404.629982] Starting dynamic subtest: pipe-B-eDP-1
[404.656823] Dynamic subtest pipe-B-eDP-1: SKIP (0.038s)
[404.667585] Starting dynamic subtest: pipe-C-eDP-1
[404.690026] Dynamic subtest pipe-C-eDP-1: SKIP (0.032s)
[404.699041] Starting dynamic subtest: pipe-D-eDP-1
[404.723818] Dynamic subtest pipe-D-eDP-1: SKIP (0.032s)
[404.727342] Subtest scaling-mode-none: SKIP (0.206s)
[404.831991] [068/133] (616s left) perf_pmu (busy-accuracy-98)
[404.871589] Starting subtest: busy-accuracy-98
[404.873395] Starting dynamic subtest: rcs0
[407.908229] Dynamic subtest rcs0: SUCCESS (3.033s)
[407.919672] Starting dynamic subtest: bcs0
[410.957908] Dynamic subtest bcs0: SUCCESS (3.047s)
[410.969043] Starting dynamic subtest: vcs0
[414.002009] Dynamic subtest vcs0: SUCCESS (3.041s)
[414.014241] Starting dynamic subtest: vcs1
[417.041830] Dynamic subtest vcs1: SUCCESS (3.039s)
[417.053728] Starting dynamic subtest: vecs0
[420.082049] Dynamic subtest vecs0: SUCCESS (3.039s)
[420.093732] Starting dynamic subtest: ccs0
[423.134251] Dynamic subtest ccs0: SUCCESS (3.051s)
[423.136514] Subtest busy-accuracy-98: SUCCESS (18.261s)
[423.253653] [069/133] (597s left) gem_pxp (hw-rejects-pxp-context)
[423.382319] Starting subtest: hw-rejects-pxp-context
[423.387238] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[423.493150] [070/133] (597s left) gem_exec_suspend (basic-s3)
[423.558615] Starting subtest: basic-S3
[423.560942] Starting dynamic subtest: smem
[444.153775] Dynamic subtest smem: SUCCESS (6.294s)
[444.156987] Subtest basic-S3: SUCCESS (6.297s)
[444.346165] [071/133] (576s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[444.412787] Starting subtest: y-tiled-ccs-to-yf-tiled
[444.417196] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[444.608583] [072/133] (576s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[444.710114] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[444.712417] Starting dynamic subtest: pipe-A
[445.104312] Dynamic subtest pipe-A: SUCCESS (0.396s)
[445.117972] Starting dynamic subtest: pipe-B
[446.522288] Dynamic subtest pipe-B: SUCCESS (1.416s)
[446.533191] Starting dynamic subtest: pipe-C
[447.958300] Dynamic subtest pipe-C: SUCCESS (1.436s)
[447.967593] Starting dynamic subtest: pipe-D
[449.431614] Dynamic subtest pipe-D: SUCCESS (1.473s)
[449.433789] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.725s)
[450.658172] [073/133] (570s left) kms_cdclk (mode-transition)
[450.754141] Starting subtest: mode-transition
[450.756383] Starting dynamic subtest: pipe-A-eDP-1
[451.047118] Dynamic subtest pipe-A-eDP-1: SKIP (0.290s)
[451.057885] Starting dynamic subtest: pipe-B-eDP-1
[451.062682] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[451.072654] Starting dynamic subtest: pipe-C-eDP-1
[451.077207] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[451.087152] Starting dynamic subtest: pipe-D-eDP-1
[451.091472] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[451.095551] Subtest mode-transition: SKIP (0.297s)
[451.899589] [074/133] (569s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[451.995219] Starting subtest: plane-upscale-factor-0-25-with-rotation
[451.997500] Starting dynamic subtest: pipe-A
[452.220676] Dynamic subtest pipe-A: SUCCESS (0.227s)
[452.232006] Starting dynamic subtest: pipe-B
[453.568077] Dynamic subtest pipe-B: SUCCESS (1.346s)
[453.581753] Starting dynamic subtest: pipe-C
[454.908488] Dynamic subtest pipe-C: SUCCESS (1.338s)
[454.918911] Starting dynamic subtest: pipe-D
[456.246094] Dynamic subtest pipe-D: SUCCESS (1.337s)
[456.248357] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.255s)
[457.434890] [075/133] (564s left) syncobj_basic (bad-destroy-pad)
[457.494032] Starting subtest: bad-destroy-pad
[457.496318] Subtest bad-destroy-pad: SUCCESS (0.000s)
[457.590797] [076/133] (563s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[457.692303] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[457.696646] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[457.787757] [077/133] (563s left) prime_mmap (test_forked_cpu_write)
[457.835804] Starting subtest: test_forked_cpu_write
[457.837688] Starting dynamic subtest: test_forked_cpu_write-smem
[457.839606] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.006s)
[457.841864] Subtest test_forked_cpu_write: SUCCESS (0.007s)
[457.951634] [078/133] (563s left) sw_sync (alloc_fence_invalid_timeline)
[457.991275] Starting subtest: alloc_fence_invalid_timeline
[457.993671] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[458.044581] [079/133] (563s left) kms_cursor_crc (cursor-random-256x256)
[458.272957] Starting subtest: cursor-random-256x256
[458.277796] Starting dynamic subtest: pipe-A-eDP-1
[461.633327] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.355s)
[461.659196] Starting dynamic subtest: pipe-D-eDP-1
[465.632712] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.978s)
[465.634902] Subtest cursor-random-256x256: SUCCESS (7.358s)
[466.495596] [080/133] (555s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[466.620472] Starting subtest: short-flip-after-cursor-atomic-transitions
[467.605242] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.986s)
[468.420229] [081/133] (553s left) gem_exec_balancer (bonded-chain)
[468.474239] Starting subtest: bonded-chain
[475.473438] Subtest bonded-chain: SUCCESS (6.998s)
[475.792200] [082/133] (545s left) gem_mmap_gtt (basic-small-copy)
[475.852674] Subtest basic-small-copy: SKIP (0.000s)
[475.908982] [083/133] (545s left) syncobj_timeline (wait-all-for-submit-snapshot)
[475.960819] Starting subtest: wait-all-for-submit-snapshot
[476.041945] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[476.120074] [084/133] (545s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[476.224113] Starting subtest: drrs-dirtyfb-ioctl
[476.235997] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[476.315801] [085/133] (545s left) i915_suspend (forcewake)
[476.359215] Starting subtest: forcewake
[508.173705] Subtest forcewake: SUCCESS (2.212s)
[509.273956] [086/133] (513s left) gem_mmap_gtt (basic-write)
[509.333439] Subtest basic-write: SKIP (0.000s)
[509.389237] [087/133] (513s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[509.448480] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[509.555431] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.106s)
[509.632711] [088/133] (513s left) kms_dsc (dsc-with-formats)
[509.725949] Starting subtest: dsc-with-formats
[509.764156] Subtest dsc-with-formats: SKIP (0.003s)
[509.808961] [089/133] (513s left) syncobj_wait (single-wait-all-submitted)
[509.868209] Starting subtest: single-wait-all-submitted
[509.971288] Subtest single-wait-all-submitted: SUCCESS (0.103s)
[510.048783] [090/133] (513s left) gem_lmem_swapping (verify-ccs)
[510.089164] Subtest verify-ccs: SKIP (0.000s)
[510.134314] [091/133] (513s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[510.226366] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[510.228302] Starting dynamic subtest: pipe-A-eDP-1
[510.644164] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.418s)
[510.657335] Starting dynamic subtest: pipe-B-eDP-1
[510.677652] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[510.687752] Starting dynamic subtest: pipe-C-eDP-1
[510.706854] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[510.715732] Starting dynamic subtest: pipe-D-eDP-1
[510.734480] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[510.736321] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.433s)
[512.909567] [092/133] (510s left) perf_pmu (init-wait)
[512.947317] Starting subtest: init-wait
[512.949670] Starting dynamic subtest: rcs0
[512.951881] Dynamic subtest rcs0: SUCCESS (0.001s)
[512.963729] Starting dynamic subtest: bcs0
[512.965910] Dynamic subtest bcs0: SUCCESS (0.000s)
[512.975592] Starting dynamic subtest: vcs0
[512.977732] Dynamic subtest vcs0: SUCCESS (0.001s)
[512.987415] Starting dynamic subtest: vcs1
[512.989181] Dynamic subtest vcs1: SUCCESS (0.001s)
[513.000634] Starting dynamic subtest: vecs0
[513.002855] Dynamic subtest vecs0: SUCCESS (0.001s)
[513.011937] Starting dynamic subtest: ccs0
[513.013960] Dynamic subtest ccs0: SUCCESS (0.000s)
[513.016042] Subtest init-wait: SUCCESS (0.007s)
[513.063730] [093/133] (510s left) gem_exec_parallel (engines)
[513.126135] Starting subtest: engines
[513.128451] Starting dynamic subtest: basic
[514.711384] Dynamic subtest basic: SUCCESS (1.587s)
[514.724179] Starting dynamic subtest: contexts
[519.020114] Dynamic subtest contexts: SUCCESS (4.306s)
[519.034255] Starting dynamic subtest: fds
[522.838968] Dynamic subtest fds: SUCCESS (3.818s)
[522.851267] Starting dynamic subtest: userptr
[524.760681] Dynamic subtest userptr: SUCCESS (1.918s)
[524.763404] Subtest engines: SUCCESS (11.638s)
[524.956560] [094/133] (498s left) kms_getfb (getfb-reject-nv12)
[525.044231] Starting subtest: getfb-reject-nv12
[525.046621] Subtest getfb-reject-nv12: SUCCESS (0.002s)
[525.151350] [095/133] (498s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[525.254652] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[525.256909] Starting dynamic subtest: pipe-A-eDP-1
[525.374898] Dynamic subtest pipe-A-eDP-1: SKIP (0.124s)
[525.385678] Starting dynamic subtest: pipe-B-eDP-1
[526.579404] Dynamic subtest pipe-B-eDP-1: SKIP (1.202s)
[526.583595] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.329s)
[527.823179] [096/133] (495s left) gem_flink_race (flink_close)
[527.869216] Starting subtest: flink_close
[532.966375] Subtest flink_close: SUCCESS (5.097s)
[533.042024] [097/133] (490s left) gem_spin_batch (user-each)
[533.115692] Starting subtest: user-each
[536.487710] Subtest user-each: SUCCESS (3.369s)
[536.777228] [098/133] (486s left) kms_content_protection (uevent)
[536.922380] Starting subtest: uevent
[536.926842] Subtest uevent: SKIP (0.000s)
[537.046451] [099/133] (486s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[537.414647] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[538.230251] [100/133] (485s left) gem_mmap_wc (write-read-distinct)
[538.287408] Subtest write-read-distinct: SKIP (0.000s)
[538.344206] [101/133] (485s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[538.428067] Starting subtest: plane-primary-toggle-with-vblank-wait
[538.430362] Starting dynamic subtest: pipe-A-eDP-1
[538.602395] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[538.914258] Starting dynamic subtest: pipe-B-eDP-1
[539.812442] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.906s)
[540.110597] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.683s)
[540.964956] [102/133] (482s left) gem_partial_pwrite_pread (write-snoop)
[541.036103] Subtest write-snoop: SKIP (0.000s)
[541.111505] [103/133] (482s left) gen7_exec_parse (oacontrol-tracking)
[541.172968] Subtest oacontrol-tracking: SKIP (0.000s)
[541.237739] [104/133] (482s left) gem_basic (multigpu-create-close)
[541.299148] Starting subtest: multigpu-create-close
[541.303158] Subtest multigpu-create-close: SKIP (0.000s)
[541.410311] [105/133] (482s left) kms_psr (fbc-psr-dpms)
[541.502467] Starting subtest: fbc-psr-dpms
[541.504802] Starting dynamic subtest: eDP-1
[541.509281] Dynamic subtest eDP-1: SKIP (0.000s)
[541.513805] Subtest fbc-psr-dpms: SKIP (0.001s)
[541.577563] [106/133] (482s left) gem_unfence_active_buffers
[541.749065] [107/133] (482s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[541.849996] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[548.431610] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.586s)
[548.549388] [108/133] (475s left) gem_ctx_shared (single-timeline)
[548.606295] Starting subtest: single-timeline
[548.626007] Subtest single-timeline: SUCCESS (0.011s)
[548.854977] [109/133] (475s left) gem_gpgpu_fill (basic)
[548.919998] Starting subtest: basic
[548.922217] Starting dynamic subtest: smem
[548.926843] Dynamic subtest smem: SUCCESS (0.008s)
[548.928985] Subtest basic: SUCCESS (0.009s)
[549.081644] [110/133] (474s left) gem_exec_schedule (u-submit-golden-slice)
[549.164606] Starting subtest: u-submit-golden-slice
[549.166996] Starting dynamic subtest: rcs0
[549.188401] Dynamic subtest rcs0: SUCCESS (0.033s)
[549.201817] Starting dynamic subtest: bcs0
[549.220419] Dynamic subtest bcs0: SUCCESS (0.031s)
[549.233415] Starting dynamic subtest: vcs0
[549.251931] Dynamic subtest vcs0: SUCCESS (0.031s)
[549.261660] Starting dynamic subtest: vcs1
[549.287953] Dynamic subtest vcs1: SUCCESS (0.035s)
[549.298031] Starting dynamic subtest: vecs0
[549.323645] Dynamic subtest vecs0: SUCCESS (0.035s)
[549.334045] Starting dynamic subtest: ccs0
[549.354263] Dynamic subtest ccs0: SUCCESS (0.029s)
[549.357073] Subtest u-submit-golden-slice: SUCCESS (0.199s)
[550.839684] [111/133] (473s left) kms_pm_rpm (drm-resources-equal)
[552.879963] Starting subtest: drm-resources-equal
[557.897522] Subtest drm-resources-equal: SUCCESS (5.019s)
[558.005130] [112/133] (466s left) kms_psr (pr-sprite-plane-onoff)
[558.102236] Starting subtest: pr-sprite-plane-onoff
[558.106485] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[558.177965] [113/133] (465s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[558.273982] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[558.278374] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[558.354869] [114/133] (465s left) gem_exec_create (madvise)
[558.412642] Starting subtest: madvise
[558.415060] Starting dynamic subtest: smem
[578.566331] Dynamic subtest smem: SUCCESS (20.154s)
[578.569241] Subtest madvise: SUCCESS (20.155s)
[579.342965] [115/133] (444s left) gem_mmap_offset (pf-nonblock)
[579.408925] Starting subtest: pf-nonblock
[579.417219] Subtest pf-nonblock: SUCCESS (0.010s)
[579.517091] [116/133] (444s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[579.630472] Starting subtest: y-tiled-64bpp-rotate-270
[579.634567] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[579.746812] [117/133] (444s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[579.837353] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[580.961129] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.124s)
[581.107992] [118/133] (443s left) gem_exec_schedule (submit-late-slice)
[581.169706] Starting subtest: submit-late-slice
[581.171614] Starting dynamic subtest: rcs0
[581.200483] Dynamic subtest rcs0: SUCCESS (0.035s)
[581.210015] Starting dynamic subtest: bcs0
[581.231650] Dynamic subtest bcs0: SUCCESS (0.031s)
[581.241479] Starting dynamic subtest: vcs0
[581.264703] Dynamic subtest vcs0: SUCCESS (0.032s)
[581.274194] Starting dynamic subtest: vcs1
[581.298311] Dynamic subtest vcs1: SUCCESS (0.032s)
[581.308021] Starting dynamic subtest: vecs0
[581.327860] Dynamic subtest vecs0: SUCCESS (0.029s)
[581.337224] Starting dynamic subtest: ccs0
[581.360659] Dynamic subtest ccs0: SUCCESS (0.031s)
[581.362658] Subtest submit-late-slice: SUCCESS (0.196s)
[582.825531] [119/133] (441s left) syncobj_timeline (reset-multiple-signaled)
[582.875271] Starting subtest: reset-multiple-signaled
[582.877511] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[582.966749] [120/133] (441s left) gem_ctx_engines (independent)
[583.028946] Starting subtest: independent
[583.031454] Starting dynamic subtest: rcs0
[583.158429] Dynamic subtest rcs0: SUCCESS (0.127s)
[583.172002] Starting dynamic subtest: bcs0
[583.290030] Dynamic subtest bcs0: SUCCESS (0.131s)
[583.301798] Starting dynamic subtest: vcs0
[583.408777] Dynamic subtest vcs0: SUCCESS (0.118s)
[583.422543] Starting dynamic subtest: vcs1
[583.519764] Dynamic subtest vcs1: SUCCESS (0.111s)
[583.536422] Starting dynamic subtest: vecs0
[583.702543] Dynamic subtest vecs0: SUCCESS (0.181s)
[583.716003] Starting dynamic subtest: ccs0
[583.956255] Dynamic subtest ccs0: SUCCESS (0.252s)
[583.970284] Starting dynamic subtest: all-engines
[585.132857] Dynamic subtest all-engines: SUCCESS (1.176s)
[585.135568] Subtest independent: SUCCESS (2.103s)
[586.768584] [121/133] (437s left) kms_flip (plain-flip-fb-recreate)
[586.872624] Starting subtest: plain-flip-fb-recreate
[586.874978] Starting dynamic subtest: A-eDP1
[595.710396] Dynamic subtest A-eDP1: SUCCESS (8.837s)
[595.721994] Starting dynamic subtest: B-eDP1
[604.532389] Dynamic subtest B-eDP1: SUCCESS (8.820s)
[604.545755] Starting dynamic subtest: C-eDP1
[613.356964] Dynamic subtest C-eDP1: SUCCESS (8.821s)
[613.367297] Starting dynamic subtest: D-eDP1
[622.195849] Dynamic subtest D-eDP1: SUCCESS (8.837s)
[622.199800] Subtest plain-flip-fb-recreate: SUCCESS (35.328s)
[623.377910] [122/133] (401s left) gem_exec_schedule (smoketest-all)
[623.449615] Starting subtest: smoketest-all
[655.805447] Subtest smoketest-all: SUCCESS (32.314s)
[656.781580] [123/133] (367s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[656.876788] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[656.880985] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[656.969731] [124/133] (367s left) i915_drm_fdinfo (memory-info-idle)
[657.015950] Starting subtest: memory-info-idle
[657.017866] Starting dynamic subtest: smem0
[657.069312] Dynamic subtest smem0: SUCCESS (0.055s)
[657.071978] Subtest memory-info-idle: SUCCESS (0.056s)
[657.155348] [125/133] (367s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[657.259649] Starting subtest: fbc-psr2-primary-mmap-gtt
[657.261982] Starting dynamic subtest: eDP-1
[657.266462] Dynamic subtest eDP-1: SKIP (0.000s)
[657.270892] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[657.332162] [126/133] (367s left) kms_joiner (basic-ultra-joiner)
[657.420955] Starting subtest: basic-ultra-joiner
[657.425174] Subtest basic-ultra-joiner: SKIP (0.000s)
[657.490562] [127/133] (367s left) perf (per-context-mode-unprivileged)
[657.584978] Starting subtest: per-context-mode-unprivileged
[657.589267] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[657.631794] [128/133] (367s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[657.717244] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[657.744687] Starting dynamic subtest: pipe-A-plane-0
[658.044291] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[658.054373] Starting dynamic subtest: pipe-A-plane-3
[658.277820] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[658.289885] Starting dynamic subtest: pipe-A-plane-5
[658.293725] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[659.363529] Starting dynamic subtest: pipe-B-plane-0
[659.655086] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[659.665966] Starting dynamic subtest: pipe-B-plane-3
[659.899100] Dynamic subtest pipe-B-plane-3: SUCCESS (0.243s)
[659.909184] Starting dynamic subtest: pipe-B-plane-5
[659.913211] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[660.207100] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.491s)
[661.034242] [129/133] (363s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[661.141693] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[668.235503] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.097s)
[668.369248] [130/133] (356s left) gem_ctx_exec (basic)
[668.427231] Starting subtest: basic
[668.429692] Subtest basic: SUCCESS (0.005s)
[668.574694] [131/133] (356s left) kms_color (ctm-0-50)
[668.661314] Starting subtest: ctm-0-50
[668.663569] Starting dynamic subtest: pipe-A-eDP-1
[672.101436] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.439s)
[672.111489] Starting dynamic subtest: pipe-B-eDP-1
[676.397261] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.296s)
[676.407141] Starting dynamic subtest: pipe-C-eDP-1
[680.435708] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.036s)
[680.447053] Starting dynamic subtest: pipe-D-eDP-1
[684.431965] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.996s)
[684.433826] Subtest ctm-0-50: SUCCESS (15.770s)
[685.263635] [132/133] (339s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[685.363138] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[686.120635] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.759s)
[686.287062] [133/133] (338s left) gem_exec_params (invalid-bsd1-flag-on-render)
[686.343956] Starting subtest: invalid-bsd1-flag-on-render
[686.346111] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[686.437960] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_properties
results: parsing output: 2/ for test: api_intel_allocator
results: parsing output: 3/ for test: kms_vblank
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: gem_ctx_exec
results: parsing output: 6/ for test: gem_exec_params
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: i915_pm_freq_api
results: parsing output: 9/ for test: kms_pipe_crc_basic
results: parsing output: 10/ for test: kms_addfb_basic
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: kms_flip_scaled_crc
results: parsing output: 13/ for test: gem_exec_schedule
results: parsing output: 14/ for test: sysfs_heartbeat_interval
results: parsing output: 15/ for test: gem_userptr_blits
results: parsing output: 16/ for test: kms_lease
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_flip_scaled_crc
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: gem_ctx_persistence
results: parsing output: 23/ for test: i915_query
results: parsing output: 24/ for test: vgem_basic
results: parsing output: 25/ for test: gem_unref_active_buffers
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: gem_mmap_gtt
results: parsing output: 29/ for test: kms_cursor_legacy
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: i915_pm_rc6_residency
results: parsing output: 32/ for test: kms_pm_rpm
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_capture
results: parsing output: 35/ for test: kms_cursor_crc
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: kms_cursor_legacy
results: parsing output: 41/ for test: prime_mmap_coherency
results: parsing output: 42/ for test: gem_exec_fence
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_async_flips
results: parsing output: 45/ for test: gem_exec_nop
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_chamelium_edid
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: drm_mm
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_wait
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: prime_busy
results: parsing output: 57/ for test: kms_busy
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: kms_plane
results: parsing output: 60/ for test: kms_prop_blob
results: parsing output: 61/ for test: gem_ctx_param
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: api_intel_bb
results: parsing output: 64/ for test: gem_render_copy_redux
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_scaling_modes
results: parsing output: 67/ for test: perf_pmu
results: parsing output: 68/ for test: gem_pxp
results: parsing output: 69/ for test: gem_exec_suspend
results: parsing output: 70/ for test: gem_render_copy
results: parsing output: 71/ for test: kms_plane_scaling
results: parsing output: 72/ for test: kms_cdclk
results: parsing output: 73/ for test: kms_plane_scaling
results: parsing output: 74/ for test: syncobj_basic
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: prime_mmap
results: parsing output: 77/ for test: sw_sync
results: parsing output: 78/ for test: kms_cursor_crc
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: gem_exec_balancer
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_dirtyfb
results: parsing output: 84/ for test: i915_suspend
results: parsing output: 85/ for test: gem_mmap_gtt
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: kms_dsc
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: gem_lmem_swapping
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: perf_pmu
results: parsing output: 92/ for test: gem_exec_parallel
results: parsing output: 93/ for test: kms_getfb
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: gem_flink_race
results: parsing output: 96/ for test: gem_spin_batch
results: parsing output: 97/ for test: kms_content_protection
results: parsing output: 98/ for test: kms_chamelium_hpd
results: parsing output: 99/ for test: gem_mmap_wc
results: parsing output: 100/ for test: kms_atomic_transition
results: parsing output: 101/ for test: gem_partial_pwrite_pread
results: parsing output: 102/ for test: gen7_exec_parse
results: parsing output: 103/ for test: gem_basic
results: parsing output: 104/ for test: kms_psr
results: parsing output: 105/ for test: gem_unfence_active_buffers
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_ctx_shared
results: parsing output: 108/ for test: gem_gpgpu_fill
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: kms_pm_rpm
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_exec_create
results: parsing output: 114/ for test: gem_mmap_offset
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: gem_exec_schedule
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: gem_ctx_engines
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: i915_drm_fdinfo
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_joiner
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: kms_plane
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_color
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: gem_exec_params
Done.