[58.059039] Initializing watchdogs
[58.059085]   /dev/watchdog0
[58.072605] [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]
[58.076942] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.079464] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.082750] [001/133] (960s left) kms_cdclk (plane-scaling)
[58.197100] Starting subtest: plane-scaling
[58.199233] Starting dynamic subtest: pipe-A-eDP-1
[58.485903] Dynamic subtest pipe-A-eDP-1: SKIP (0.287s)
[58.494064] Starting dynamic subtest: pipe-B-eDP-1
[58.498681] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[58.505304] Starting dynamic subtest: pipe-C-eDP-1
[58.509968] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[58.517361] Starting dynamic subtest: pipe-D-eDP-1
[58.521877] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[58.526085] Subtest plane-scaling: SKIP (0.294s)
[59.290200] [002/133] (959s left) gem_workarounds (basic-read-context)
[59.364067] Starting subtest: basic-read-context
[59.374705] Subtest basic-read-context: SUCCESS (0.013s)
[59.495042] [003/133] (959s left) gem_exec_create (basic)
[59.555415] Starting subtest: basic
[59.557894] Starting dynamic subtest: smem
[62.936594] Dynamic subtest smem: SUCCESS (3.383s)
[62.939112] Subtest basic: SUCCESS (3.384s)
[63.117932] [004/133] (955s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[63.218065] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[70.164881] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.956s)
[70.290160] [005/133] (948s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[70.382184] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[70.384445] Starting dynamic subtest: pipe-A-eDP-1
[70.835166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.424s)
[70.844633] Starting dynamic subtest: pipe-B-eDP-1
[72.311188] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.478s)
[72.319076] Starting dynamic subtest: pipe-C-eDP-1
[73.740917] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.430s)
[73.750512] Starting dynamic subtest: pipe-D-eDP-1
[75.052915] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.310s)
[75.055419] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.645s)
[76.137417] [006/133] (942s left) gem_mmap (bad-object)
[76.199986] Subtest bad-object: SKIP (0.000s)
[76.256781] [007/133] (942s left) kms_psr (fbc-pr-sprite-blt)
[76.369202] Starting subtest: fbc-pr-sprite-blt
[76.373186] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[76.440206] [008/133] (942s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[76.535603] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[76.539769] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[76.618136] [009/133] (942s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[76.713105] Starting subtest: psr-rgb565-draw-blt
[83.772627] Subtest psr-rgb565-draw-blt: SUCCESS (7.065s)
[83.915206] [010/133] (934s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[84.013047] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[84.017643] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[84.079801] [011/133] (934s left) gem_softpin (evict-active)
[84.132225] Starting subtest: evict-active
[84.138112] Subtest evict-active: SUCCESS (0.006s)
[84.294015] [012/133] (934s left) kms_addfb_basic (bad-pitch-256)
[84.339641] Starting subtest: bad-pitch-256
[84.341842] Subtest bad-pitch-256: SUCCESS (0.000s)
[84.478593] [013/133] (934s left) i915_pm_freq_mult (media-freq)
[84.531131] Starting subtest: media-freq
[84.533508] Starting dynamic subtest: gt0
[84.537710] Dynamic subtest gt0: SKIP (0.000s)
[84.546311] Starting dynamic subtest: gt1
[84.550418] Dynamic subtest gt1: SKIP (0.000s)
[84.554437] Subtest media-freq: SKIP (0.001s)
[84.633268] [014/133] (934s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[84.684638] Starting subtest: single-wait-all-for-submit-signaled
[84.686919] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[84.806280] [015/133] (934s left) gem_exec_schedule (semaphore-resolve)
[84.873503] Starting subtest: semaphore-resolve
[84.913340] Subtest semaphore-resolve: SUCCESS (0.040s)
[85.242417] [016/133] (933s left) api_intel_allocator (alloc-simple)
[85.295554] Starting subtest: alloc-simple
[85.297960] Subtest alloc-simple: SUCCESS (0.003s)
[85.425721] [017/133] (933s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[85.517189] Starting subtest: primary-y-tiled-reflect-x-0
[85.577209] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[85.677880] [018/133] (933s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[85.769086] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[85.771454] Starting dynamic subtest: pipe-A-eDP-1
[85.792456] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[85.802261] Starting dynamic subtest: pipe-B-eDP-1
[85.822175] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[85.829585] Starting dynamic subtest: pipe-C-eDP-1
[85.849271] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[85.856292] Starting dynamic subtest: pipe-D-eDP-1
[85.876302] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[85.878553] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.005s)
[88.018672] [019/133] (931s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[88.124087] Starting subtest: psr-farfromfence-mmap-gtt
[88.128320] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[88.221775] [020/133] (931s left) gem_softpin (evict-single-offset)
[88.292610] Starting subtest: evict-single-offset
[115.925500] Subtest evict-single-offset: SUCCESS (27.634s)
[116.004788] [021/133] (903s left) gem_exec_whisper (basic-contexts-priority-all)
[116.106690] Starting subtest: basic-contexts-priority-all
[139.092861] Subtest basic-contexts-priority-all: SUCCESS (22.979s)
[139.270913] [022/133] (880s left) kms_flip (2x-plain-flip-fb-recreate)
[139.367301] Starting subtest: 2x-plain-flip-fb-recreate
[139.378185] Subtest 2x-plain-flip-fb-recreate: SKIP (0.010s)
[139.467822] [023/133] (879s left) kms_flip (bo-too-big)
[139.557219] Starting subtest: bo-too-big
[139.559595] Starting dynamic subtest: A-eDP1
[140.878409] Dynamic subtest A-eDP1: SUCCESS (1.321s)
[140.888979] Starting dynamic subtest: B-eDP1
[142.212590] Dynamic subtest B-eDP1: SUCCESS (1.333s)
[142.222731] Starting dynamic subtest: C-eDP1
[143.564040] Dynamic subtest C-eDP1: SUCCESS (1.350s)
[143.572443] Starting dynamic subtest: D-eDP1
[144.899609] Dynamic subtest D-eDP1: SUCCESS (1.334s)
[144.902077] Subtest bo-too-big: SUCCESS (5.346s)
[146.099826] [024/133] (873s left) syncobj_timeline (invalid-query-illegal-handle)
[146.166414] Starting subtest: invalid-query-illegal-handle
[146.168806] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[146.271687] [025/133] (873s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[146.362990] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[146.367137] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[146.428768] [026/133] (873s left) gem_eio (in-flight-contexts-immediate)
[146.670318] Starting subtest: in-flight-contexts-immediate
[157.930340] Subtest in-flight-contexts-immediate: SUCCESS (11.261s)
[158.080449] [027/133] (861s left) sw_sync (alloc_fence_invalid_timeline)
[158.124102] Starting subtest: alloc_fence_invalid_timeline
[158.126425] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[158.182773] [028/133] (861s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[158.281543] Starting subtest: fbcpsr-indfb-scaledprimary
[164.100703] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.822s)
[164.238574] [029/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[164.331857] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[164.336344] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[164.437829] [030/133] (855s left) gem_mmap_wc (write-gtt-read-wc)
[164.505207] Subtest write-gtt-read-wc: SKIP (0.000s)
[164.570130] [031/133] (855s left) i915_getparams_basic (basic-eu-total)
[164.612707] Starting subtest: basic-eu-total
[164.617013] Subtest basic-eu-total: SUCCESS (0.000s)
[164.707311] [032/133] (855s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[164.792323] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[164.794655] Starting dynamic subtest: pipe-A
[165.129861] Dynamic subtest pipe-A: SUCCESS (0.338s)
[165.140830] Starting dynamic subtest: pipe-B
[166.529544] Dynamic subtest pipe-B: SUCCESS (1.398s)
[166.539144] Starting dynamic subtest: pipe-C
[167.968807] Dynamic subtest pipe-C: SUCCESS (1.439s)
[167.980768] Starting dynamic subtest: pipe-D
[169.406091] Dynamic subtest pipe-D: SUCCESS (1.437s)
[169.408354] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.614s)
[170.573060] [033/133] (849s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[170.695654] Starting subtest: flip-vs-cursor-atomic
[178.115640] Subtest flip-vs-cursor-atomic: SUCCESS (7.418s)
[178.913455] [034/133] (840s left) vgem_basic (bad-pad)
[179.015189] Starting subtest: bad-pad
[179.017409] Subtest bad-pad: SUCCESS (0.000s)
[179.071073] [FACT vgem_basic (bad-pad)] new: kernel.kmod_is_loaded.vgem: true
[179.074082] [035/133] (840s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[179.165443] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[179.169459] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[179.268093] [036/133] (840s left) gem_userptr_blits (access-control)
[179.343084] Starting subtest: access-control
[179.347587] Subtest access-control: SKIP (0.000s)
[179.445858] [037/133] (840s left) gem_render_copy (x-tiled)
[179.499716] Starting subtest: x-tiled
[179.502061] Starting dynamic subtest: smem
[179.541600] Dynamic subtest smem: SUCCESS (0.041s)
[179.544192] Subtest x-tiled: SUCCESS (0.042s)
[179.725825] [038/133] (840s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[179.972786] Starting subtest: cursor-rapid-movement-256x256
[179.977621] Starting dynamic subtest: pipe-A-eDP-1
[180.530524] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.553s)
[180.559318] Starting dynamic subtest: pipe-D-eDP-1
[181.740319] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.187s)
[181.742224] Subtest cursor-rapid-movement-256x256: SUCCESS (1.765s)
[182.588178] [039/133] (837s left) drm_mm (drm_mm)
[182.627941] Starting subtest: drm_mm
[182.638986] Starting dynamic subtest: drm_test_mm_init
[182.731048] Dynamic subtest drm_test_mm_init: SUCCESS (0.092s)
[182.741105] Starting dynamic subtest: drm_test_mm_debug
[182.834226] Dynamic subtest drm_test_mm_debug: SUCCESS (0.102s)
[182.849221] Starting dynamic subtest: drm_test_mm_align32
[182.939016] Dynamic subtest drm_test_mm_align32: SUCCESS (0.104s)
[182.952116] Starting dynamic subtest: drm_test_mm_align64
[183.045447] Dynamic subtest drm_test_mm_align64: SUCCESS (0.105s)
[183.056431] Starting dynamic subtest: drm_test_mm_lowest
[183.141247] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.096s)
[183.148819] Starting dynamic subtest: drm_test_mm_highest
[183.236190] Dynamic subtest drm_test_mm_highest: SUCCESS (0.093s)
[183.238047] Subtest drm_mm: SUCCESS (0.607s)
[183.404851] [040/133] (836s left) core_debugfs (read-all-entries)
[183.453664] Starting subtest: read-all-entries
[183.546515] Subtest read-all-entries: SUCCESS (0.094s)
[183.613393] [041/133] (836s left) kms_big_fb (linear-16bpp-rotate-270)
[183.715797] Starting subtest: linear-16bpp-rotate-270
[183.736573] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[183.847549] [042/133] (836s left) kms_cursor_edge_walk (128x128-left-edge)
[183.941580] Starting subtest: 128x128-left-edge
[183.943546] Starting dynamic subtest: pipe-A-eDP-1
[187.361741] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.419s)
[187.379192] Starting dynamic subtest: pipe-D-eDP-1
[190.474434] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.112s)
[190.476791] Subtest 128x128-left-edge: SUCCESS (6.533s)
[191.294466] [043/133] (828s left) kms_fbcon_fbt (psr-suspend)
[191.360635] Starting subtest: psr-suspend
[240.334335] Subtest psr-suspend: SUCCESS (19.823s)
[240.408450] [044/133] (779s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[240.504216] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[240.508327] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[240.576413] [045/133] (779s left) gem_exec_fence (invalid-timeline-fence-array)
[241.110515] Starting subtest: invalid-timeline-fence-array
[241.117213] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[242.144052] [046/133] (778s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[242.246032] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[242.250306] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[242.355079] [047/133] (777s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[242.447328] Starting subtest: y-tiled-16bpp-rotate-270
[242.451625] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[242.544082] [048/133] (777s left) perf (gen12-group-concurrent-oa-buffer-read)
[242.678587] Starting subtest: gen12-group-concurrent-oa-buffer-read
[252.705604] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.044s)
[252.798635] [049/133] (767s left) syncobj_basic (illegal-fd-to-handle)
[252.859471] Starting subtest: illegal-fd-to-handle
[252.861412] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[252.956783] [050/133] (767s left) kms_mmap_write_crc (main)
[253.046690] Starting subtest: main
[253.048998] Starting dynamic subtest: pipe-A-eDP-1
[256.458937] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.412s)
[256.460952] Subtest main: SUCCESS (3.413s)
[257.280058] [051/133] (763s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[257.339356] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[257.341774] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[257.436208] [052/133] (763s left) gem_exec_params (invalid-flag)
[257.494690] Starting subtest: invalid-flag
[257.497064] Subtest invalid-flag: SUCCESS (0.000s)
[257.588502] [053/133] (763s left) kms_invalid_mode (clock-too-high)
[257.691485] Starting subtest: clock-too-high
[257.693696] Starting dynamic subtest: pipe-A-eDP-1
[257.697749] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[257.708107] Starting dynamic subtest: pipe-B-eDP-1
[257.712386] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[257.722422] Starting dynamic subtest: pipe-C-eDP-1
[257.726302] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[257.734556] Starting dynamic subtest: pipe-D-eDP-1
[257.738492] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[257.742474] Subtest clock-too-high: SKIP (0.004s)
[257.779392] [054/133] (762s left) kms_psr (pr-cursor-mmap-gtt)
[257.878900] Starting subtest: pr-cursor-mmap-gtt
[257.882825] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[257.963000] [055/133] (762s left) kms_plane_multiple (tiling-4)
[258.059464] Starting subtest: tiling-4
[258.061370] Starting dynamic subtest: pipe-A-eDP-1
[261.723090] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.666s)
[261.733700] Starting dynamic subtest: pipe-B-eDP-1
[266.261085] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.539s)
[266.271005] Starting dynamic subtest: pipe-C-eDP-1
[270.573968] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.310s)
[270.584057] Starting dynamic subtest: pipe-D-eDP-1
[274.913987] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.339s)
[274.916228] Subtest tiling-4: SUCCESS (16.859s)
[275.714927] [056/133] (745s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[275.774292] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[275.881918] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s)
[275.958010] [057/133] (744s left) gem_exec_reloc (basic-concurrent0)
[276.026302] Subtest basic-concurrent0: SKIP (0.000s)
[276.101409] [058/133] (744s left) kms_flip (flip-vs-suspend)
[276.196651] Starting subtest: flip-vs-suspend
[276.199004] Starting dynamic subtest: A-eDP1
[421.351159] Dynamic subtest A-eDP1: SUCCESS (11.299s)
[421.353700] Subtest flip-vs-suspend: SUCCESS (11.301s)
[421.495134] [059/133] (599s left) kms_psr (psr-sprite-blt)
[421.593255] Starting subtest: psr-sprite-blt
[421.596048] Starting dynamic subtest: eDP-1
[423.332051] Dynamic subtest eDP-1: SUCCESS (1.749s)
[423.334645] Subtest psr-sprite-blt: SUCCESS (1.750s)
[424.163274] [060/133] (596s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[424.218268] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[424.320013] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.103s)
[424.396545] [061/133] (596s left) gem_exec_balancer (parallel)
[424.653721] Starting subtest: parallel
[425.574592] Subtest parallel: SUCCESS (0.924s)
[426.033254] [062/133] (594s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[426.142763] Starting subtest: cursor-vs-flip-toggle
[457.421685] Subtest cursor-vs-flip-toggle: SUCCESS (31.309s)
[458.257437] [063/133] (562s left) kms_vblank (wait-forked)
[458.350030] Starting subtest: wait-forked
[458.352075] Starting dynamic subtest: pipe-A-eDP-1
[460.170002] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.820s)
[460.185964] Starting dynamic subtest: pipe-D-eDP-1
[462.659230] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.486s)
[462.662151] Subtest wait-forked: SUCCESS (4.309s)
[463.532737] [064/133] (557s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[463.634272] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[463.639905] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[463.710139] [065/133] (557s left) gem_mmap_offset (perf)
[463.764911] Starting subtest: perf
[463.767526] Starting dynamic subtest: smem0
[464.119166] Dynamic subtest smem0: SUCCESS (0.354s)
[464.122170] Subtest perf: SUCCESS (0.355s)
[464.228619] [066/133] (556s left) gem_mmap_offset (ptrace)
[464.275875] Starting subtest: ptrace
[464.278315] Starting dynamic subtest: smem0-wb
[464.296817] Dynamic subtest smem0-wb: SUCCESS (0.019s)
[464.306872] Starting dynamic subtest: smem0-wc
[464.318154] Dynamic subtest smem0-wc: SUCCESS (0.021s)
[464.327515] Starting dynamic subtest: smem0-uc
[464.336462] Dynamic subtest smem0-uc: SUCCESS (0.018s)
[464.339085] Subtest ptrace: SUCCESS (0.064s)
[464.435705] [067/133] (556s left) kms_draw_crc (draw-method-pwrite)
[464.537055] Starting subtest: draw-method-pwrite
[464.539693] Starting dynamic subtest: xrgb8888-untiled
[465.081773] Dynamic subtest xrgb8888-untiled: SUCCESS (0.546s)
[465.092006] Starting dynamic subtest: rgb565-untiled
[465.525970] Dynamic subtest rgb565-untiled: SUCCESS (0.442s)
[465.537803] Starting dynamic subtest: xrgb2101010-untiled
[466.081485] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.555s)
[466.091683] Starting dynamic subtest: xbgr16161616f-untiled
[466.817272] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.733s)
[466.826551] Starting dynamic subtest: xrgb8888-xtiled
[468.492837] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.677s)
[468.502093] Starting dynamic subtest: rgb565-xtiled
[469.528055] Dynamic subtest rgb565-xtiled: SUCCESS (1.033s)
[469.537615] Starting dynamic subtest: xrgb2101010-xtiled
[471.205002] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.677s)
[471.215267] Starting dynamic subtest: xbgr16161616f-xtiled
[474.427111] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.221s)
[474.437104] Starting dynamic subtest: xrgb8888-4tiled
[475.493828] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.064s)
[475.503293] Starting dynamic subtest: rgb565-4tiled
[476.026959] Dynamic subtest rgb565-4tiled: SUCCESS (0.532s)
[476.036531] Starting dynamic subtest: xrgb2101010-4tiled
[477.071249] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.043s)
[477.081785] Starting dynamic subtest: xbgr16161616f-4tiled
[479.938026] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.866s)
[479.940907] Subtest draw-method-pwrite: SUCCESS (15.403s)
[480.034313] [068/133] (541s left) gem_exercise_blt (fast-copy)
[480.090948] Starting subtest: fast-copy
[480.093611] Starting dynamic subtest: linear-smem-smem
[480.112733] Dynamic subtest linear-smem-smem: SUCCESS (0.024s)
[480.123980] Starting dynamic subtest: xmajor-smem-smem
[480.129868] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[480.139246] Starting dynamic subtest: tile4-smem-smem
[480.147763] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[480.157370] Starting dynamic subtest: tile64-smem-smem
[480.165130] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[480.168058] Subtest fast-copy: SUCCESS (0.077s)
[480.290615] [069/133] (540s left) kms_pm_rpm (pm-tiling)
[482.116737] Starting subtest: pm-tiling
[482.122515] Subtest pm-tiling: SKIP (0.000s)
[482.558648] [070/133] (538s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[482.666786] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[482.672384] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[482.752466] [071/133] (538s left) kms_atomic (crtc-invalid-params)
[482.839081] Starting subtest: crtc-invalid-params
[482.841866] Starting dynamic subtest: pipe-A-eDP-1
[483.200391] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.361s)
[483.203274] Subtest crtc-invalid-params: SUCCESS (0.363s)
[484.091147] [072/133] (537s left) perf_pmu (busy-hang)
[484.135425] Starting subtest: busy-hang
[484.137918] Starting dynamic subtest: rcs0
[485.402150] Dynamic subtest rcs0: SUCCESS (1.271s)
[485.414868] Starting dynamic subtest: bcs0
[486.630397] Dynamic subtest bcs0: SUCCESS (1.227s)
[486.642996] Starting dynamic subtest: vcs0
[488.460449] Dynamic subtest vcs0: SUCCESS (1.828s)
[488.472882] Starting dynamic subtest: vcs1
[490.509747] Dynamic subtest vcs1: SUCCESS (2.048s)
[490.521833] Starting dynamic subtest: vecs0
[492.502809] Dynamic subtest vecs0: SUCCESS (1.991s)
[492.515101] Starting dynamic subtest: ccs0
[493.754243] Dynamic subtest ccs0: SUCCESS (1.247s)
[493.756717] Subtest busy-hang: SUCCESS (9.624s)
[493.864419] [073/133] (527s left) gem_exec_schedule (preempt-hang)
[494.038253] Starting subtest: preempt-hang
[494.041108] Starting dynamic subtest: rcs0
[494.311082] Dynamic subtest rcs0: SUCCESS (0.273s)
[494.322069] Starting dynamic subtest: bcs0
[494.595313] Dynamic subtest bcs0: SUCCESS (0.284s)
[494.605470] Starting dynamic subtest: vcs0
[494.827218] Dynamic subtest vcs0: SUCCESS (0.228s)
[494.836979] Starting dynamic subtest: vcs1
[495.062376] Dynamic subtest vcs1: SUCCESS (0.236s)
[495.073262] Starting dynamic subtest: vecs0
[495.294278] Dynamic subtest vecs0: SUCCESS (0.230s)
[495.305305] Starting dynamic subtest: ccs0
[495.563992] Dynamic subtest ccs0: SUCCESS (0.271s)
[495.566730] Subtest preempt-hang: SUCCESS (1.527s)
[496.040560] [074/133] (525s left) gem_reg_read (timestamp-monotonic)
[496.114767] Starting subtest: timestamp-monotonic
[500.792016] Subtest timestamp-monotonic: SUCCESS (4.679s)
[500.866302] [075/133] (520s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[500.964177] Starting subtest: plane-all-modeset-transition-fencing
[500.970079] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[501.035545] [076/133] (520s left) gem_exec_reloc (basic-write-cpu)
[501.095099] Subtest basic-write-cpu: SKIP (0.000s)
[501.159705] [077/133] (520s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[501.258384] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[501.263114] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[501.360174] [078/133] (520s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[501.454042] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[501.459256] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[501.553842] [079/133] (520s left) i915_drm_fdinfo (busy-idle-check-all)
[501.594744] Starting subtest: busy-idle-check-all
[501.596757] Starting dynamic subtest: rcs0
[501.601724] Dynamic subtest rcs0: SKIP (0.003s)
[501.612332] Starting dynamic subtest: bcs0
[501.617249] Dynamic subtest bcs0: SKIP (0.000s)
[501.625759] Starting dynamic subtest: vcs0
[501.630095] Dynamic subtest vcs0: SKIP (0.000s)
[501.639027] Starting dynamic subtest: vcs1
[501.643863] Dynamic subtest vcs1: SKIP (0.000s)
[501.652817] Starting dynamic subtest: vecs0
[501.657422] Dynamic subtest vecs0: SKIP (0.000s)
[501.666146] Starting dynamic subtest: ccs0
[501.670951] Dynamic subtest ccs0: SKIP (0.000s)
[501.675759] Subtest busy-idle-check-all: SKIP (0.008s)
[501.714610] [080/133] (520s left) syncobj_basic (bad-flags-handle-to-fd)
[501.775120] Starting subtest: bad-flags-handle-to-fd
[501.777432] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[501.887845] [081/133] (519s left) perf (invalid-oa-metric-set-id)
[501.970875] Starting subtest: invalid-oa-metric-set-id
[501.981835] Subtest invalid-oa-metric-set-id: SUCCESS (0.010s)
[502.030122] [082/133] (519s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[502.411506] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[503.191004] [083/133] (518s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[503.248276] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[503.254229] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[503.435002] [084/133] (518s left) kms_pipe_crc_basic (bad-source)
[503.530685] Starting subtest: bad-source
[503.533125] Subtest bad-source: SUCCESS (0.000s)
[503.600810] [085/133] (518s left) gem_vm_create (isolation)
[503.659851] Starting subtest: isolation
[503.667997] Subtest isolation: SUCCESS (0.008s)
[503.780108] [086/133] (518s left) syncobj_wait (single-wait-all-signaled)
[503.836969] Starting subtest: single-wait-all-signaled
[503.839540] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[503.933181] [087/133] (518s left) gem_ctx_persistence (engines-mixed)
[503.990983] Starting subtest: engines-mixed
[503.993616] Starting dynamic subtest: rcs0
[504.058094] Dynamic subtest rcs0: SUCCESS (0.066s)
[504.069561] Starting dynamic subtest: bcs0
[504.107022] Dynamic subtest bcs0: SUCCESS (0.048s)
[504.116793] Starting dynamic subtest: vcs0
[504.157138] Dynamic subtest vcs0: SUCCESS (0.048s)
[504.168092] Starting dynamic subtest: vcs1
[504.204109] Dynamic subtest vcs1: SUCCESS (0.045s)
[504.213945] Starting dynamic subtest: vecs0
[504.247957] Dynamic subtest vecs0: SUCCESS (0.042s)
[504.258687] Starting dynamic subtest: ccs0
[504.294260] Dynamic subtest ccs0: SUCCESS (0.044s)
[504.297102] Subtest engines-mixed: SUCCESS (0.304s)
[504.376693] [088/133] (517s left) kms_lease (lease-get)
[504.467145] Starting subtest: lease-get
[504.469413] Starting dynamic subtest: pipe-A-eDP-1
[504.471629] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[504.480973] Starting dynamic subtest: pipe-B-eDP-1
[504.482944] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[504.491483] Starting dynamic subtest: pipe-C-eDP-1
[504.493545] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[504.502031] Starting dynamic subtest: pipe-D-eDP-1
[504.503949] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[504.506127] Subtest lease-get: SUCCESS (0.009s)
[504.603019] [089/133] (517s left) i915_suspend (basic-s2idle-without-i915)
[504.654225] Starting subtest: basic-s2idle-without-i915
[525.837657] Subtest basic-s2idle-without-i915: SUCCESS (5.637s)
[525.963023] [090/133] (496s left) kms_psr (psr2-no-drrs)
[526.059483] Starting subtest: psr2-no-drrs
[526.061733] Starting dynamic subtest: eDP-1
[527.689337] Dynamic subtest eDP-1: SUCCESS (1.637s)
[527.691643] Subtest psr2-no-drrs: SUCCESS (1.638s)
[528.482677] [091/133] (493s left) gem_ctx_engines (execute-allforone)
[528.537737] Starting subtest: execute-allforone
[528.553655] Subtest execute-allforone: SUCCESS (0.019s)
[530.558599] [092/133] (491s left) kms_selftest (drm_cmdline_parser)
[530.592850] Starting subtest: drm_cmdline_parser
[530.606738] Starting dynamic subtest: drm_test_cmdline_force_d_only
[530.701203] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.095s)
[530.710738] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[530.796088] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.094s)
[530.805877] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[530.897807] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.102s)
[530.907833] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[531.000601] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.102s)
[531.008790] Starting dynamic subtest: drm_test_cmdline_force_e_only
[531.089302] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.089s)
[531.097394] Starting dynamic subtest: drm_test_cmdline_res
[531.182987] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.093s)
[531.191142] Starting dynamic subtest: drm_test_cmdline_res_vesa
[531.265061] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.082s)
[531.273189] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[531.361957] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.095s)
[531.371815] Starting dynamic subtest: drm_test_cmdline_res_rblank
[531.459701] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.097s)
[531.469402] Starting dynamic subtest: drm_test_cmdline_res_bpp
[531.557093] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.098s)
[531.567284] Starting dynamic subtest: drm_test_cmdline_res_refresh
[531.652317] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.095s)
[531.659774] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[531.768952] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.115s)
[531.776670] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[531.869621] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.101s)
[531.877340] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[531.970011] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.100s)
[531.977708] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[532.051722] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.081s)
[532.059336] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[532.144099] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.092s)
[532.152115] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[532.256131] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.108s)
[532.265796] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[532.363620] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.108s)
[532.373336] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[532.469719] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.104s)
[532.479737] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[532.561798] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.090s)
[532.571810] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[532.654014] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.092s)
[532.662646] Starting dynamic subtest: drm_test_cmdline_name
[532.753773] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.098s)
[532.763803] Starting dynamic subtest: drm_test_cmdline_name_bpp
[532.848894] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.094s)
[532.858305] Starting dynamic subtest: drm_test_cmdline_name_option
[532.967849] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.117s)
[532.977456] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[533.064824] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.097s)
[533.074895] Starting dynamic subtest: drm_test_cmdline_rotate_0
[533.157922] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.093s)
[533.167707] Starting dynamic subtest: drm_test_cmdline_rotate_90
[533.262507] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.102s)
[533.270234] Starting dynamic subtest: drm_test_cmdline_rotate_180
[533.355044] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.093s)
[533.362778] Starting dynamic subtest: drm_test_cmdline_rotate_270
[533.452957] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.097s)
[533.460771] Starting dynamic subtest: drm_test_cmdline_hmirror
[533.552941] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.099s)
[533.560596] Starting dynamic subtest: drm_test_cmdline_vmirror
[533.668888] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.115s)
[533.677193] Starting dynamic subtest: drm_test_cmdline_margin_options
[533.772040] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.103s)
[533.780150] Starting dynamic subtest: drm_test_cmdline_multiple_options
[533.875043] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.103s)
[533.883016] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[533.975875] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.100s)
[533.984200] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[534.074611] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.095s)
[534.082420] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[534.186981] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.112s)
[534.194780] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[534.277195] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.090s)
[534.285018] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[534.394725] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.116s)
[534.402729] Starting dynamic subtest: drm_test_cmdline_invalid
[534.495750] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.101s)
[534.504068] Starting dynamic subtest: drm_test_cmdline_tv_options
[534.608749] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.114s)
[534.611030] Subtest drm_cmdline_parser: SUCCESS (4.026s)
[534.778562] [093/133] (487s left) gem_exec_nop (basic-parallel)
[534.858597] Starting subtest: basic-parallel
[553.452811] Subtest basic-parallel: SUCCESS (18.621s)
[553.687669] [094/133] (468s left) gem_exec_reloc (basic-gtt-read-noreloc)
[553.746558] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[553.818944] [095/133] (468s left) syncobj_wait (invalid-wait-bad-flags)
[553.870375] Starting subtest: invalid-wait-bad-flags
[553.872615] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[553.971041] [096/133] (468s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[554.081154] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[554.085108] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[554.202672] [097/133] (468s left) kms_addfb_basic (basic)
[554.262616] Starting subtest: basic
[554.264751] Subtest basic: SUCCESS (0.000s)
[554.402624] [098/133] (468s left) kms_plane_scaling (invalid-num-scalers)
[554.501392] Starting subtest: invalid-num-scalers
[554.503604] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[554.521497] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.020s)
[554.530315] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[554.556395] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.035s)
[554.564946] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[554.593347] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.035s)
[554.601818] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[554.630189] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.035s)
[554.631974] Subtest invalid-num-scalers: SUCCESS (0.131s)
[554.731991] [099/133] (467s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[554.821440] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[554.823256] Starting dynamic subtest: pipe-A-eDP-1
[554.841653] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[554.852042] Starting dynamic subtest: pipe-B-eDP-1
[554.869903] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[554.877769] Starting dynamic subtest: pipe-C-eDP-1
[554.895115] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[554.903562] Starting dynamic subtest: pipe-D-eDP-1
[554.920626] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[554.924008] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[557.110114] [100/133] (465s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[557.202604] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[557.204711] Starting dynamic subtest: pipe-A-eDP-1
[559.408627] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.206s)
[559.417613] Starting dynamic subtest: pipe-B-eDP-1
[561.906461] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.495s)
[561.915128] Starting dynamic subtest: pipe-C-eDP-1
[564.089785] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.182s)
[564.097960] Starting dynamic subtest: pipe-D-eDP-1
[566.155657] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.061s)
[566.157498] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.950s)
[567.374311] [101/133] (455s left) kms_color (ctm-red-to-blue)
[567.470159] Starting subtest: ctm-red-to-blue
[567.472320] Starting dynamic subtest: pipe-A-eDP-1
[568.201803] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.731s)
[568.210889] Starting dynamic subtest: pipe-B-eDP-1
[569.575806] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.372s)
[569.585284] Starting dynamic subtest: pipe-C-eDP-1
[570.953689] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.377s)
[570.962887] Starting dynamic subtest: pipe-D-eDP-1
[572.270568] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[572.272837] Subtest ctm-red-to-blue: SUCCESS (4.801s)
[573.086991] [102/133] (449s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[573.181806] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[573.210602] Starting dynamic subtest: pipe-A-plane-0
[573.499916] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[573.511608] Starting dynamic subtest: pipe-A-plane-3
[573.732422] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[573.741970] Starting dynamic subtest: pipe-A-plane-5
[573.746156] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[574.883597] Starting dynamic subtest: pipe-B-plane-0
[575.176597] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[575.185614] Starting dynamic subtest: pipe-B-plane-3
[575.409155] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[575.419149] Starting dynamic subtest: pipe-B-plane-5
[575.423619] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[575.721742] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.538s)
[576.562918] [103/133] (446s left) kms_psr (fbc-psr-dpms)
[576.656999] Starting subtest: fbc-psr-dpms
[576.659305] Starting dynamic subtest: eDP-1
[576.663679] Dynamic subtest eDP-1: SKIP (0.000s)
[576.667994] Subtest fbc-psr-dpms: SKIP (0.001s)
[576.730718] [104/133] (445s left) gem_basic (multigpu-create-close)
[576.791967] Starting subtest: multigpu-create-close
[576.796270] Subtest multigpu-create-close: SKIP (0.000s)
[576.886826] [105/133] (445s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[576.996341] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[577.000641] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[577.092908] [106/133] (445s left) prime_vgem (busy)
[577.155598] Starting subtest: busy
[577.157974] Starting dynamic subtest: rcs0
[577.199024] Dynamic subtest rcs0: SUCCESS (0.044s)
[577.208137] Starting dynamic subtest: bcs0
[577.266207] Dynamic subtest bcs0: SUCCESS (0.066s)
[577.275170] Starting dynamic subtest: vcs0
[577.314609] Dynamic subtest vcs0: SUCCESS (0.048s)
[577.323215] Starting dynamic subtest: vcs1
[577.358273] Dynamic subtest vcs1: SUCCESS (0.043s)
[577.366794] Starting dynamic subtest: vecs0
[577.406713] Dynamic subtest vecs0: SUCCESS (0.047s)
[577.415178] Starting dynamic subtest: ccs0
[577.453690] Dynamic subtest ccs0: SUCCESS (0.046s)
[577.455948] Subtest busy: SUCCESS (0.299s)
[577.565153] [107/133] (445s left) gem_ctx_persistence (legacy-engines-cleanup)
[577.630495] Starting subtest: legacy-engines-cleanup
[577.632629] Starting dynamic subtest: render
[577.720878] Dynamic subtest render: SUCCESS (0.090s)
[577.733705] Starting dynamic subtest: bsd1
[577.767590] Dynamic subtest bsd1: SUCCESS (0.045s)
[577.779896] Starting dynamic subtest: bsd2
[577.792400] Dynamic subtest bsd2: SUCCESS (0.022s)
[577.802429] Starting dynamic subtest: blt
[577.875758] Dynamic subtest blt: SUCCESS (0.081s)
[577.887985] Starting dynamic subtest: vebox
[577.923991] Dynamic subtest vebox: SUCCESS (0.044s)
[577.926346] Subtest legacy-engines-cleanup: SUCCESS (0.295s)
[578.009609] [108/133] (444s left) kms_big_fb (x-tiled-addfb-size-overflow)
[578.135031] Starting subtest: x-tiled-addfb-size-overflow
[578.136975] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[578.234972] [109/133] (444s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[578.622012] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[579.466690] [110/133] (443s left) gem_lmem_swapping (verify-random)
[579.509578] Subtest verify-random: SKIP (0.000s)
[579.552115] [111/133] (443s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[579.662678] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[579.667117] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[579.807943] [112/133] (443s left) gem_ctx_shared (exec-single-timeline)
[579.878192] Starting subtest: exec-single-timeline
[579.880603] Starting dynamic subtest: rcs0
[580.907820] Dynamic subtest rcs0: SUCCESS (1.028s)
[580.920483] Starting dynamic subtest: bcs0
[581.995880] Dynamic subtest bcs0: SUCCESS (1.084s)
[582.009218] Starting dynamic subtest: vcs0
[583.086386] Dynamic subtest vcs0: SUCCESS (1.087s)
[583.099650] Starting dynamic subtest: vcs1
[584.172572] Dynamic subtest vcs1: SUCCESS (1.083s)
[584.185318] Starting dynamic subtest: vecs0
[585.261756] Dynamic subtest vecs0: SUCCESS (1.086s)
[585.273119] Starting dynamic subtest: ccs0
[586.349185] Dynamic subtest ccs0: SUCCESS (1.085s)
[586.352334] Subtest exec-single-timeline: SUCCESS (6.472s)
[586.536120] [113/133] (436s left) gem_exec_flush (basic-wb-pro-default)
[586.607442] Starting subtest: basic-wb-pro-default
[592.079434] Subtest basic-wb-pro-default: SUCCESS (5.475s)
[592.281962] [114/133] (430s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[592.381189] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[592.385637] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[592.467793] [115/133] (430s left) kms_psr (fbc-pr-no-drrs)
[592.561597] Starting subtest: fbc-pr-no-drrs
[592.565598] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[592.648720] [116/133] (430s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[592.751696] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[592.755643] Subtest fbc-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[592.834029] [117/133] (430s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[592.925743] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[599.750328] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.829s)
[599.884934] [118/133] (423s left) kms_content_protection (atomic)
[600.027630] Starting subtest: atomic
[600.032135] Subtest atomic: SKIP (0.000s)
[600.151810] [119/133] (423s left) kms_cursor_crc (cursor-offscreen-512x170)
[600.418401] Starting subtest: cursor-offscreen-512x170
[600.422856] Subtest cursor-offscreen-512x170: SKIP (0.002s)
[600.558500] [120/133] (422s left) gem_userptr_blits (unsync-unmap)
[600.613237] Subtest unsync-unmap: SKIP (0.000s)
[600.723904] [121/133] (422s left) kms_cursor_legacy (torture-bo)
[600.835740] Starting subtest: torture-bo
[600.837627] Starting dynamic subtest: pipe-A
[606.933630] Dynamic subtest pipe-A: SUCCESS (6.094s)
[606.945532] Starting dynamic subtest: pipe-B
[613.860649] Dynamic subtest pipe-B: SUCCESS (6.927s)
[613.874893] Starting dynamic subtest: pipe-C
[620.414836] Dynamic subtest pipe-C: SUCCESS (6.556s)
[620.427728] Starting dynamic subtest: pipe-D
[626.914925] Dynamic subtest pipe-D: SUCCESS (6.495s)
[626.923932] Starting dynamic subtest: all-pipes
[634.001305] Dynamic subtest all-pipes: SUCCESS (7.080s)
[634.003917] Subtest torture-bo: SUCCESS (33.163s)
[634.161358] [122/133] (389s left) kms_pm_backlight (fade-with-dpms)
[634.257185] Starting subtest: fade-with-dpms
[636.369409] Starting dynamic subtest: eDP-1
[639.059399] Dynamic subtest eDP-1: SUCCESS (2.686s)
[639.061683] Subtest fade-with-dpms: SUCCESS (4.800s)
[639.871476] [123/133] (383s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[639.973235] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[640.939718] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (0.967s)
[641.072973] [124/133] (382s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[641.175818] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[648.524397] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.351s)
[648.660731] [125/133] (374s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[648.774334] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[648.778840] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[648.918760] [126/133] (374s left) gen9_exec_parse (basic-rejected-ctx-param)
[648.971988] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[649.029518] [127/133] (374s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[649.116471] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[649.120989] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[649.233774] [128/133] (374s left) kms_busy (extended-modeset-hang-oldfb)
[649.324694] Starting subtest: extended-modeset-hang-oldfb
[649.327108] Starting dynamic subtest: pipe-A
[660.867522] Dynamic subtest pipe-A: SUCCESS (11.541s)
[660.876793] Starting dynamic subtest: pipe-D
[673.149655] Dynamic subtest pipe-D: SUCCESS (12.278s)
[673.151854] Subtest extended-modeset-hang-oldfb: SUCCESS (23.825s)
[673.956430] [129/133] (349s left) kms_rotation_crc (cursor-rotation-180)
[674.049588] Starting subtest: cursor-rotation-180
[675.544035] Subtest cursor-rotation-180: SUCCESS (1.495s)
[676.725485] [130/133] (347s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[676.828625] Starting subtest: addfb25-y-tiled-small-legacy
[676.832989] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[676.928470] [131/133] (346s left) gem_pxp (reject-modify-context-protection-off-2)
[677.050429] Starting subtest: reject-modify-context-protection-off-2
[677.052958] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[677.166895] [132/133] (346s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[677.263266] Starting subtest: 4-tiled-64bpp-rotate-270
[677.282900] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[677.381864] [133/133] (346s left) gem_exec_fence (basic-wait)
[677.551566] Starting subtest: basic-wait
[677.553728] Starting dynamic subtest: rcs0
[677.640515] Dynamic subtest rcs0: SUCCESS (0.088s)
[677.651597] Starting dynamic subtest: bcs0
[677.710184] Dynamic subtest bcs0: SUCCESS (0.068s)
[677.722324] Starting dynamic subtest: vcs0
[677.768274] Dynamic subtest vcs0: SUCCESS (0.055s)
[677.779874] Starting dynamic subtest: vcs1
[677.835208] Dynamic subtest vcs1: SUCCESS (0.066s)
[677.846116] Starting dynamic subtest: vecs0
[677.902341] Dynamic subtest vecs0: SUCCESS (0.064s)
[677.914398] Starting dynamic subtest: ccs0
[677.967204] Dynamic subtest ccs0: SUCCESS (0.062s)
[677.969665] Subtest basic-wait: SUCCESS (0.417s)
[679.570249] Closing watchdogs
results: parsing output: 0/ for test: kms_cdclk
results: parsing output: 1/ for test: gem_workarounds
results: parsing output: 2/ for test: gem_exec_create
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: gem_mmap
results: parsing output: 6/ for test: kms_psr
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_psr2_sf
results: parsing output: 10/ for test: gem_softpin
results: parsing output: 11/ for test: kms_addfb_basic
results: parsing output: 12/ for test: i915_pm_freq_mult
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: api_intel_allocator
results: parsing output: 16/ for test: kms_rotation_crc
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_softpin
results: parsing output: 20/ for test: gem_exec_whisper
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_flip
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: sw_sync
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_mmap_wc
results: parsing output: 30/ for test: i915_getparams_basic
results: parsing output: 31/ for test: kms_plane_scaling
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: vgem_basic
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: gem_userptr_blits
results: parsing output: 36/ for test: gem_render_copy
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: drm_mm
results: parsing output: 39/ for test: core_debugfs
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_cursor_edge_walk
results: parsing output: 42/ for test: kms_fbcon_fbt
results: parsing output: 43/ for test: kms_flip_scaled_crc
results: parsing output: 44/ for test: gem_exec_fence
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: syncobj_basic
results: parsing output: 49/ for test: kms_mmap_write_crc
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: gem_exec_params
results: parsing output: 52/ for test: kms_invalid_mode
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_plane_multiple
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: gem_exec_reloc
results: parsing output: 57/ for test: kms_flip
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: kms_vblank
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: gem_mmap_offset
results: parsing output: 65/ for test: gem_mmap_offset
results: parsing output: 66/ for test: kms_draw_crc
results: parsing output: 67/ for test: gem_exercise_blt
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_atomic
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: gem_exec_schedule
results: parsing output: 73/ for test: gem_reg_read
results: parsing output: 74/ for test: kms_atomic_transition
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: i915_drm_fdinfo
results: parsing output: 79/ for test: syncobj_basic
results: parsing output: 80/ for test: perf
results: parsing output: 81/ for test: kms_chamelium_edid
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: kms_pipe_crc_basic
results: parsing output: 84/ for test: gem_vm_create
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: kms_lease
results: parsing output: 88/ for test: i915_suspend
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: gem_ctx_engines
results: parsing output: 91/ for test: kms_selftest
results: parsing output: 92/ for test: gem_exec_nop
results: parsing output: 93/ for test: gem_exec_reloc
results: parsing output: 94/ for test: syncobj_wait
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_addfb_basic
results: parsing output: 97/ for test: kms_plane_scaling
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_ccs
results: parsing output: 100/ for test: kms_color
results: parsing output: 101/ for test: kms_plane
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: gem_basic
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: prime_vgem
results: parsing output: 106/ for test: gem_ctx_persistence
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_chamelium_hpd
results: parsing output: 109/ for test: gem_lmem_swapping
results: parsing output: 110/ for test: kms_cursor_legacy
results: parsing output: 111/ for test: gem_ctx_shared
results: parsing output: 112/ for test: gem_exec_flush
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_psr
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_content_protection
results: parsing output: 118/ for test: kms_cursor_crc
results: parsing output: 119/ for test: gem_userptr_blits
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: kms_pm_backlight
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gen9_exec_parse
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_busy
results: parsing output: 128/ for test: kms_rotation_crc
results: parsing output: 129/ for test: kms_addfb_basic
results: parsing output: 130/ for test: gem_pxp
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: gem_exec_fence
Done.