[57.922380] Initializing watchdogs
[57.922427]   /dev/watchdog0
[57.937009] [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.941539] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.943722] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.947038] [001/133] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[58.061381] Starting subtest: psr-rgb565-draw-mmap-wc
[64.781566] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.768s)
[64.891870] [002/133] (953s left) gem_exec_balancer (parallel-out-fence)
[65.163990] Starting subtest: parallel-out-fence
[66.258341] Subtest parallel-out-fence: SUCCESS (1.098s)
[66.378095] [003/133] (952s left) core_setmaster (master-drop-set-shared-fd)
[66.467174] Starting subtest: master-drop-set-shared-fd
[66.474402] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[66.508870] [004/133] (952s left) prime_busy (hang)
[66.569138] Starting subtest: hang
[66.571676] Starting dynamic subtest: rcs0
[92.398967] Dynamic subtest rcs0: SUCCESS (25.843s)
[92.407298] Starting dynamic subtest: bcs0
[99.557037] Dynamic subtest bcs0: SUCCESS (7.154s)
[99.565769] Starting dynamic subtest: vcs0
[106.591525] Dynamic subtest vcs0: SUCCESS (7.035s)
[106.600717] Starting dynamic subtest: vcs1
[113.567604] Dynamic subtest vcs1: SUCCESS (6.975s)
[113.576792] Starting dynamic subtest: vecs0
[120.538557] Dynamic subtest vecs0: SUCCESS (6.972s)
[120.548207] Starting dynamic subtest: ccs0
[134.444926] Dynamic subtest ccs0: SUCCESS (13.902s)
[134.447147] Subtest hang: SUCCESS (67.885s)
[134.575206] [005/133] (884s left) syncobj_timeline (multi-wait-all-signaled)
[134.637671] Starting subtest: multi-wait-all-signaled
[134.640112] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[134.734509] [006/133] (883s left) gem_lmem_swapping (heavy-verify-random-ccs)
[134.772559] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[134.816163] [007/133] (883s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[134.929083] Starting subtest: fbcpsr-rgb565-draw-pwrite
[142.124898] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.200s)
[142.247362] [008/133] (876s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[142.300773] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[142.302768] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[142.407928] [009/133] (876s left) syncobj_basic (bad-fd-to-handle)
[142.469996] Starting subtest: bad-fd-to-handle
[142.472408] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[142.590363] [010/133] (876s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[142.695105] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[142.699526] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[142.788854] [011/133] (876s left) gem_exec_schedule (out-order)
[142.861975] Starting subtest: out-order
[142.864387] Starting dynamic subtest: rcs0
[142.964784] Dynamic subtest rcs0: SUCCESS (0.106s)
[142.974888] Starting dynamic subtest: vcs0
[143.046738] Dynamic subtest vcs0: SUCCESS (0.082s)
[143.056808] Starting dynamic subtest: vcs1
[143.134678] Dynamic subtest vcs1: SUCCESS (0.088s)
[143.146804] Starting dynamic subtest: vecs0
[143.216205] Dynamic subtest vecs0: SUCCESS (0.081s)
[143.226518] Starting dynamic subtest: ccs0
[143.306683] Dynamic subtest ccs0: SUCCESS (0.090s)
[143.309244] Subtest out-order: SUCCESS (0.449s)
[145.005489] [012/133] (873s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[145.118269] Starting subtest: primary-yf-tiled-reflect-x-90
[145.122487] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[145.190915] [013/133] (873s left) syncobj_basic (bad-pad-handle-to-fd)
[145.252095] Starting subtest: bad-pad-handle-to-fd
[145.254344] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[145.345230] [014/133] (873s left) gem_softpin (32b-excludes-last-page)
[145.408926] Starting subtest: 32b-excludes-last-page
[145.413877] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[145.545512] [015/133] (873s left) gem_exec_reloc (basic-wc-noreloc)
[145.612602] Subtest basic-wc-noreloc: SKIP (0.000s)
[145.686873] [016/133] (873s left) syncobj_wait (wait-all-snapshot)
[145.737607] Starting subtest: wait-all-snapshot
[145.818065] Subtest wait-all-snapshot: SUCCESS (0.081s)
[145.901492] [017/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[145.996862] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[146.000377] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[146.095866] [018/133] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[146.198862] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[153.525077] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.328s)
[153.655319] [019/133] (865s left) gem_eio (wait-wedge-10ms)
[153.862155] Starting subtest: wait-wedge-10ms
[154.968810] Subtest wait-wedge-10ms: SUCCESS (1.107s)
[155.094900] [020/133] (864s left) gem_exec_reloc (basic-cpu-noreloc)
[155.148245] Subtest basic-cpu-noreloc: SKIP (0.000s)
[155.213203] [021/133] (863s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[155.320217] Starting subtest: bad-aux-stride-yf-tiled-ccs
[155.322609] Starting dynamic subtest: pipe-A-eDP-1
[155.345529] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[155.354678] Starting dynamic subtest: pipe-B-eDP-1
[155.375288] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[155.382242] Starting dynamic subtest: pipe-C-eDP-1
[155.403423] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[155.410690] Starting dynamic subtest: pipe-D-eDP-1
[155.432260] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[155.436585] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.003s)
[157.636167] [022/133] (861s left) gem_readwrite (write-bad-handle)
[157.696268] Subtest write-bad-handle: SKIP (0.000s)
[157.754979] [023/133] (861s left) gem_pxp (create-regular-context-2)
[157.865943] Starting subtest: create-regular-context-2
[157.868533] Subtest create-regular-context-2: SUCCESS (0.002s)
[157.968749] [024/133] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[158.071021] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[165.125051] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.056s)
[165.238125] [025/133] (854s left) perf (low-oa-exponent-permissions)
[165.371318] Starting subtest: low-oa-exponent-permissions
[165.406068] Subtest low-oa-exponent-permissions: SUCCESS (0.036s)
[165.452107] [026/133] (853s left) kms_psr (psr-basic)
[165.558772] Starting subtest: psr-basic
[165.561036] Starting dynamic subtest: eDP-1
[167.156866] Dynamic subtest eDP-1: SUCCESS (1.599s)
[167.159616] Subtest psr-basic: SUCCESS (1.600s)
[168.048284] [027/133] (851s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[168.137132] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[168.139496] Starting dynamic subtest: pipe-A-default-mode
[169.285634] Dynamic subtest pipe-A-default-mode: SKIP (1.148s)
[169.290087] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.149s)
[169.462570] [028/133] (850s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[169.556057] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[169.560198] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[169.647702] [029/133] (849s left) kms_vblank (ts-continuation-dpms-suspend)
[169.744224] Starting subtest: ts-continuation-dpms-suspend
[169.746159] Starting dynamic subtest: pipe-A-eDP-1
[186.964293] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.037s)
[186.978431] Starting dynamic subtest: pipe-D-eDP-1
[204.926838] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.538s)
[204.929119] Subtest ts-continuation-dpms-suspend: SUCCESS (4.578s)
[205.731967] [030/133] (813s left) gem_exec_store (cachelines)
[205.797086] Starting subtest: cachelines
[205.799305] Starting dynamic subtest: rcs0
[205.856178] Dynamic subtest rcs0: SUCCESS (0.063s)
[205.867630] Starting dynamic subtest: bcs0
[205.910300] Dynamic subtest bcs0: SUCCESS (0.054s)
[205.921500] Starting dynamic subtest: vcs0
[205.966875] Dynamic subtest vcs0: SUCCESS (0.057s)
[205.977329] Starting dynamic subtest: vcs1
[206.016049] Dynamic subtest vcs1: SUCCESS (0.050s)
[206.026211] Starting dynamic subtest: vecs0
[206.069699] Dynamic subtest vecs0: SUCCESS (0.053s)
[206.080777] Starting dynamic subtest: ccs0
[206.126881] Dynamic subtest ccs0: SUCCESS (0.060s)
[206.129218] Subtest cachelines: SUCCESS (0.343s)
[206.306095] [031/133] (813s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[206.404329] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[207.354932] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.978s)
[207.482434] [032/133] (812s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[207.575225] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[207.579579] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[207.661413] [033/133] (812s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[207.720513] Starting subtest: invalid-bsd1-flag-on-vebox
[207.722068] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[207.822408] [034/133] (811s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[207.916352] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[215.182380] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.386s)
[215.318626] [035/133] (804s left) kms_cursor_crc (cursor-sliding-128x42)
[215.543363] Starting subtest: cursor-sliding-128x42
[215.588966] Subtest cursor-sliding-128x42: SKIP (0.040s)
[215.803957] [036/133] (804s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[215.897733] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[215.901613] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[215.993359] [037/133] (803s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[216.074533] Starting subtest: fbc-pr-cursor-mmap-gtt
[216.078856] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[216.143798] [038/133] (803s left) api_intel_allocator (two-level-inception)
[216.201817] Starting subtest: two-level-inception
[221.555715] Subtest two-level-inception: SUCCESS (5.403s)
[221.677463] [039/133] (798s left) gem_reg_read (timestamp-monotonic)
[221.730920] Starting subtest: timestamp-monotonic
[225.841587] Subtest timestamp-monotonic: SUCCESS (4.131s)
[225.916970] [040/133] (794s left) kms_chamelium_hpd (dp-hpd-storm)
[226.305863] Subtest dp-hpd-storm: SKIP (0.000s)
[227.099266] [041/133] (792s left) gem_ctx_persistence (process)
[227.166298] Starting subtest: process
[227.364539] Subtest process: SUCCESS (0.199s)
[227.446013] [042/133] (792s left) i915_getparams_basic (basic-subslice-total)
[227.498456] Starting subtest: basic-subslice-total
[227.502913] Subtest basic-subslice-total: SUCCESS (0.000s)
[227.575847] [043/133] (792s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[227.690405] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[227.694940] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[227.831944] [044/133] (792s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[227.913723] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[227.917799] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[227.982991] [045/133] (792s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[228.061950] Starting subtest: crc-primary-suspend-y-tiled-ccs
[228.063884] Starting dynamic subtest: pipe-A-eDP-1
[228.071168] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[228.079587] Starting dynamic subtest: pipe-B-eDP-1
[228.087375] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[228.095600] Starting dynamic subtest: pipe-C-eDP-1
[228.102955] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[228.110582] Starting dynamic subtest: pipe-D-eDP-1
[228.117526] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[228.120877] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.006s)
[230.361574] [046/133] (789s left) kms_rotation_crc (primary-rotation-180)
[230.449375] Starting subtest: primary-rotation-180
[237.422242] Subtest primary-rotation-180: SUCCESS (6.986s)
[238.619984] [047/133] (781s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[238.714533] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[238.716809] Starting dynamic subtest: pipe-A-eDP-1
[238.801483] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[238.810241] Starting dynamic subtest: pipe-B-eDP-1
[239.903734] Dynamic subtest pipe-B-eDP-1: SKIP (1.103s)
[239.908036] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.193s)
[241.100077] [048/133] (779s left) i915_pm_rpm (system-suspend-execbuf)
[242.823532] Starting subtest: system-suspend-execbuf
[301.343300] Subtest system-suspend-execbuf: SUCCESS (43.380s)
[301.757208] [049/133] (718s left) gem_exec_nop (basic-parallel)
[301.836507] Starting subtest: basic-parallel
[318.120249] Subtest basic-parallel: SUCCESS (16.290s)
[318.325880] [050/133] (702s left) gem_create (create-ext-set-pat)
[318.492455] Starting subtest: create-ext-set-pat
[318.495280] Subtest create-ext-set-pat: SUCCESS (0.000s)
[318.573777] [051/133] (701s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[318.668167] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[318.670299] Starting dynamic subtest: pipe-A
[318.689624] Dynamic subtest pipe-A: SKIP (0.017s)
[318.698175] Starting dynamic subtest: pipe-B
[318.719788] Dynamic subtest pipe-B: SKIP (0.030s)
[318.728916] Starting dynamic subtest: pipe-C
[318.752990] Dynamic subtest pipe-C: SKIP (0.033s)
[318.761779] Starting dynamic subtest: pipe-D
[318.784556] Dynamic subtest pipe-D: SKIP (0.030s)
[318.788516] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.112s)
[318.887636] [052/133] (701s left) kms_atomic_interruptible (legacy-pageflip)
[318.982182] Starting subtest: legacy-pageflip
[318.984432] Starting dynamic subtest: pipe-A-eDP-1
[326.438075] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.455s)
[326.440237] Subtest legacy-pageflip: SUCCESS (7.456s)
[327.283432] [053/133] (693s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[327.411076] Starting subtest: basic-flip-after-cursor-legacy
[328.337435] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.927s)
[329.220786] [054/133] (691s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[329.314962] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[329.319349] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[329.402635] [055/133] (691s left) syncobj_timeline (wait-all-delayed-signal)
[329.453628] Starting subtest: wait-all-delayed-signal
[329.555082] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[329.634947] [056/133] (690s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[329.725948] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[336.568590] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.845s)
[336.694767] [057/133] (683s left) prime_self_import (basic-with_two_bos)
[336.732793] Starting subtest: basic-with_two_bos
[336.747147] Subtest basic-with_two_bos: SUCCESS (0.016s)
[336.829353] [058/133] (683s left) fbdev (unaligned-write)
[336.873381] Starting subtest: unaligned-write
[336.971662] Subtest unaligned-write: SUCCESS (0.100s)
[337.033894] [059/133] (683s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[337.130727] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[337.134876] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[337.233841] [060/133] (683s left) kms_sequence (get-idle)
[337.321844] Starting subtest: get-idle
[337.323748] Starting dynamic subtest: pipe-A-eDP-1
[339.144425] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.822s)
[339.153724] Starting dynamic subtest: pipe-B-eDP-1
[341.612333] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.468s)
[341.621671] Starting dynamic subtest: pipe-C-eDP-1
[344.022785] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.410s)
[344.032006] Starting dynamic subtest: pipe-D-eDP-1
[346.446005] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.424s)
[346.448286] Subtest get-idle: SUCCESS (9.129s)
[347.268900] [061/133] (673s left) perf_pmu (busy-start)
[347.310400] Starting subtest: busy-start
[347.312381] Starting dynamic subtest: rcs0
[349.860541] Dynamic subtest rcs0: SUCCESS (2.552s)
[349.873060] Starting dynamic subtest: bcs0
[352.411444] Dynamic subtest bcs0: SUCCESS (2.549s)
[352.422013] Starting dynamic subtest: vcs0
[356.134189] Dynamic subtest vcs0: SUCCESS (3.721s)
[356.145831] Starting dynamic subtest: vcs1
[360.166128] Dynamic subtest vcs1: SUCCESS (4.029s)
[360.178067] Starting dynamic subtest: vecs0
[364.134831] Dynamic subtest vecs0: SUCCESS (3.964s)
[364.145724] Starting dynamic subtest: ccs0
[366.682903] Dynamic subtest ccs0: SUCCESS (2.546s)
[366.685619] Subtest busy-start: SUCCESS (19.374s)
[366.784099] [062/133] (654s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[366.897414] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[366.899617] Starting dynamic subtest: pipe-A-eDP-1
[366.923199] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[366.932957] Starting dynamic subtest: pipe-B-eDP-1
[366.955943] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[366.964704] Starting dynamic subtest: pipe-C-eDP-1
[366.987447] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[366.995984] Starting dynamic subtest: pipe-D-eDP-1
[367.019710] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[367.024042] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.008s)
[369.167938] [063/133] (651s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[369.272236] Starting subtest: yf-tiled-addfb-size-offset-overflow
[369.276639] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[369.365207] [064/133] (651s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[369.942389] Starting subtest: syncobj-timeline-invalid-wait
[369.944969] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[371.200651] [FACT gem_exec_fence (syncobj-timeline-invalid-wait)] new: kernel.kmod_is_loaded.vgem: true
[371.204460] [065/133] (649s left) gem_ctx_persistence (engines-queued)
[371.270885] Starting subtest: engines-queued
[371.272849] Starting dynamic subtest: rcs0
[371.332999] Dynamic subtest rcs0: SUCCESS (0.063s)
[371.344010] Starting dynamic subtest: bcs0
[371.398826] Dynamic subtest bcs0: SUCCESS (0.065s)
[371.412310] Starting dynamic subtest: vcs0
[373.125691] Dynamic subtest vcs0: SUCCESS (1.724s)
[373.138137] Starting dynamic subtest: vcs1
[375.109053] Dynamic subtest vcs1: SUCCESS (1.979s)
[375.121328] Starting dynamic subtest: vecs0
[377.093890] Dynamic subtest vecs0: SUCCESS (1.981s)
[377.105971] Starting dynamic subtest: ccs0
[377.305313] Dynamic subtest ccs0: SUCCESS (0.210s)
[377.307932] Subtest engines-queued: SUCCESS (6.034s)
[377.385649] [066/133] (643s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[377.446271] Starting subtest: etime-multi-wait-available-unsubmitted
[377.546575] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.101s)
[377.641064] [067/133] (643s left) kms_prop_blob (blob-prop-core)
[377.697007] Starting subtest: blob-prop-core
[377.699161] Subtest blob-prop-core: SUCCESS (0.000s)
[377.793469] [068/133] (643s left) gem_mmap_gtt (basic-write-gtt)
[377.837744] Subtest basic-write-gtt: SKIP (0.000s)
[377.894594] [069/133] (643s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[377.995886] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[379.031157] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.035s)
[379.157777] [070/133] (642s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[379.254470] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[379.258094] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[379.383567] [071/133] (641s left) kms_flip (dpms-vs-vblank-race)
[379.491901] Starting subtest: dpms-vs-vblank-race
[379.493702] Starting dynamic subtest: A-eDP1
[384.432275] Dynamic subtest A-eDP1: SUCCESS (4.939s)
[384.442133] Starting dynamic subtest: B-eDP1
[389.055201] Dynamic subtest B-eDP1: SUCCESS (4.620s)
[389.065666] Starting dynamic subtest: C-eDP1
[393.682426] Dynamic subtest C-eDP1: SUCCESS (4.627s)
[393.693604] Starting dynamic subtest: D-eDP1
[398.306426] Dynamic subtest D-eDP1: SUCCESS (4.620s)
[398.309102] Subtest dpms-vs-vblank-race: SUCCESS (18.816s)
[399.180929] [072/133] (622s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[399.251324] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[399.354885] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[399.432606] [073/133] (621s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[399.541573] Starting subtest: x-tiled-8bpp-rotate-270
[399.565968] Subtest x-tiled-8bpp-rotate-270: SKIP (0.024s)
[399.674264] [074/133] (621s left) kms_feature_discovery (display-2x)
[399.761574] Starting subtest: display-2x
[399.765863] Subtest display-2x: SKIP (0.000s)
[399.835409] [075/133] (621s left) kms_flip (dpms-off-confusion-interruptible)
[399.937831] Starting subtest: dpms-off-confusion-interruptible
[399.939991] Starting dynamic subtest: A-eDP1
[408.596836] Dynamic subtest A-eDP1: SUCCESS (8.658s)
[408.611578] Starting dynamic subtest: B-eDP1
[417.227637] Dynamic subtest B-eDP1: SUCCESS (8.629s)
[417.240974] Starting dynamic subtest: C-eDP1
[425.854431] Dynamic subtest C-eDP1: SUCCESS (8.625s)
[425.865407] Starting dynamic subtest: D-eDP1
[434.482312] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[434.487805] Subtest dpms-off-confusion-interruptible: SUCCESS (34.554s)
[435.641454] [076/133] (585s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[435.737334] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[435.765063] Starting dynamic subtest: pipe-A-plane-0
[436.053304] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[436.066096] Starting dynamic subtest: pipe-A-plane-3
[436.308797] Dynamic subtest pipe-A-plane-3: SUCCESS (0.254s)
[436.318114] Starting dynamic subtest: pipe-A-plane-5
[436.322364] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[437.429796] Starting dynamic subtest: pipe-B-plane-0
[437.719721] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[437.730011] Starting dynamic subtest: pipe-B-plane-3
[437.953042] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[437.962313] Starting dynamic subtest: pipe-B-plane-5
[437.966571] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[438.261700] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.526s)
[439.113082] [077/133] (582s left) gem_render_copy (yf-tiled-ccs-to-linear)
[439.180721] Starting subtest: yf-tiled-ccs-to-linear
[439.185370] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[439.380153] [078/133] (582s left) kms_invalid_mode (bad-vsync-start)
[439.465789] Starting subtest: bad-vsync-start
[439.468088] Starting dynamic subtest: pipe-A-eDP-1
[439.756150] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[439.768647] Starting dynamic subtest: pipe-B-eDP-1
[439.770840] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[439.780168] Starting dynamic subtest: pipe-C-eDP-1
[439.782015] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[439.791707] Starting dynamic subtest: pipe-D-eDP-1
[439.794153] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[439.796545] Subtest bad-vsync-start: SUCCESS (0.297s)
[440.554094] [079/133] (580s left) kms_lease (lease-unleased-connector)
[440.645310] Starting subtest: lease-unleased-connector
[440.647526] Starting dynamic subtest: pipe-A-eDP-1
[440.649706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[440.664217] Starting dynamic subtest: pipe-B-eDP-1
[440.666420] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[440.676565] Starting dynamic subtest: pipe-C-eDP-1
[440.678808] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[440.688404] Starting dynamic subtest: pipe-D-eDP-1
[440.690286] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[440.692186] Subtest lease-unleased-connector: SUCCESS (0.032s)
[440.783545] [080/133] (580s left) i915_pm_rps (thresholds-idle-park)
[440.848438] Starting subtest: thresholds-idle-park
[440.852795] Subtest thresholds-idle-park: SKIP (0.001s)
[440.949096] [081/133] (580s left) kms_chamelium_frames (dp-crc-single)
[441.323816] Subtest dp-crc-single: SKIP (0.000s)
[442.157984] [082/133] (579s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[442.247648] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[446.836204] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.593s)
[447.985329] [083/133] (573s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[448.077480] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[448.079780] Starting dynamic subtest: pipe-A
[449.192115] Dynamic subtest pipe-A: SUCCESS (1.117s)
[449.200987] Starting dynamic subtest: pipe-B
[451.331035] Dynamic subtest pipe-B: SUCCESS (2.138s)
[451.343556] Starting dynamic subtest: pipe-C
[452.485032] Dynamic subtest pipe-C: SUCCESS (1.153s)
[452.496146] Starting dynamic subtest: pipe-D
[453.638603] Dynamic subtest pipe-D: SUCCESS (1.153s)
[453.640800] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.565s)
[454.823373] [084/133] (566s left) gem_mmap_offset (bad-object)
[454.878563] Starting subtest: bad-object
[454.880894] Subtest bad-object: SUCCESS (0.001s)
[454.991467] [085/133] (566s left) testdisplay
[455.310042] [086/133] (566s left) gem_spin_batch (legacy-resubmit)
[455.376040] Starting subtest: legacy-resubmit
[455.378300] Starting dynamic subtest: default
[455.383075] Dynamic subtest default: SUCCESS (0.005s)
[455.393210] Starting dynamic subtest: render
[455.395303] Dynamic subtest render: SUCCESS (0.001s)
[455.404282] Starting dynamic subtest: bsd
[455.406436] Dynamic subtest bsd: SUCCESS (0.003s)
[455.415144] Starting dynamic subtest: bsd1
[455.417219] Dynamic subtest bsd1: SUCCESS (0.002s)
[455.426060] Starting dynamic subtest: bsd2
[455.428182] Dynamic subtest bsd2: SUCCESS (0.001s)
[455.437183] Starting dynamic subtest: blt
[455.439314] Dynamic subtest blt: SUCCESS (0.002s)
[455.448254] Starting dynamic subtest: vebox
[455.450453] Dynamic subtest vebox: SUCCESS (0.002s)
[455.452631] Subtest legacy-resubmit: SUCCESS (0.021s)
[455.630321] [087/133] (566s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[455.734172] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[455.738562] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[455.830256] [088/133] (566s left) gem_ctx_sseu (mmap-args)
[455.894672] Subtest mmap-args: SKIP (0.000s)
[455.970211] [089/133] (565s left) gen9_exec_parse (basic-rejected-ctx-param)
[456.029936] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[456.099277] [090/133] (565s left) kms_psr (fbc-psr-primary-blt)
[456.189818] Starting subtest: fbc-psr-primary-blt
[456.191571] Starting dynamic subtest: eDP-1
[456.194911] Dynamic subtest eDP-1: SKIP (0.000s)
[456.198244] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[456.266305] [091/133] (565s left) kms_vrr (seamless-rr-switch-vrr)
[456.367248] Starting subtest: seamless-rr-switch-vrr
[456.372820] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[456.441508] [092/133] (565s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[456.552027] Starting subtest: flip-vs-cursor-crc-legacy
[457.227091] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.673s)
[458.057171] [093/133] (564s left) kms_psr (psr-sprite-plane-move)
[458.154717] Starting subtest: psr-sprite-plane-move
[458.157068] Starting dynamic subtest: eDP-1
[459.894455] Dynamic subtest eDP-1: SUCCESS (1.739s)
[459.896783] Subtest psr-sprite-plane-move: SUCCESS (1.740s)
[460.730756] [094/133] (561s left) kms_content_protection (lic-type-0)
[460.867043] Starting subtest: lic-type-0
[460.871618] Subtest lic-type-0: SKIP (0.000s)
[460.976878] [095/133] (561s left) perf_pmu (rc6)
[461.021082] Starting subtest: rc6
[461.023155] Starting dynamic subtest: gt0
[467.378788] Dynamic subtest gt0: SUCCESS (6.360s)
[467.391822] Starting dynamic subtest: runtime-pm-gt0
[475.101427] Dynamic subtest runtime-pm-gt0: SUCCESS (7.722s)
[475.114301] Starting dynamic subtest: runtime-pm-long-gt0
[487.549558] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.448s)
[487.562556] Starting dynamic subtest: other-idle-gt0
[493.893774] Dynamic subtest other-idle-gt0: SUCCESS (6.340s)
[493.906404] Starting dynamic subtest: gt1
[500.237028] Dynamic subtest gt1: SUCCESS (6.339s)
[500.249401] Starting dynamic subtest: runtime-pm-gt1
[507.670873] Dynamic subtest runtime-pm-gt1: SUCCESS (7.429s)
[507.683984] Starting dynamic subtest: runtime-pm-long-gt1
[520.119124] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.443s)
[520.131217] Starting dynamic subtest: other-idle-gt1
[526.462244] Dynamic subtest other-idle-gt1: SUCCESS (6.340s)
[526.464855] Subtest rc6: SUCCESS (65.439s)
[527.563794] [096/133] (494s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[527.676946] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[527.680696] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[527.776256] [097/133] (494s left) kms_pm_rpm (i2c)
[529.338476] Starting subtest: i2c
[535.740828] Subtest i2c: SUCCESS (6.404s)
[535.841863] [098/133] (486s left) kms_getfb (getfb-handle-valid)
[535.933364] Starting subtest: getfb-handle-valid
[535.935545] Subtest getfb-handle-valid: SUCCESS (0.000s)
[536.004939] [099/133] (486s left) sw_sync (sync_random_merge)
[536.041014] Starting subtest: sync_random_merge
[536.083213] Subtest sync_random_merge: SUCCESS (0.043s)
[536.121571] [100/133] (486s left) kms_chamelium_audio (hdmi-audio-edid)
[536.502768] Subtest hdmi-audio-edid: SKIP (0.000s)
[537.317143] [101/133] (485s left) kms_color_pipeline (plane-ctm3x4)
[537.415603] Starting subtest: plane-ctm3x4
[537.538815] Starting dynamic subtest: pipe-A-plane-0
[537.838964] Dynamic subtest pipe-A-plane-0: FAIL (0.128s)
[537.849783] Starting dynamic subtest: pipe-A-plane-1
[538.059925] Dynamic subtest pipe-A-plane-1: FAIL (0.145s)
[538.071285] Starting dynamic subtest: pipe-A-plane-2
[538.308149] Dynamic subtest pipe-A-plane-2: FAIL (0.143s)
[539.129127] Starting dynamic subtest: pipe-B-plane-0
[539.498097] Dynamic subtest pipe-B-plane-0: FAIL (0.119s)
[539.508142] Starting dynamic subtest: pipe-B-plane-1
[539.805699] Dynamic subtest pipe-B-plane-1: FAIL (0.133s)
[539.820009] Starting dynamic subtest: pipe-B-plane-2
[540.123859] Dynamic subtest pipe-B-plane-2: FAIL (0.617s)
[541.186155] Starting dynamic subtest: pipe-C-plane-0
[541.661183] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[541.672000] Starting dynamic subtest: pipe-C-plane-1
[542.056629] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[542.066917] Starting dynamic subtest: pipe-C-plane-2
[542.407699] Dynamic subtest pipe-C-plane-2: FAIL (0.582s)
[543.216580] Starting dynamic subtest: pipe-D-plane-0
[543.774846] Dynamic subtest pipe-D-plane-0: FAIL (0.128s)
[543.785404] Starting dynamic subtest: pipe-D-plane-1
[544.227853] Dynamic subtest pipe-D-plane-1: FAIL (0.608s)
[544.239160] Starting dynamic subtest: pipe-D-plane-2
[544.652473] Dynamic subtest pipe-D-plane-2: FAIL (0.540s)
[544.782842] Subtest plane-ctm3x4: FAIL (7.369s)
[545.650893] [102/133] (476s left) gem_sync (basic-store-each)
[545.726083] Starting subtest: basic-store-each
[548.061240] Subtest basic-store-each: SUCCESS (2.346s)
[548.222812] [103/133] (474s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[548.324062] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[557.280762] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.958s)
[557.383946] [104/133] (465s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[557.492821] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[557.496878] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[557.569616] [105/133] (465s left) syncobj_wait (reset-during-wait-for-submit)
[557.620266] Starting subtest: reset-during-wait-for-submit
[557.721818] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[557.782382] [106/133] (464s left) kms_pm_lpsp (kms-lpsp)
[558.883990] Starting subtest: kms-lpsp
[558.886256] Starting dynamic subtest: pipe-A-eDP-1
[559.036904] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.154s)
[559.322442] Subtest kms-lpsp: SUCCESS (0.439s)
[560.138986] [107/133] (462s left) kms_flip (blocking-absolute-wf_vblank)
[560.234376] Starting subtest: blocking-absolute-wf_vblank
[560.236567] Starting dynamic subtest: A-eDP1
[569.020003] Dynamic subtest A-eDP1: SUCCESS (8.784s)
[569.030928] Starting dynamic subtest: B-eDP1
[577.751219] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[577.768443] Starting dynamic subtest: C-eDP1
[586.466690] Dynamic subtest C-eDP1: SUCCESS (8.713s)
[586.486405] Starting dynamic subtest: D-eDP1
[595.196324] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[595.201344] Subtest blocking-absolute-wf_vblank: SUCCESS (34.968s)
[596.393353] [108/133] (426s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[596.495538] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[596.499933] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[597.621374] [109/133] (425s left) gem_ctx_param (invalid-size-get)
[597.673278] Starting subtest: invalid-size-get
[597.675555] Subtest invalid-size-get: SUCCESS (0.001s)
[597.745878] [110/133] (425s left) gem_userptr_blits (vma-merge)
[597.810360] Starting subtest: vma-merge
[597.850346] Subtest vma-merge: SUCCESS (0.040s)
[597.947017] [111/133] (424s left) kms_pipe_crc_basic (nonblocking-crc)
[598.028227] Starting subtest: nonblocking-crc
[598.030102] Starting dynamic subtest: pipe-A-eDP-1
[598.778250] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.749s)
[598.788320] Starting dynamic subtest: pipe-B-eDP-1
[600.165722] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.387s)
[600.176632] Starting dynamic subtest: pipe-C-eDP-1
[601.564158] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.395s)
[601.574401] Starting dynamic subtest: pipe-D-eDP-1
[602.891427] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[602.893849] Subtest nonblocking-crc: SUCCESS (4.865s)
[603.670540] [112/133] (419s left) kms_psr (pr-basic)
[603.756053] Starting subtest: pr-basic
[603.760690] Subtest pr-basic: SKIP (0.000s)
[603.805625] [113/133] (419s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[603.898822] Starting subtest: fbc-psr2-primary-mmap-gtt
[603.900711] Starting dynamic subtest: eDP-1
[603.904755] Dynamic subtest eDP-1: SKIP (0.000s)
[603.908884] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[603.950774] [114/133] (419s left) kms_cursor_crc (cursor-suspend)
[604.072306] Starting subtest: cursor-suspend
[604.074575] Starting dynamic subtest: pipe-A-eDP-1
[636.879875] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.871s)
[636.890457] Starting dynamic subtest: pipe-D-eDP-1
[670.872372] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.796s)
[670.875436] Subtest cursor-suspend: SUCCESS (7.670s)
[671.680908] [115/133] (351s left) gem_exec_schedule (fifo)
[671.768230] Starting subtest: fifo
[671.770641] Starting dynamic subtest: rcs0
[671.866505] Dynamic subtest rcs0: SUCCESS (0.115s)
[671.880285] Starting dynamic subtest: vcs0
[671.952505] Dynamic subtest vcs0: SUCCESS (0.088s)
[671.964542] Starting dynamic subtest: vcs1
[672.034258] Dynamic subtest vcs1: SUCCESS (0.084s)
[672.046564] Starting dynamic subtest: vecs0
[672.111081] Dynamic subtest vecs0: SUCCESS (0.078s)
[672.123475] Starting dynamic subtest: ccs0
[672.194896] Dynamic subtest ccs0: SUCCESS (0.086s)
[672.197665] Subtest fifo: SUCCESS (0.457s)
[673.462038] [116/133] (349s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[673.560024] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[680.117348] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.695s)
[680.211997] [117/133] (342s left) kms_cursor_crc (cursor-offscreen-512x170)
[680.482005] Starting subtest: cursor-offscreen-512x170
[680.486506] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[680.591984] [118/133] (342s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[680.680871] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[681.051797] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[683.779718] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.752s)
[683.792256] Starting dynamic subtest: pipe-A-eDP-1-4
[685.966228] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.202s)
[685.978075] Starting dynamic subtest: pipe-A-eDP-1-4
[687.973794] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.017s)
[687.986643] Starting dynamic subtest: pipe-A-eDP-1-x
[690.163000] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.197s)
[690.534676] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[693.257964] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.739s)
[693.271554] Starting dynamic subtest: pipe-B-eDP-1-4
[695.448452] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.195s)
[695.459607] Starting dynamic subtest: pipe-B-eDP-1-4
[697.453466] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.007s)
[697.465918] Starting dynamic subtest: pipe-B-eDP-1-x
[699.639758] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[700.007691] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[702.771540] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.769s)
[702.785843] Starting dynamic subtest: pipe-C-eDP-1-4
[704.960252] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[704.973683] Starting dynamic subtest: pipe-C-eDP-1-4
[706.973015] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[706.990117] Starting dynamic subtest: pipe-C-eDP-1-x
[709.165439] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.190s)
[709.532001] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[712.309190] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.784s)
[712.322438] Starting dynamic subtest: pipe-D-eDP-1-4
[714.507510] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.196s)
[714.521910] Starting dynamic subtest: pipe-D-eDP-1-4
[716.518378] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[716.530088] Starting dynamic subtest: pipe-D-eDP-1-x
[718.698469] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[718.701593] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.094s)
[719.878515] [119/133] (303s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[719.979167] Starting subtest: 4-tiled-32bpp-rotate-180
[721.336290] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.357s)
[721.444983] [120/133] (301s left) gem_softpin (evict-snoop)
[721.500226] Starting subtest: evict-snoop
[721.505239] Subtest evict-snoop: SKIP (0.000s)
[721.588089] [121/133] (301s left) kms_atomic (test-only)
[721.682180] Starting subtest: test-only
[721.732647] Starting dynamic subtest: pipe-A-eDP-1
[724.222476] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.486s)
[724.224806] Subtest test-only: SUCCESS (2.538s)
[725.059567] [122/133] (298s left) gem_exec_schedule (semaphore-noskip)
[725.138368] Starting subtest: semaphore-noskip
[725.569690] Subtest semaphore-noskip: SUCCESS (0.440s)
[725.883285] [123/133] (297s left) gem_media_vme
[726.001519] [124/133] (297s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[726.100654] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[726.106131] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[726.152004] [125/133] (297s left) gem_ctx_bad_destroy (invalid-default-ctx)
[726.202197] Starting subtest: invalid-default-ctx
[726.204952] Subtest invalid-default-ctx: SUCCESS (0.000s)
[726.255784] [126/133] (297s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[726.309517] Starting subtest: single-wait-for-submit-unsubmitted
[726.415014] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[726.477453] [127/133] (297s left) gem_exec_balancer (bonded-chain)
[726.530601] Starting subtest: bonded-chain
[733.515417] Subtest bonded-chain: SUCCESS (6.983s)
[733.801339] [128/133] (289s left) api_intel_bb (object-reloc-keep-cache)
[733.859967] Starting subtest: object-reloc-keep-cache
[733.865270] Subtest object-reloc-keep-cache: SKIP (0.005s)
[733.962177] [129/133] (289s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[734.067578] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[734.072409] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[734.142584] [130/133] (289s left) gen9_exec_parse (valid-registers)
[734.207920] Subtest valid-registers: SKIP (0.000s)
[734.284887] [131/133] (289s left) kms_flip (2x-flip-vs-panning)
[734.375720] Starting subtest: 2x-flip-vs-panning
[734.384092] Subtest 2x-flip-vs-panning: SKIP (0.010s)
[734.445554] [132/133] (289s left) vgem_basic (bad-pad)
[734.492665] Starting subtest: bad-pad
[734.495392] Subtest bad-pad: SUCCESS (0.000s)
[734.538267] [133/133] (289s left) gem_exec_flush (basic-uc-ro-default)
[734.603250] Starting subtest: basic-uc-ro-default
[740.064087] Subtest basic-uc-ro-default: SUCCESS (5.451s)
[740.220162] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: core_setmaster
results: parsing output: 3/ for test: prime_busy
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: gem_lmem_swapping
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: syncobj_basic
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: gem_exec_schedule
results: parsing output: 11/ for test: kms_rotation_crc
results: parsing output: 12/ for test: syncobj_basic
results: parsing output: 13/ for test: gem_softpin
results: parsing output: 14/ for test: gem_exec_reloc
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_eio
results: parsing output: 19/ for test: gem_exec_reloc
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: gem_readwrite
results: parsing output: 22/ for test: gem_pxp
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: perf
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: kms_plane
results: parsing output: 28/ for test: kms_vblank
results: parsing output: 29/ for test: gem_exec_store
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: gem_exec_params
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_cursor_crc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: api_intel_allocator
results: parsing output: 38/ for test: gem_reg_read
results: parsing output: 39/ for test: kms_chamelium_hpd
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: i915_getparams_basic
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_flip_scaled_crc
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_rotation_crc
results: parsing output: 46/ for test: kms_psr2_sf
results: parsing output: 47/ for test: i915_pm_rpm
results: parsing output: 48/ for test: gem_exec_nop
results: parsing output: 49/ for test: gem_create
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: kms_atomic_interruptible
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: prime_self_import
results: parsing output: 57/ for test: fbdev
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_sequence
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: gem_ctx_persistence
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_prop_blob
results: parsing output: 67/ for test: gem_mmap_gtt
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: kms_feature_discovery
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: kms_plane
results: parsing output: 76/ for test: gem_render_copy
results: parsing output: 77/ for test: kms_invalid_mode
results: parsing output: 78/ for test: kms_lease
results: parsing output: 79/ for test: i915_pm_rps
results: parsing output: 80/ for test: kms_chamelium_frames
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_plane_scaling
results: parsing output: 83/ for test: gem_mmap_offset
results: parsing output: 84/ for test: testdisplay
results: parsing output: 85/ for test: gem_spin_batch
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_ctx_sseu
results: parsing output: 88/ for test: gen9_exec_parse
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: kms_vrr
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_content_protection
results: parsing output: 94/ for test: perf_pmu
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_pm_rpm
results: parsing output: 97/ for test: kms_getfb
results: parsing output: 98/ for test: sw_sync
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_color_pipeline
results: parsing output: 101/ for test: gem_sync
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: syncobj_wait
results: parsing output: 105/ for test: kms_pm_lpsp
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: gem_ctx_param
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: kms_pipe_crc_basic
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: kms_cursor_crc
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_cursor_crc
results: parsing output: 117/ for test: kms_async_flips
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: gem_softpin
results: parsing output: 120/ for test: kms_atomic
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: gem_media_vme
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: gem_ctx_bad_destroy
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: api_intel_bb
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gen9_exec_parse
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: vgem_basic
results: parsing output: 132/ for test: gem_exec_flush
Done.