[59.530059] Initializing watchdogs
[59.530111]   /dev/watchdog0
[59.544633] [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]
[59.549441] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.551881] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.555203] [001/133] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[59.686645] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[59.691046] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[59.805551] [002/133] (960s left) gem_reg_read (timestamp-monotonic)
[59.872002] Starting subtest: timestamp-monotonic
[64.578048] Subtest timestamp-monotonic: SUCCESS (4.691s)
[64.664279] [003/133] (955s left) i915_drm_fdinfo (busy-check-all)
[64.707310] Starting subtest: busy-check-all
[64.709639] Starting dynamic subtest: rcs0
[64.716632] Dynamic subtest rcs0: SKIP (0.005s)
[64.724992] Starting dynamic subtest: bcs0
[64.728932] Dynamic subtest bcs0: SKIP (0.001s)
[64.735666] Starting dynamic subtest: vcs0
[64.740068] Dynamic subtest vcs0: SKIP (0.001s)
[64.746654] Starting dynamic subtest: vcs1
[64.750647] Dynamic subtest vcs1: SKIP (0.000s)
[64.757477] Starting dynamic subtest: vecs0
[64.761937] Dynamic subtest vecs0: SKIP (0.000s)
[64.768650] Starting dynamic subtest: ccs0
[64.772768] Dynamic subtest ccs0: SKIP (0.000s)
[64.777113] Subtest busy-check-all: SKIP (0.009s)
[64.810924] [004/133] (955s left) kms_pwrite_crc
[66.507779] [005/133] (953s left) syncobj_timeline (reset-unsignaled)
[66.570137] Starting subtest: reset-unsignaled
[66.572481] Subtest reset-unsignaled: SUCCESS (0.000s)
[66.665970] [006/133] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[66.824570] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[66.829038] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[66.967747] [007/133] (953s left) api_intel_allocator (execbuf-with-allocator)
[67.213375] Starting subtest: execbuf-with-allocator
[67.220831] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[67.391520] [008/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[67.631069] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[74.698843] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.066s)
[74.846299] [009/133] (945s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[74.952858] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[79.724343] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.774s)
[80.862733] [010/133] (939s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[80.981700] Starting subtest: y-tiled-32bpp-rotate-180
[80.986024] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[81.063943] [011/133] (939s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[81.160167] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[81.164575] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[81.242691] [012/133] (939s left) gem_exec_suspend (basic-s3)
[81.308328] Starting subtest: basic-S3
[81.310765] Starting dynamic subtest: smem
[101.183720] Dynamic subtest smem: SUCCESS (7.506s)
[101.186696] Subtest basic-S3: SUCCESS (7.507s)
[101.364658] [013/133] (919s left) gem_exec_balancer (invalid-balancer)
[101.450196] Starting subtest: invalid-balancer
[102.462980] Subtest invalid-balancer: SUCCESS (0.993s)
[102.776143] [014/133] (917s left) kms_pm_lpsp (screens-disabled)
[103.881680] Starting subtest: screens-disabled
[103.885763] Subtest screens-disabled: SKIP (0.000s)
[103.950650] [015/133] (916s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[104.051435] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[108.440960] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.348s)
[109.633037] [016/133] (911s left) gem_wait (await)
[109.692716] Starting subtest: await
[109.695092] Starting dynamic subtest: all-engines
[110.861287] Dynamic subtest all-engines: SUCCESS (1.161s)
[110.877714] Starting dynamic subtest: rcs0
[111.916614] Dynamic subtest rcs0: SUCCESS (1.048s)
[111.932253] Starting dynamic subtest: bcs0
[112.972827] Dynamic subtest bcs0: SUCCESS (1.051s)
[112.990024] Starting dynamic subtest: vcs0
[114.028807] Dynamic subtest vcs0: SUCCESS (1.051s)
[114.045816] Starting dynamic subtest: vcs1
[115.081217] Dynamic subtest vcs1: SUCCESS (1.047s)
[115.097946] Starting dynamic subtest: vecs0
[116.137381] Dynamic subtest vecs0: SUCCESS (1.051s)
[116.151595] Starting dynamic subtest: ccs0
[117.197483] Dynamic subtest ccs0: SUCCESS (1.056s)
[117.199978] Subtest await: SUCCESS (7.472s)
[117.392959] [FACT gem_wait (await)] new: kernel.kmod_is_loaded.vgem: true
[117.396488] [017/133] (903s left) gem_exercise_blt (fast-copy-emit)
[117.453283] Starting subtest: fast-copy-emit
[117.455774] Starting dynamic subtest: linear-smem-smem-emit
[117.483841] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.030s)
[117.495909] Starting dynamic subtest: xmajor-smem-smem-emit
[117.503544] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.019s)
[117.512865] Starting dynamic subtest: tile4-smem-smem-emit
[117.522732] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.018s)
[117.530224] Starting dynamic subtest: tile64-smem-smem-emit
[117.541399] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.018s)
[117.543798] Subtest fast-copy-emit: SUCCESS (0.087s)
[117.658705] [018/133] (903s left) kms_big_fb (linear-16bpp-rotate-270)
[117.769359] Starting subtest: linear-16bpp-rotate-270
[117.790203] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[117.914765] [019/133] (902s left) gem_exec_balancer (parallel-keep-submit-fence)
[118.169906] Starting subtest: parallel-keep-submit-fence
[119.247968] Subtest parallel-keep-submit-fence: SUCCESS (1.075s)
[119.356057] [020/133] (901s left) gem_ctx_shared (single-timeline)
[119.425606] Starting subtest: single-timeline
[119.447169] Subtest single-timeline: SUCCESS (0.013s)
[119.686788] [021/133] (901s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[119.805308] Starting subtest: extended-modeset-hang-newfb-with-reset
[119.807311] Starting dynamic subtest: pipe-A
[135.663407] Dynamic subtest pipe-A: SUCCESS (15.845s)
[135.676959] Starting dynamic subtest: pipe-D
[151.243921] Dynamic subtest pipe-D: SUCCESS (15.576s)
[151.246271] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (31.425s)
[152.094783] [022/133] (868s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[152.204326] Starting subtest: x-tiled-32bpp-rotate-270
[152.223697] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[152.332999] [023/133] (868s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[152.438974] Starting subtest: primary-y-tiled-reflect-x-180
[152.513478] Subtest primary-y-tiled-reflect-x-180: SKIP (0.072s)
[152.653099] [024/133] (868s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[152.744824] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[152.747175] Starting dynamic subtest: pipe-A-eDP-1
[153.494461] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.740s)
[153.504768] Starting dynamic subtest: pipe-B-eDP-1
[155.512209] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.016s)
[155.520945] Starting dynamic subtest: pipe-C-eDP-1
[157.335237] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.822s)
[157.345911] Starting dynamic subtest: pipe-D-eDP-1
[159.164165] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.827s)
[159.166314] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.409s)
[160.307016] [025/133] (860s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[160.375334] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[160.379962] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[160.556937] [026/133] (860s left) gem_exec_schedule (u-fairslice-all)
[160.634828] Starting subtest: u-fairslice-all
[162.685325] Subtest u-fairslice-all: SUCCESS (2.055s)
[162.979653] [027/133] (858s left) kms_prime (basic-crc-vgem)
[163.071332] Starting subtest: basic-crc-vgem
[163.073572] Starting dynamic subtest: second-to-first
[164.016792] Dynamic subtest second-to-first: SUCCESS (0.949s)
[164.018915] Subtest basic-crc-vgem: SUCCESS (0.950s)
[164.145869] [028/133] (857s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[164.268579] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[164.272301] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[164.411610] [029/133] (856s left) gem_mmap_gtt (basic-small-copy)
[164.471110] Subtest basic-small-copy: SKIP (0.000s)
[164.533204] [030/133] (856s left) kms_content_protection (atomic-dpms)
[164.682510] Starting subtest: atomic-dpms
[164.686266] Subtest atomic-dpms: SKIP (0.000s)
[164.807643] [031/133] (856s left) prime_vgem (fence-wait)
[164.877000] Starting subtest: fence-wait
[164.879004] Starting dynamic subtest: rcs0
[165.914742] Dynamic subtest rcs0: SUCCESS (1.036s)
[165.926184] Starting dynamic subtest: bcs0
[166.957818] Dynamic subtest bcs0: SUCCESS (1.041s)
[166.969493] Starting dynamic subtest: vcs0
[168.001929] Dynamic subtest vcs0: SUCCESS (1.042s)
[168.014009] Starting dynamic subtest: vcs1
[169.043140] Dynamic subtest vcs1: SUCCESS (1.040s)
[169.054808] Starting dynamic subtest: vecs0
[170.087820] Dynamic subtest vecs0: SUCCESS (1.042s)
[170.100752] Starting dynamic subtest: ccs0
[171.130930] Dynamic subtest ccs0: SUCCESS (1.041s)
[171.133769] Subtest fence-wait: SUCCESS (6.251s)
[171.255784] [032/133] (850s left) i915_pm_rps (thresholds-idle)
[171.318238] Starting subtest: thresholds-idle
[171.322961] Subtest thresholds-idle: SKIP (0.002s)
[171.421160] [033/133] (850s left) kms_pipe_crc_basic (bad-source)
[171.526872] Starting subtest: bad-source
[171.528999] Subtest bad-source: SUCCESS (0.000s)
[171.635327] [034/133] (849s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[171.705143] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[171.773147] [035/133] (849s left) syncobj_wait (invalid-reset-illegal-handle)
[171.827660] Starting subtest: invalid-reset-illegal-handle
[171.829982] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[171.923094] [036/133] (849s left) gem_pxp (create-regular-context-1)
[172.035429] Starting subtest: create-regular-context-1
[172.037793] Subtest create-regular-context-1: SUCCESS (0.002s)
[172.153839] [037/133] (849s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[172.251930] Starting subtest: fbc-psr2-cursor-plane-update-sf
[172.253819] Starting dynamic subtest: pipe-A-eDP-1
[172.330045] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[172.338908] Starting dynamic subtest: pipe-B-eDP-1
[173.493554] Dynamic subtest pipe-B-eDP-1: SKIP (1.160s)
[173.497742] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.241s)
[174.676740] [038/133] (847s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[174.779679] Starting subtest: fbc-pr-cursor-mmap-gtt
[174.783623] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[174.853690] [039/133] (846s left) dmabuf_sync_file (export-before-signal)
[174.892891] Starting subtest: export-before-signal
[174.895118] Subtest export-before-signal: SUCCESS (0.001s)
[174.949649] [040/133] (846s left) kms_psr (psr2-sprite-mmap-cpu)
[175.037527] Starting subtest: psr2-sprite-mmap-cpu
[175.039767] Starting dynamic subtest: eDP-1
[176.778929] Dynamic subtest eDP-1: SUCCESS (1.740s)
[176.781313] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.740s)
[177.648961] [041/133] (844s left) gem_exec_parallel (userptr)
[177.717763] Starting subtest: userptr
[177.720375] Starting dynamic subtest: rcs0
[179.175894] Dynamic subtest rcs0: SUCCESS (1.457s)
[179.187234] Starting dynamic subtest: bcs0
[180.642503] Dynamic subtest bcs0: SUCCESS (1.465s)
[180.653968] Starting dynamic subtest: vcs0
[182.230140] Dynamic subtest vcs0: SUCCESS (1.585s)
[182.239296] Starting dynamic subtest: vcs1
[184.255090] Dynamic subtest vcs1: SUCCESS (2.022s)
[184.267078] Starting dynamic subtest: vecs0
[186.238540] Dynamic subtest vecs0: SUCCESS (1.980s)
[186.248830] Starting dynamic subtest: ccs0
[187.728730] Dynamic subtest ccs0: SUCCESS (1.489s)
[187.731263] Subtest userptr: SUCCESS (10.007s)
[187.917916] [042/133] (833s left) gem_pxp (reject-modify-context-protection-off-3)
[187.972519] Starting subtest: reject-modify-context-protection-off-3
[187.974870] Subtest reject-modify-context-protection-off-3: SUCCESS (0.001s)
[188.067466] [043/133] (833s left) gem_shrink (reclaim)
[188.123051] Starting subtest: reclaim
[190.570102] Subtest reclaim: SUCCESS (2.439s)
[190.707983] [044/133] (831s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[190.816451] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[197.558084] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.744s)
[197.695068] [045/133] (824s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[197.796141] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[197.798475] Starting dynamic subtest: pipe-A-default-mode
[198.946078] Dynamic subtest pipe-A-default-mode: SKIP (1.148s)
[198.950638] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.150s)
[199.084521] [046/133] (822s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[199.147730] Starting subtest: single-wait-for-submit-available-signaled
[199.149972] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[199.261320] [047/133] (822s left) gem_ctx_param (get-priority-new-ctx)
[199.313572] Starting subtest: get-priority-new-ctx
[199.315949] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[199.413785] [048/133] (822s left) i915_pm_rpm (system-suspend)
[201.334804] Starting subtest: system-suspend
[218.928514] Subtest system-suspend: SUCCESS (2.843s)
[219.314099] [049/133] (802s left) kms_lease (implicit-plane-lease)
[219.417590] Starting subtest: implicit-plane-lease
[219.420252] Subtest implicit-plane-lease: SUCCESS (0.002s)
[219.526967] [050/133] (802s left) gem_exec_schedule (implicit-read-write)
[219.591924] Starting subtest: implicit-read-write
[219.593872] Starting dynamic subtest: rcs0
[219.704540] Dynamic subtest rcs0: SUCCESS (0.121s)
[219.716148] Starting dynamic subtest: vcs0
[219.779858] Dynamic subtest vcs0: SUCCESS (0.075s)
[219.791731] Starting dynamic subtest: vcs1
[219.853869] Dynamic subtest vcs1: SUCCESS (0.073s)
[219.865157] Starting dynamic subtest: vecs0
[219.932766] Dynamic subtest vecs0: SUCCESS (0.078s)
[219.944470] Starting dynamic subtest: ccs0
[220.020782] Dynamic subtest ccs0: SUCCESS (0.087s)
[220.023313] Subtest implicit-read-write: SUCCESS (0.438s)
[221.110773] [051/133] (801s left) perf_pmu (frequency)
[221.154922] Starting subtest: frequency
[221.159929] Starting dynamic subtest: gt0
[223.284526] Dynamic subtest gt0: SUCCESS (2.123s)
[223.295973] Starting dynamic subtest: idle-gt0
[223.821198] Dynamic subtest idle-gt0: SUCCESS (0.536s)
[223.833759] Starting dynamic subtest: gt1
[227.995604] Dynamic subtest gt1: SUCCESS (4.103s)
[228.007611] Starting dynamic subtest: idle-gt1
[228.507073] Dynamic subtest idle-gt1: SUCCESS (0.504s)
[228.509643] Subtest frequency: SUCCESS (7.279s)
[228.628167] [052/133] (793s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[228.743490] Starting subtest: fbcpsr-modesetfrombusy
[235.018733] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.218s)
[235.162630] [053/133] (787s left) gem_userptr_blits (stress-mm-invalidate-close)
[235.226054] Starting subtest: stress-mm-invalidate-close
[237.386015] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[237.538779] [054/133] (784s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[237.621582] Starting subtest: crc-primary-suspend-y-tiled-ccs
[237.624005] Starting dynamic subtest: pipe-A-eDP-1
[237.633299] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[237.648372] Starting dynamic subtest: pipe-B-eDP-1
[237.657406] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[237.666528] Starting dynamic subtest: pipe-C-eDP-1
[237.674780] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[237.684157] Starting dynamic subtest: pipe-D-eDP-1
[237.692851] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[237.697010] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s)
[239.814350] [055/133] (782s left) sw_sync (sync_multi_consumer)
[239.849225] Starting subtest: sync_multi_consumer
[240.281894] Subtest sync_multi_consumer: SUCCESS (0.433s)
[240.355032] [056/133] (782s left) kms_vblank (query-forked-busy-hang)
[240.453477] Starting subtest: query-forked-busy-hang
[240.455367] Starting dynamic subtest: pipe-A-eDP-1
[266.783692] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.302s)
[266.798851] Starting dynamic subtest: pipe-D-eDP-1
[291.810771] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.018s)
[291.812702] Subtest query-forked-busy-hang: SUCCESS (51.325s)
[292.685199] [057/133] (729s left) kms_plane (plane-panning-bottom-right-suspend)
[292.776312] Starting subtest: plane-panning-bottom-right-suspend
[292.778665] Starting dynamic subtest: pipe-A
[310.700853] Dynamic subtest pipe-A: SUCCESS (2.957s)
[310.712037] Starting dynamic subtest: pipe-B
[329.518190] Dynamic subtest pipe-B: SUCCESS (3.431s)
[329.521163] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.391s)
[330.353119] [058/133] (692s left) gem_mmap_offset (bad-extensions)
[330.427087] Starting subtest: bad-extensions
[330.430110] Subtest bad-extensions: SUCCESS (0.000s)
[330.527831] [059/133] (692s left) kms_psr (pr-basic)
[330.628057] Starting subtest: pr-basic
[330.633011] Subtest pr-basic: SKIP (0.000s)
[330.712488] [060/133] (691s left) gem_userptr_blits (relocations)
[330.776025] Starting subtest: relocations
[330.781084] Subtest relocations: SKIP (0.001s)
[330.894162] [061/133] (691s left) kms_cursor_legacy (single-move)
[331.016016] Starting subtest: single-move
[331.018907] Starting dynamic subtest: pipe-A
[336.477232] Dynamic subtest pipe-A: SUCCESS (5.381s)
[336.489761] Starting dynamic subtest: pipe-B
[341.897759] Dynamic subtest pipe-B: SUCCESS (5.379s)
[341.909810] Starting dynamic subtest: pipe-C
[347.299252] Dynamic subtest pipe-C: SUCCESS (5.379s)
[347.311015] Starting dynamic subtest: pipe-D
[352.692078] Dynamic subtest pipe-D: SUCCESS (5.379s)
[352.704031] Starting dynamic subtest: all-pipes
[358.087275] Dynamic subtest all-pipes: SUCCESS (5.386s)
[358.090146] Subtest single-move: SUCCESS (26.919s)
[358.246053] [062/133] (664s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[358.363975] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[359.044233] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.681s)
[359.208381] [063/133] (663s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[359.306716] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[359.311725] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[359.429959] [064/133] (663s left) gem_exec_reloc (basic-gtt-read-active)
[359.488254] Subtest basic-gtt-read-active: SKIP (0.000s)
[359.563318] [065/133] (663s left) kms_chamelium_frames (hdmi-crc-single)
[359.941865] Subtest hdmi-crc-single: SKIP (0.000s)
[360.740602] [066/133] (662s left) gem_userptr_blits (process-exit)
[360.810743] Starting subtest: process-exit
[360.817982] Subtest process-exit: SUCCESS (0.010s)
[360.938008] [067/133] (661s left) kms_psr (psr-primary-mmap-cpu)
[361.030128] Starting subtest: psr-primary-mmap-cpu
[361.032948] Starting dynamic subtest: eDP-1
[362.748958] Dynamic subtest eDP-1: SUCCESS (1.721s)
[362.751809] Subtest psr-primary-mmap-cpu: SUCCESS (1.722s)
[363.612887] [068/133] (659s left) gem_ctx_persistence (legacy-engines-cleanup)
[363.678533] Starting subtest: legacy-engines-cleanup
[363.680495] Starting dynamic subtest: render
[363.746220] Dynamic subtest render: SUCCESS (0.069s)
[363.759377] Starting dynamic subtest: bsd1
[363.786200] Dynamic subtest bsd1: SUCCESS (0.038s)
[363.796221] Starting dynamic subtest: bsd2
[363.826201] Dynamic subtest bsd2: SUCCESS (0.038s)
[363.836159] Starting dynamic subtest: blt
[363.871162] Dynamic subtest blt: SUCCESS (0.043s)
[363.882028] Starting dynamic subtest: vebox
[363.912254] Dynamic subtest vebox: SUCCESS (0.039s)
[363.914959] Subtest legacy-engines-cleanup: SUCCESS (0.237s)
[363.990797] [069/133] (659s left) kms_flip (blocking-absolute-wf_vblank)
[364.096011] Starting subtest: blocking-absolute-wf_vblank
[364.101769] Starting dynamic subtest: A-eDP1
[372.870039] Dynamic subtest A-eDP1: SUCCESS (8.771s)
[372.888526] Starting dynamic subtest: B-eDP1
[381.603124] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[381.623168] Starting dynamic subtest: C-eDP1
[390.324620] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[390.334766] Starting dynamic subtest: D-eDP1
[399.058969] Dynamic subtest D-eDP1: SUCCESS (8.730s)
[399.065144] Subtest blocking-absolute-wf_vblank: SUCCESS (34.966s)
[400.295779] [070/133] (622s left) kms_cursor_crc (cursor-alpha-transparent)
[400.393905] Starting subtest: cursor-alpha-transparent
[400.396271] Starting dynamic subtest: pipe-A-eDP-1
[401.033765] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.641s)
[401.043727] Starting dynamic subtest: pipe-D-eDP-1
[402.356060] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.318s)
[402.358716] Subtest cursor-alpha-transparent: SUCCESS (1.962s)
[403.229849] [071/133] (619s left) kms_color (invalid-degamma-lut-sizes)
[403.317009] Starting subtest: invalid-degamma-lut-sizes
[403.319502] Starting dynamic subtest: pipe-A
[403.622262] Dynamic subtest pipe-A: SUCCESS (0.306s)
[403.634388] Starting dynamic subtest: pipe-B
[403.636936] Dynamic subtest pipe-B: SUCCESS (0.000s)
[403.648383] Starting dynamic subtest: pipe-C
[403.651028] Dynamic subtest pipe-C: SUCCESS (0.000s)
[403.660541] Starting dynamic subtest: pipe-D
[403.663242] Dynamic subtest pipe-D: SUCCESS (0.000s)
[403.665764] Subtest invalid-degamma-lut-sizes: SUCCESS (0.309s)
[404.486749] [072/133] (618s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[404.606093] Starting subtest: 2x-flip-vs-cursor-legacy
[404.612920] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[404.750230] [073/133] (618s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[404.847494] Starting subtest: psr-1p-primscrn-cur-indfb-move
[412.261418] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.414s)
[412.399621] [074/133] (610s left) syncobj_wait (wait-any-interrupted)
[412.458071] Starting subtest: wait-any-interrupted
[412.562575] Subtest wait-any-interrupted: SUCCESS (0.104s)
[412.655783] [075/133] (610s left) gem_ctx_param (set-priority-invalid-size)
[412.711375] Starting subtest: set-priority-invalid-size
[412.714227] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[412.786878] [076/133] (610s left) perf_pmu (busy-double-start)
[412.829742] Starting subtest: busy-double-start
[412.832427] Starting dynamic subtest: rcs0
[416.470940] Dynamic subtest rcs0: FAIL (3.580s)
[416.480228] Starting dynamic subtest: bcs0
[419.977747] Dynamic subtest bcs0: SUCCESS (3.567s)
[419.989745] Starting dynamic subtest: vcs0
[423.800138] Dynamic subtest vcs0: SUCCESS (3.819s)
[423.813243] Starting dynamic subtest: vcs1
[427.811264] Dynamic subtest vcs1: SUCCESS (4.010s)
[427.824239] Starting dynamic subtest: vecs0
[431.778107] Dynamic subtest vecs0: SUCCESS (3.966s)
[431.789813] Starting dynamic subtest: ccs0
[435.417753] Dynamic subtest ccs0: FAIL (3.567s)
[435.419956] Subtest busy-double-start: FAIL (22.518s)
[435.464106] [077/133] (587s left) gem_ctx_persistence (engines-queued)
[435.526767] Starting subtest: engines-queued
[435.529414] Starting dynamic subtest: rcs0
[435.603218] Dynamic subtest rcs0: SUCCESS (0.076s)
[435.615116] Starting dynamic subtest: bcs0
[435.631733] Dynamic subtest bcs0: SUCCESS (0.028s)
[435.640947] Starting dynamic subtest: vcs0
[437.253256] Dynamic subtest vcs0: SUCCESS (1.619s)
[437.264914] Starting dynamic subtest: vcs1
[439.236706] Dynamic subtest vcs1: SUCCESS (1.981s)
[439.249465] Starting dynamic subtest: vecs0
[441.219568] Dynamic subtest vecs0: SUCCESS (1.980s)
[441.232265] Starting dynamic subtest: ccs0
[441.387271] Dynamic subtest ccs0: SUCCESS (0.166s)
[441.390088] Subtest engines-queued: SUCCESS (5.862s)
[441.499344] [078/133] (581s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[441.596969] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[448.553227] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.959s)
[448.681818] [079/133] (574s left) kms_hdr (static-toggle-dpms)
[448.778235] Starting subtest: static-toggle-dpms
[448.786038] Subtest static-toggle-dpms: SKIP (0.000s)
[448.851512] [080/133] (574s left) gem_mmap (short-mmap)
[448.919567] Subtest short-mmap: SKIP (0.000s)
[448.984857] [081/133] (574s left) syncobj_wait (invalid-signal-one-illegal-handle)
[449.041034] Starting subtest: invalid-signal-one-illegal-handle
[449.043471] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[449.136646] [082/133] (574s left) kms_cursor_crc (cursor-sliding-32x10)
[449.298671] Starting subtest: cursor-sliding-32x10
[449.351552] Subtest cursor-sliding-32x10: SKIP (0.043s)
[449.610360] [083/133] (574s left) gem_bad_reloc (negative-reloc)
[449.678037] Subtest negative-reloc: SKIP (0.000s)
[449.748306] [084/133] (573s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[449.841925] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[449.846062] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[449.940384] [085/133] (573s left) gem_pipe_control_store_loop (fresh-buffer)
[449.991670] Starting subtest: fresh-buffer
[452.145692] Subtest fresh-buffer: SUCCESS (2.152s)
[452.255772] [086/133] (571s left) i915_pm_freq_api (freq-basic-api)
[452.317819] Starting subtest: freq-basic-api
[452.320398] Starting dynamic subtest: gt0
[452.323158] Dynamic subtest gt0: SUCCESS (0.004s)
[452.336249] Starting dynamic subtest: gt1
[452.338925] Dynamic subtest gt1: SUCCESS (0.004s)
[452.341236] Subtest freq-basic-api: SUCCESS (0.010s)
[452.408588] [087/133] (571s left) gem_request_retire (retire-vma-not-inactive)
[452.474905] Starting subtest: retire-vma-not-inactive
[452.538927] Subtest retire-vma-not-inactive: SUCCESS (0.063s)
[452.669639] [088/133] (571s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[452.763211] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[452.765719] Starting dynamic subtest: pipe-A-eDP-1
[452.787178] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[452.798274] Starting dynamic subtest: pipe-B-eDP-1
[452.820609] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[452.829508] Starting dynamic subtest: pipe-C-eDP-1
[452.851943] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[452.860975] Starting dynamic subtest: pipe-D-eDP-1
[452.883899] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[452.886037] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[454.902760] [089/133] (568s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[455.005917] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[455.011058] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[455.108052] [090/133] (568s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[455.209757] Starting subtest: fbc-psr-cursor-mmap-cpu
[455.212289] Starting dynamic subtest: eDP-1
[455.218031] Dynamic subtest eDP-1: SKIP (0.000s)
[455.223218] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.002s)
[455.289658] [091/133] (568s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[455.355511] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[455.457791] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[455.541745] [092/133] (568s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[455.659017] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[455.664086] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[455.758701] [093/133] (568s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[455.859147] Starting subtest: 4-tiled-8bpp-rotate-90
[455.881888] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.022s)
[455.998600] [094/133] (568s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[456.089825] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[456.092151] Starting dynamic subtest: pipe-A-eDP-1
[456.121779] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[456.132299] Starting dynamic subtest: pipe-B-eDP-1
[456.158223] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[456.167563] Starting dynamic subtest: pipe-C-eDP-1
[456.193238] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[456.202719] Starting dynamic subtest: pipe-D-eDP-1
[456.229424] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[456.234339] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[458.370758] [095/133] (565s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[458.484540] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[458.490061] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[458.591769] [096/133] (565s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[458.979664] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[459.778735] [097/133] (564s left) kms_feature_discovery (dp-mst)
[459.883582] Starting subtest: dp-mst
[459.888821] Subtest dp-mst: SKIP (0.000s)
[459.952669] [098/133] (564s left) kms_flip (bo-too-big)
[460.041114] Starting subtest: bo-too-big
[460.043847] Starting dynamic subtest: A-eDP1
[461.345804] Dynamic subtest A-eDP1: SUCCESS (1.304s)
[461.356883] Starting dynamic subtest: B-eDP1
[462.666078] Dynamic subtest B-eDP1: SUCCESS (1.319s)
[462.678220] Starting dynamic subtest: C-eDP1
[464.012268] Dynamic subtest C-eDP1: SUCCESS (1.344s)
[464.022223] Starting dynamic subtest: D-eDP1
[465.347586] Dynamic subtest D-eDP1: SUCCESS (1.332s)
[465.350389] Subtest bo-too-big: SUCCESS (5.310s)
[466.550796] [099/133] (557s left) sysfs_defaults (readonly)
[466.607910] Starting subtest: readonly
[466.610399] Starting dynamic subtest: vecs0
[466.612903] Dynamic subtest vecs0: SUCCESS (0.000s)
[466.622773] Starting dynamic subtest: ccs0
[466.625103] Dynamic subtest ccs0: SUCCESS (0.000s)
[466.634801] Starting dynamic subtest: vcs0
[466.636953] Dynamic subtest vcs0: SUCCESS (0.000s)
[466.646711] Starting dynamic subtest: bcs0
[466.649169] Dynamic subtest bcs0: SUCCESS (0.000s)
[466.658729] Starting dynamic subtest: rcs0
[466.661171] Dynamic subtest rcs0: SUCCESS (0.000s)
[466.671064] Starting dynamic subtest: vcs1
[466.673877] Dynamic subtest vcs1: SUCCESS (0.000s)
[466.676326] Subtest readonly: SUCCESS (0.006s)
[466.718563] [100/133] (557s left) gem_spin_batch (resubmit-all)
[466.780105] Starting subtest: resubmit-all
[466.782878] Starting dynamic subtest: rcs0
[466.793259] Dynamic subtest rcs0: SUCCESS (0.013s)
[466.805286] Starting dynamic subtest: bcs0
[466.808078] Dynamic subtest bcs0: SUCCESS (0.002s)
[466.817941] Starting dynamic subtest: vcs0
[466.820264] Dynamic subtest vcs0: SUCCESS (0.002s)
[466.830086] Starting dynamic subtest: vcs1
[466.832793] Dynamic subtest vcs1: SUCCESS (0.002s)
[466.842392] Starting dynamic subtest: vecs0
[466.844999] Dynamic subtest vecs0: SUCCESS (0.002s)
[466.854739] Starting dynamic subtest: ccs0
[466.857205] Dynamic subtest ccs0: SUCCESS (0.003s)
[466.859989] Subtest resubmit-all: SUCCESS (0.029s)
[468.302614] [101/133] (556s left) perf_pmu (busy-idle-check-all)
[468.349148] Starting subtest: busy-idle-check-all
[468.351977] Starting dynamic subtest: rcs0
[469.001122] Dynamic subtest rcs0: SUCCESS (0.654s)
[469.012571] Starting dynamic subtest: bcs0
[469.659224] Dynamic subtest bcs0: SUCCESS (0.655s)
[469.673395] Starting dynamic subtest: vcs0
[471.270227] Dynamic subtest vcs0: SUCCESS (1.607s)
[471.283375] Starting dynamic subtest: vcs1
[473.253857] Dynamic subtest vcs1: SUCCESS (1.980s)
[473.267594] Starting dynamic subtest: vecs0
[475.306216] Dynamic subtest vecs0: SUCCESS (2.048s)
[475.324352] Starting dynamic subtest: ccs0
[475.964017] Dynamic subtest ccs0: SUCCESS (0.656s)
[475.967292] Subtest busy-idle-check-all: SUCCESS (7.618s)
[476.070095] [102/133] (548s left) kms_vblank (accuracy-idle)
[476.162372] Starting subtest: accuracy-idle
[476.164832] Starting dynamic subtest: pipe-A-eDP-1
[477.916716] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.753s)
[477.931527] Starting dynamic subtest: pipe-D-eDP-1
[480.393627] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.476s)
[480.396256] Subtest accuracy-idle: SUCCESS (4.233s)
[481.217222] [103/133] (543s left) perf (per-context-mode-unprivileged)
[481.330277] Starting subtest: per-context-mode-unprivileged
[481.335115] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[481.378351] [104/133] (543s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[481.470342] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[481.473248] Starting dynamic subtest: pipe-A
[482.518842] Dynamic subtest pipe-A: SUCCESS (1.050s)
[482.528660] Starting dynamic subtest: pipe-B
[484.622154] Dynamic subtest pipe-B: SUCCESS (2.102s)
[484.631608] Starting dynamic subtest: pipe-C
[485.817240] Dynamic subtest pipe-C: SUCCESS (1.194s)
[485.827160] Starting dynamic subtest: pipe-D
[487.053097] Dynamic subtest pipe-D: SUCCESS (1.235s)
[487.056076] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.585s)
[488.256168] [105/133] (536s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[488.352942] Starting subtest: compare-crc-sanitycheck-xr24
[488.355004] Starting dynamic subtest: pipe-A-eDP-1
[489.013804] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.659s)
[489.023769] Starting dynamic subtest: pipe-B-eDP-1
[490.352560] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.338s)
[490.362782] Starting dynamic subtest: pipe-C-eDP-1
[491.626254] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.273s)
[491.638601] Starting dynamic subtest: pipe-D-eDP-1
[492.855361] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.225s)
[492.858227] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.502s)
[493.682504] [106/133] (530s left) kms_atomic_transition (modeset-transition-nonblocking)
[493.774037] Starting subtest: modeset-transition-nonblocking
[493.776506] Starting dynamic subtest: 1x-outputs
[504.642522] Dynamic subtest 1x-outputs: SUCCESS (10.865s)
[504.645236] Subtest modeset-transition-nonblocking: SUCCESS (10.866s)
[505.508971] [107/133] (519s left) syncobj_wait (invalid-signal-illegal-handle)
[505.580695] Starting subtest: invalid-signal-illegal-handle
[505.583163] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[505.699829] [108/133] (519s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[505.794894] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[505.799600] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[505.891868] [109/133] (518s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[505.947442] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[506.048497] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[506.128768] [110/133] (518s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[506.181193] Starting subtest: invalid-multi-wait-unsubmitted
[506.183684] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[506.270572] [111/133] (518s left) syncobj_timeline (multi-wait-all-available-signaled)
[506.329576] Starting subtest: multi-wait-all-available-signaled
[506.331925] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[506.410878] [112/133] (518s left) gem_userptr_blits (invalid-null-pointer)
[506.481268] Starting subtest: invalid-null-pointer
[506.483446] Subtest invalid-null-pointer: SUCCESS (0.001s)
[506.599376] [113/133] (518s left) kms_psr (fbc-psr2-cursor-render)
[506.699254] Starting subtest: fbc-psr2-cursor-render
[506.701685] Starting dynamic subtest: eDP-1
[506.705982] Dynamic subtest eDP-1: SKIP (0.000s)
[506.710223] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[506.772651] [114/133] (518s left) gem_exec_schedule (u-submit-late-slice)
[506.850493] Starting subtest: u-submit-late-slice
[506.852644] Starting dynamic subtest: rcs0
[506.884213] Dynamic subtest rcs0: SUCCESS (0.043s)
[506.896834] Starting dynamic subtest: bcs0
[506.911755] Dynamic subtest bcs0: SUCCESS (0.027s)
[506.926676] Starting dynamic subtest: vcs0
[506.949570] Dynamic subtest vcs0: SUCCESS (0.036s)
[506.959906] Starting dynamic subtest: vcs1
[506.990226] Dynamic subtest vcs1: SUCCESS (0.039s)
[507.008224] Starting dynamic subtest: vecs0
[507.021977] Dynamic subtest vecs0: SUCCESS (0.030s)
[507.031843] Starting dynamic subtest: ccs0
[507.057986] Dynamic subtest ccs0: SUCCESS (0.034s)
[507.060700] Subtest u-submit-late-slice: SUCCESS (0.217s)
[508.292340] [115/133] (516s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[508.353219] Starting subtest: single-wait-all-for-submit-unsubmitted
[508.458229] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[508.535766] [116/133] (516s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[508.626911] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[512.874753] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.252s)
[514.008519] [117/133] (511s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[514.113234] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[514.118537] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[514.190099] [118/133] (510s left) kms_addfb_basic (master-rmfb)
[514.245259] Starting subtest: master-rmfb
[514.247942] Subtest master-rmfb: SUCCESS (0.001s)
[514.387308] [119/133] (510s left) api_intel_bb (offset-control)
[514.448221] Starting subtest: offset-control
[514.454816] Subtest offset-control: SUCCESS (0.009s)
[514.546201] [120/133] (510s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[514.639741] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[514.651161] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[514.758654] [121/133] (510s left) gen9_exec_parse (bb-secure)
[514.818029] Subtest bb-secure: SKIP (0.000s)
[514.884849] [122/133] (510s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[514.977693] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[514.982075] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[515.054642] [123/133] (510s left) kms_plane_multiple (2x-tiling-x)
[515.156919] Starting subtest: 2x-tiling-x
[515.161254] Subtest 2x-tiling-x: SKIP (0.000s)
[515.228660] [124/133] (510s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[515.346815] Starting subtest: basic-flip-before-cursor-varying-size
[516.295243] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.947s)
[517.155972] [125/133] (508s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[517.253837] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[517.256418] Starting dynamic subtest: pipe-A-eDP-1
[517.350000] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[517.360278] Starting dynamic subtest: pipe-B-eDP-1
[518.499019] Dynamic subtest pipe-B-eDP-1: SKIP (1.146s)
[518.504436] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.248s)
[519.687756] [126/133] (505s left) gem_basic (bad-close)
[519.742055] Starting subtest: bad-close
[519.747028] Subtest bad-close: SUCCESS (0.000s)
[519.842490] [127/133] (505s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[519.946983] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[519.952059] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[520.036267] [128/133] (505s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs)
[520.125985] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs
[520.128567] Starting dynamic subtest: pipe-A-eDP-1
[520.153757] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[520.164239] Starting dynamic subtest: pipe-B-eDP-1
[520.188360] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[520.197773] Starting dynamic subtest: pipe-C-eDP-1
[520.223220] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[520.234498] Starting dynamic subtest: pipe-D-eDP-1
[520.261408] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[520.266936] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[522.453139] [129/133] (503s left) kms_cursor_crc (cursor-sliding-128x128)
[522.668986] Starting subtest: cursor-sliding-128x128
[522.671417] Starting dynamic subtest: pipe-A-eDP-1
[525.894396] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.221s)
[525.924362] Starting dynamic subtest: pipe-D-eDP-1
[529.767306] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.850s)
[529.770392] Subtest cursor-sliding-128x128: SUCCESS (7.098s)
[530.633167] [130/133] (495s left) gem_exec_fence (long-history)
[531.088656] Starting subtest: long-history
[537.979734] Subtest long-history: SUCCESS (6.891s)
[538.497506] [131/133] (487s left) gem_ctx_exec (basic-close-race)
[538.557378] Starting subtest: basic-close-race
[544.499788] Subtest basic-close-race: SUCCESS (5.940s)
[544.637620] [132/133] (481s left) syncobj_wait (single-wait-all-signaled)
[544.697044] Starting subtest: single-wait-all-signaled
[544.699924] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[544.804306] [133/133] (480s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[544.921756] Starting subtest: basic-flip-before-cursor-legacy
[545.837063] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.913s)
[546.656161] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_reg_read
results: parsing output: 2/ for test: i915_drm_fdinfo
results: parsing output: 3/ for test: kms_pwrite_crc
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: api_intel_allocator
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_flip_scaled_crc
results: parsing output: 11/ for test: gem_exec_suspend
results: parsing output: 12/ for test: gem_exec_balancer
results: parsing output: 13/ for test: kms_pm_lpsp
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_wait
results: parsing output: 16/ for test: gem_exercise_blt
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: gem_exec_balancer
results: parsing output: 19/ for test: gem_ctx_shared
results: parsing output: 20/ for test: kms_busy
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_rotation_crc
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: gem_render_copy
results: parsing output: 25/ for test: gem_exec_schedule
results: parsing output: 26/ for test: kms_prime
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: gem_mmap_gtt
results: parsing output: 29/ for test: kms_content_protection
results: parsing output: 30/ for test: prime_vgem
results: parsing output: 31/ for test: i915_pm_rps
results: parsing output: 32/ for test: kms_pipe_crc_basic
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_pxp
results: parsing output: 36/ for test: kms_psr2_sf
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: dmabuf_sync_file
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: gem_exec_parallel
results: parsing output: 41/ for test: gem_pxp
results: parsing output: 42/ for test: gem_shrink
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_ctx_param
results: parsing output: 47/ for test: i915_pm_rpm
results: parsing output: 48/ for test: kms_lease
results: parsing output: 49/ for test: gem_exec_schedule
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: sw_sync
results: parsing output: 55/ for test: kms_vblank
results: parsing output: 56/ for test: kms_plane
results: parsing output: 57/ for test: gem_mmap_offset
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: gem_userptr_blits
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: gem_userptr_blits
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: gem_ctx_persistence
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: kms_cursor_crc
results: parsing output: 70/ for test: kms_color
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: gem_ctx_param
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_hdr
results: parsing output: 79/ for test: gem_mmap
results: parsing output: 80/ for test: syncobj_wait
results: parsing output: 81/ for test: kms_cursor_crc
results: parsing output: 82/ for test: gem_bad_reloc
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_pipe_control_store_loop
results: parsing output: 85/ for test: i915_pm_freq_api
results: parsing output: 86/ for test: gem_request_retire
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_chamelium_hpd
results: parsing output: 96/ for test: kms_feature_discovery
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: sysfs_defaults
results: parsing output: 99/ for test: gem_spin_batch
results: parsing output: 100/ for test: perf_pmu
results: parsing output: 101/ for test: kms_vblank
results: parsing output: 102/ for test: perf
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: kms_pipe_crc_basic
results: parsing output: 105/ for test: kms_atomic_transition
results: parsing output: 106/ for test: syncobj_wait
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gem_userptr_blits
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_exec_schedule
results: parsing output: 114/ for test: syncobj_wait
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_plane
results: parsing output: 117/ for test: kms_addfb_basic
results: parsing output: 118/ for test: api_intel_bb
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: gen9_exec_parse
results: parsing output: 121/ for test: kms_flip_scaled_crc
results: parsing output: 122/ for test: kms_plane_multiple
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: gem_basic
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: gem_exec_fence
results: parsing output: 130/ for test: gem_ctx_exec
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: kms_cursor_legacy
Done.