[61.026801] Initializing watchdogs
[61.026848]   /dev/watchdog0
[61.039903] [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]
[61.043976] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.046189] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.049769] [001/133] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[61.178586] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[61.182737] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[61.286646] [002/133] (960s left) kms_big_fb (y-tiled-addfb-size-overflow)
[61.388658] Starting subtest: y-tiled-addfb-size-overflow
[61.392724] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[61.487401] [003/133] (960s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[61.598456] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[61.602731] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[61.693326] [004/133] (959s left) gem_mmap_gtt (isolation)
[61.756016] Subtest isolation: SKIP (0.000s)
[61.813637] [005/133] (959s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[61.901035] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[61.902840] Starting dynamic subtest: pipe-A-default-mode
[63.085393] Dynamic subtest pipe-A-default-mode: SKIP (1.182s)
[63.090051] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.183s)
[63.227929] [006/133] (958s left) gem_pwrite_snooped
[63.359161] [007/133] (958s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[63.447629] Starting subtest: read-crc-frame-sequence
[63.449901] Starting dynamic subtest: pipe-A-eDP-1
[64.165648] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.717s)
[64.178068] Starting dynamic subtest: pipe-B-eDP-1
[65.531830] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.366s)
[65.541538] Starting dynamic subtest: pipe-C-eDP-1
[66.815632] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.284s)
[66.825049] Starting dynamic subtest: pipe-D-eDP-1
[68.149631] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[68.152068] Subtest read-crc-frame-sequence: SUCCESS (4.706s)
[68.972031] [008/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[69.076875] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[69.080840] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[69.175832] [009/133] (952s left) gem_exec_parallel (basic)
[69.248836] Starting subtest: basic
[69.251356] Starting dynamic subtest: rcs0
[70.648796] Dynamic subtest rcs0: SUCCESS (1.403s)
[70.656482] Starting dynamic subtest: bcs0
[72.115987] Dynamic subtest bcs0: SUCCESS (1.466s)
[72.125754] Starting dynamic subtest: vcs0
[73.877663] Dynamic subtest vcs0: SUCCESS (1.759s)
[73.887664] Starting dynamic subtest: vcs1
[75.861996] Dynamic subtest vcs1: SUCCESS (1.984s)
[75.874385] Starting dynamic subtest: vecs0
[77.844612] Dynamic subtest vecs0: SUCCESS (1.982s)
[77.854671] Starting dynamic subtest: ccs0
[79.302228] Dynamic subtest ccs0: SUCCESS (1.458s)
[79.304601] Subtest basic: SUCCESS (10.057s)
[79.495611] [010/133] (942s left) kms_big_fb (linear-16bpp-rotate-180)
[79.607212] Starting subtest: linear-16bpp-rotate-180
[80.932283] Subtest linear-16bpp-rotate-180: SUCCESS (1.327s)
[81.070808] [011/133] (940s left) kms_psr (fbc-pr-cursor-blt)
[81.174998] Starting subtest: fbc-pr-cursor-blt
[81.178570] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[81.239344] [012/133] (940s left) gem_flink_basic (flink-lifetime)
[81.297818] Starting subtest: flink-lifetime
[81.300700] Subtest flink-lifetime: SUCCESS (0.002s)
[81.391576] [013/133] (940s left) gem_ctx_shared (detached-shared-gtt)
[81.439305] Starting subtest: detached-shared-gtt
[83.594460] Subtest detached-shared-gtt: SUCCESS (2.152s)
[83.928459] [014/133] (938s left) gem_ctx_bad_destroy (invalid-default-ctx)
[83.987399] Starting subtest: invalid-default-ctx
[83.989896] Subtest invalid-default-ctx: SUCCESS (0.000s)
[84.058476] [015/133] (938s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[84.147972] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[91.261719] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.114s)
[91.360190] [016/133] (930s left) kms_psr (fbc-psr-sprite-plane-move)
[91.455697] Starting subtest: fbc-psr-sprite-plane-move
[91.457591] Starting dynamic subtest: eDP-1
[91.461575] Dynamic subtest eDP-1: SKIP (0.000s)
[91.465573] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[91.526757] [017/133] (930s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[91.578181] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[91.580424] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[91.675469] [018/133] (930s left) kms_cursor_crc (cursor-offscreen-256x256)
[91.905779] Starting subtest: cursor-offscreen-256x256
[91.908095] Starting dynamic subtest: pipe-A-eDP-1
[93.853432] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.945s)
[93.883912] Starting dynamic subtest: pipe-D-eDP-1
[96.471876] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.596s)
[96.474269] Subtest cursor-offscreen-256x256: SUCCESS (4.567s)
[97.280600] [019/133] (924s left) perf (invalid-oa-exponent)
[97.401545] Starting subtest: invalid-oa-exponent
[97.414526] Subtest invalid-oa-exponent: SUCCESS (0.014s)
[97.462537] [020/133] (924s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[97.567859] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[101.822900] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.258s)
[102.966170] [021/133] (919s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[103.073853] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[110.623547] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.557s)
[110.744777] [022/133] (911s left) kms_vblank (ts-continuation-dpms-suspend)
[110.849560] Starting subtest: ts-continuation-dpms-suspend
[110.851667] Starting dynamic subtest: pipe-A-eDP-1
[127.746491] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.961s)
[127.760020] Starting dynamic subtest: pipe-D-eDP-1
[145.727847] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.486s)
[145.730148] Subtest ts-continuation-dpms-suspend: SUCCESS (4.450s)
[146.530401] [023/133] (875s left) i915_pm_rc6_residency (rc6-idle)
[146.588395] Starting subtest: rc6-idle
[146.605739] Starting dynamic subtest: gt0-rcs0
[170.975995] Dynamic subtest gt0-rcs0: SUCCESS (24.428s)
[170.987273] Starting dynamic subtest: gt0-bcs0
[195.263460] Dynamic subtest gt0-bcs0: SUCCESS (24.300s)
[195.275051] Starting dynamic subtest: gt0-ccs0
[219.539001] Dynamic subtest gt0-ccs0: SUCCESS (24.286s)
[219.550406] Starting dynamic subtest: gt1-vcs0
[225.713073] Dynamic subtest gt1-vcs0: SUCCESS (6.174s)
[225.725183] Starting dynamic subtest: gt1-vecs0
[231.886988] Dynamic subtest gt1-vecs0: SUCCESS (6.172s)
[231.889901] Subtest rc6-idle: SUCCESS (85.390s)
[231.962864] [024/133] (790s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[232.039174] Starting subtest: map-fixed-invalidate-overlap-busy
[232.043705] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[232.169673] [025/133] (790s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs)
[232.270817] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs
[232.273083] Starting dynamic subtest: pipe-A-eDP-1
[232.295913] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[232.307047] Starting dynamic subtest: pipe-B-eDP-1
[232.328829] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[232.337177] Starting dynamic subtest: pipe-C-eDP-1
[232.359069] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[232.367596] Starting dynamic subtest: pipe-D-eDP-1
[232.390053] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[232.394121] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[234.576169] [026/133] (787s left) api_intel_bb (bb-with-allocator)
[234.631799] Starting subtest: bb-with-allocator
[234.636905] Subtest bb-with-allocator: SUCCESS (0.006s)
[234.759123] [027/133] (787s left) kms_psr (psr-primary-blt)
[234.860011] Starting subtest: psr-primary-blt
[234.862296] Starting dynamic subtest: eDP-1
[236.592179] Dynamic subtest eDP-1: SUCCESS (1.734s)
[236.594504] Subtest psr-primary-blt: SUCCESS (1.735s)
[237.455728] [028/133] (785s left) syncobj_basic (bad-fd-to-handle)
[237.514600] Starting subtest: bad-fd-to-handle
[237.517001] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[237.609557] [029/133] (785s left) vgem_basic (bad-fence)
[237.727039] Starting subtest: bad-fence
[237.729045] Subtest bad-fence: SUCCESS (0.000s)
[237.793201] [FACT vgem_basic (bad-fence)] new: kernel.kmod_is_loaded.vgem: true
[237.796939] [030/133] (784s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[237.862670] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[237.930445] [031/133] (784s left) i915_pm_freq_api (freq-basic-api)
[237.991365] Starting subtest: freq-basic-api
[237.993786] Starting dynamic subtest: gt0
[237.998614] Dynamic subtest gt0: SUCCESS (0.007s)
[238.009020] Starting dynamic subtest: gt1
[238.011259] Dynamic subtest gt1: SUCCESS (0.004s)
[238.013465] Subtest freq-basic-api: SUCCESS (0.014s)
[238.122720] [032/133] (784s left) perf (polling)
[238.215168] Starting subtest: polling
[238.217736] Starting dynamic subtest: 0-rcs0
[248.241513] Dynamic subtest 0-rcs0: SUCCESS (10.030s)
[248.249975] Starting dynamic subtest: 1-vcs1
[258.257830] Dynamic subtest 1-vcs1: SUCCESS (10.020s)
[258.260490] Subtest polling: SUCCESS (20.051s)
[258.305190] [033/133] (764s left) fbdev (info)
[258.343479] Starting subtest: info
[258.345598] Subtest info: SUCCESS (0.000s)
[258.405974] [034/133] (764s left) syncobj_wait (wait-for-submit-snapshot)
[258.452184] Starting subtest: wait-for-submit-snapshot
[258.533451] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[258.609145] [035/133] (764s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[258.714763] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[258.717060] Starting dynamic subtest: pipe-A-eDP-1
[258.774751] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[258.783867] Starting dynamic subtest: pipe-B-eDP-1
[258.839049] Dynamic subtest pipe-B-eDP-1: SKIP (0.005s)
[258.848644] Starting dynamic subtest: pipe-C-eDP-1
[258.904307] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[258.913761] Starting dynamic subtest: pipe-D-eDP-1
[258.970650] Dynamic subtest pipe-D-eDP-1: SKIP (0.003s)
[258.974856] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.020s)
[260.995159] [036/133] (761s left) kms_flip (2x-wf_vblank-ts-check)
[261.098264] Starting subtest: 2x-wf_vblank-ts-check
[261.109871] Subtest 2x-wf_vblank-ts-check: SKIP (0.012s)
[261.188358] [037/133] (761s left) kms_psr (pr-cursor-plane-move)
[261.279453] Starting subtest: pr-cursor-plane-move
[261.283477] Subtest pr-cursor-plane-move: SKIP (0.000s)
[261.350584] [038/133] (761s left) gem_create (create-ext-placement-sanity-check)
[261.516643] Starting subtest: create-ext-placement-sanity-check
[261.523010] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[261.615705] [039/133] (761s left) perf (whitelisted-registers-userspace-config)
[261.709973] Starting subtest: whitelisted-registers-userspace-config
[261.712376] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[261.757688] [040/133] (761s left) gem_exec_schedule (noreorder-corked)
[261.934748] Starting subtest: noreorder-corked
[261.937433] Starting dynamic subtest: rcs0
[263.071208] Dynamic subtest rcs0: SUCCESS (1.138s)
[263.082874] Starting dynamic subtest: vcs0
[264.303692] Dynamic subtest vcs0: SUCCESS (1.231s)
[264.315916] Starting dynamic subtest: vcs1
[266.348963] Dynamic subtest vcs1: SUCCESS (2.044s)
[266.360584] Starting dynamic subtest: vecs0
[268.335994] Dynamic subtest vecs0: SUCCESS (1.986s)
[268.349308] Starting dynamic subtest: ccs0
[269.483467] Dynamic subtest ccs0: SUCCESS (1.148s)
[269.486444] Subtest noreorder-corked: SUCCESS (7.554s)
[269.685371] [041/133] (753s left) kms_plane (pixel-format-4-tiled-modifier)
[269.791794] Starting subtest: pixel-format-4-tiled-modifier
[269.819583] Starting dynamic subtest: pipe-A-plane-0
[270.485839] Dynamic subtest pipe-A-plane-0: SUCCESS (0.667s)
[270.504751] Starting dynamic subtest: pipe-A-plane-3
[270.907373] Dynamic subtest pipe-A-plane-3: SUCCESS (0.422s)
[270.915672] Starting dynamic subtest: pipe-A-plane-5
[270.918916] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[271.975245] Starting dynamic subtest: pipe-B-plane-0
[272.622835] Dynamic subtest pipe-B-plane-0: SUCCESS (0.655s)
[272.631607] Starting dynamic subtest: pipe-B-plane-3
[273.022226] Dynamic subtest pipe-B-plane-3: SUCCESS (0.398s)
[273.031296] Starting dynamic subtest: pipe-B-plane-5
[273.037683] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[273.347197] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.557s)
[274.154073] [042/133] (748s left) kms_getfb (getfb2-handle-protection)
[274.258715] Starting subtest: getfb2-handle-protection
[274.273558] Subtest getfb2-handle-protection: SUCCESS (0.017s)
[274.394388] [043/133] (748s left) gem_exec_params (batch-first)
[274.446955] Starting subtest: batch-first
[274.449007] Subtest batch-first: SUCCESS (0.005s)
[274.560614] [044/133] (748s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[274.652016] Starting subtest: x-tiled-64bpp-rotate-270
[274.671444] Subtest x-tiled-64bpp-rotate-270: SKIP (0.019s)
[274.789102] [045/133] (748s left) gem_tiled_pread_basic (basic)
[274.848553] Subtest basic: SKIP (0.000s)
[274.909117] [046/133] (748s left) kms_pm_lpsp (kms-lpsp)
[276.009315] Starting subtest: kms-lpsp
[276.011782] Starting dynamic subtest: pipe-A-eDP-1
[276.157069] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.149s)
[276.456077] Subtest kms-lpsp: SUCCESS (0.447s)
[277.295899] [047/133] (746s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[277.390540] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[277.392936] Starting dynamic subtest: pipe-A-default-mode
[278.548679] Dynamic subtest pipe-A-default-mode: SKIP (1.158s)
[278.553082] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.159s)
[278.704502] [048/133] (744s left) syncobj_eventfd (invalid-bad-flags)
[278.762497] Starting subtest: invalid-bad-flags
[278.764778] Subtest invalid-bad-flags: SUCCESS (0.000s)
[278.855200] [049/133] (744s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[278.953194] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[279.336851] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[282.096324] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.760s)
[282.105129] Starting dynamic subtest: pipe-A-eDP-1-4
[284.270240] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[284.287262] Starting dynamic subtest: pipe-A-eDP-1-4
[286.279201] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[286.291284] Starting dynamic subtest: pipe-A-eDP-1-x
[288.456751] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[288.825271] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[291.614413] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.797s)
[291.624357] Starting dynamic subtest: pipe-B-eDP-1-4
[293.798927] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[293.808898] Starting dynamic subtest: pipe-B-eDP-1-4
[295.807643] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[295.816119] Starting dynamic subtest: pipe-B-eDP-1-x
[298.032038] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.224s)
[298.393350] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[301.144966] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.760s)
[301.157061] Starting dynamic subtest: pipe-C-eDP-1-4
[303.307468] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.163s)
[303.319074] Starting dynamic subtest: pipe-C-eDP-1-4
[305.315939] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[305.325060] Starting dynamic subtest: pipe-C-eDP-1-x
[307.482303] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.167s)
[307.870369] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[310.676354] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.813s)
[310.685484] Starting dynamic subtest: pipe-D-eDP-1-4
[312.849988] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[312.861378] Starting dynamic subtest: pipe-D-eDP-1-4
[314.858956] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[314.870630] Starting dynamic subtest: pipe-D-eDP-1-x
[317.025508] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.167s)
[317.027723] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.092s)
[318.260599] [050/133] (705s left) gem_basic (create-close)
[318.317704] Starting subtest: create-close
[318.321805] Subtest create-close: SUCCESS (0.000s)
[318.414799] [051/133] (705s left) kms_content_protection (dp-mst-type-0)
[318.546123] Starting subtest: dp-mst-type-0
[318.550673] Subtest dp-mst-type-0: SKIP (0.000s)
[318.672526] [052/133] (704s left) gem_unfence_active_buffers
[318.825441] [053/133] (704s left) gem_exec_balancer (parallel-balancer)
[319.084778] Starting subtest: parallel-balancer
[319.964359] Subtest parallel-balancer: SUCCESS (0.881s)
[320.061941] [054/133] (703s left) kms_chamelium_frames (hdmi-crc-single)
[320.440851] Subtest hdmi-crc-single: SKIP (0.000s)
[321.269594] [055/133] (702s left) gem_render_copy_redux (flink)
[321.330882] Starting subtest: flink
[322.081642] Subtest flink: SUCCESS (0.752s)
[322.202259] [056/133] (701s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[322.248740] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[322.250558] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[322.359682] [057/133] (701s left) kms_psr (fbc-psr2-basic)
[322.460778] Starting subtest: fbc-psr2-basic
[322.462715] Starting dynamic subtest: eDP-1
[322.466308] Dynamic subtest eDP-1: SKIP (0.000s)
[322.473049] Subtest fbc-psr2-basic: SKIP (0.001s)
[322.548321] [058/133] (701s left) gem_mmap_wc (write-gtt-read-wc)
[322.602139] Subtest write-gtt-read-wc: SKIP (0.000s)
[322.671829] [059/133] (701s left) gem_mmap (bad-size)
[322.730991] Subtest bad-size: SKIP (0.000s)
[322.787213] [060/133] (701s left) kms_vblank (query-busy)
[322.887483] Starting subtest: query-busy
[322.889204] Starting dynamic subtest: pipe-A-eDP-1
[324.766178] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[324.780868] Starting dynamic subtest: pipe-D-eDP-1
[327.346112] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.579s)
[327.347993] Subtest query-busy: SUCCESS (4.460s)
[328.157782] [061/133] (695s left) kms_rotation_crc (sprite-rotation-180)
[328.250185] Starting subtest: sprite-rotation-180
[335.098907] Subtest sprite-rotation-180: SUCCESS (6.855s)
[336.308393] [062/133] (687s left) dmabuf_sync_file (export-multiwait)
[336.363840] Starting subtest: export-multiwait
[336.365788] Subtest export-multiwait: SUCCESS (0.001s)
[336.424369] [063/133] (687s left) kms_draw_crc (draw-method-pwrite)
[336.508464] Starting subtest: draw-method-pwrite
[336.510272] Starting dynamic subtest: xrgb8888-untiled
[337.084061] Dynamic subtest xrgb8888-untiled: SUCCESS (0.577s)
[337.093061] Starting dynamic subtest: rgb565-untiled
[337.527022] Dynamic subtest rgb565-untiled: SUCCESS (0.442s)
[337.536077] Starting dynamic subtest: xrgb2101010-untiled
[338.060555] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.533s)
[338.069618] Starting dynamic subtest: xbgr16161616f-untiled
[338.793289] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.732s)
[338.802115] Starting dynamic subtest: xrgb8888-xtiled
[340.492513] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.699s)
[340.502981] Starting dynamic subtest: rgb565-xtiled
[341.525256] Dynamic subtest rgb565-xtiled: SUCCESS (1.032s)
[341.534436] Starting dynamic subtest: xrgb2101010-xtiled
[343.357890] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.833s)
[343.367110] Starting dynamic subtest: xbgr16161616f-xtiled
[346.522723] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.166s)
[346.534396] Starting dynamic subtest: xrgb8888-4tiled
[347.566338] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.044s)
[347.575169] Starting dynamic subtest: rgb565-4tiled
[348.121842] Dynamic subtest rgb565-4tiled: SUCCESS (0.555s)
[348.130415] Starting dynamic subtest: xrgb2101010-4tiled
[349.210341] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.088s)
[349.219631] Starting dynamic subtest: xbgr16161616f-4tiled
[352.219583] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.009s)
[352.221939] Subtest draw-method-pwrite: SUCCESS (15.722s)
[352.324646] [064/133] (671s left) syncobj_timeline (signal-array)
[352.390221] Starting subtest: signal-array
[352.392549] Subtest signal-array: SUCCESS (0.000s)
[352.489616] [065/133] (671s left) vgem_slow (nohang)
[352.531514] Starting subtest: nohang
[362.830747] Subtest nohang: SUCCESS (10.306s)
[362.912993] [066/133] (661s left) vgem_basic (debugfs)
[362.972261] Starting subtest: debugfs
[362.974557] Subtest debugfs: SUCCESS (0.000s)
[363.043664] [067/133] (661s left) gem_eio (in-flight-contexts-10ms)
[363.278353] Starting subtest: in-flight-contexts-10ms
[377.345050] Subtest in-flight-contexts-10ms: SUCCESS (14.073s)
[377.507846] [068/133] (646s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[377.617908] Starting subtest: 4-tiled-32bpp-rotate-270
[377.637118] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[377.753881] [069/133] (646s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[377.848054] Starting subtest: psr-modesetfrombusy
[384.053029] Subtest psr-modesetfrombusy: SUCCESS (6.210s)
[384.192219] [070/133] (640s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[384.283839] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[384.285840] Starting dynamic subtest: pipe-A-eDP-1
[384.304883] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[384.315508] Starting dynamic subtest: pipe-B-eDP-1
[384.333805] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[384.342661] Starting dynamic subtest: pipe-C-eDP-1
[384.360585] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[384.369035] Starting dynamic subtest: pipe-D-eDP-1
[384.387278] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[384.390724] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[386.517152] [071/133] (637s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[386.611892] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[386.614263] Starting dynamic subtest: pipe-A-eDP-1
[386.635132] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[386.645510] Starting dynamic subtest: pipe-B-eDP-1
[386.666007] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[386.674968] Starting dynamic subtest: pipe-C-eDP-1
[386.695171] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[386.703544] Starting dynamic subtest: pipe-D-eDP-1
[386.722735] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[386.724601] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.022s)
[388.971535] [072/133] (635s left) gen9_exec_parse (bb-large)
[389.032606] Subtest bb-large: SKIP (0.000s)
[389.103588] [073/133] (635s left) kms_vblank (crtc-id)
[389.197118] Starting subtest: crtc-id
[389.199409] Starting dynamic subtest: pipe-A-eDP-1
[389.650333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[389.664833] Starting dynamic subtest: pipe-D-eDP-1
[390.798035] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.146s)
[390.800367] Subtest crtc-id: SUCCESS (1.603s)
[391.646940] [074/133] (632s left) kms_cursor_edge_walk (64x64-right-edge)
[391.740547] Starting subtest: 64x64-right-edge
[391.742831] Starting dynamic subtest: pipe-A-eDP-1
[395.202023] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[395.213366] Starting dynamic subtest: pipe-D-eDP-1
[398.325841] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[398.327745] Subtest 64x64-right-edge: SUCCESS (6.588s)
[399.136664] [075/133] (625s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[399.238965] Starting subtest: fbc-rgb101010-draw-blt
[403.888457] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.618s)
[405.091679] [076/133] (619s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[405.182228] Starting subtest: plane-lut1d-ctm3x4-lut1d
[405.352590] Starting dynamic subtest: pipe-A-plane-0
[405.521094] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s)
[405.531524] Starting dynamic subtest: pipe-A-plane-1
[405.667187] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[405.677074] Starting dynamic subtest: pipe-A-plane-2
[405.823831] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[406.988390] Starting dynamic subtest: pipe-B-plane-0
[407.126581] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[407.135709] Starting dynamic subtest: pipe-B-plane-1
[407.261146] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[407.270350] Starting dynamic subtest: pipe-B-plane-2
[407.451647] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[408.660114] Starting dynamic subtest: pipe-C-plane-0
[408.808718] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[408.818962] Starting dynamic subtest: pipe-C-plane-1
[408.954049] Dynamic subtest pipe-C-plane-1: SUCCESS (0.143s)
[408.963317] Starting dynamic subtest: pipe-C-plane-2
[409.099613] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[410.282125] Starting dynamic subtest: pipe-D-plane-0
[410.452879] Dynamic subtest pipe-D-plane-0: SUCCESS (0.177s)
[410.462598] Starting dynamic subtest: pipe-D-plane-1
[410.620562] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[410.630246] Starting dynamic subtest: pipe-D-plane-2
[410.776884] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[411.065041] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.837s)
[411.872831] [077/133] (612s left) gem_exec_fence (basic-await)
[412.059328] Starting subtest: basic-await
[412.062149] Starting dynamic subtest: rcs0
[412.134475] Dynamic subtest rcs0: SUCCESS (0.075s)
[412.146953] Starting dynamic subtest: bcs0
[412.205149] Dynamic subtest bcs0: SUCCESS (0.070s)
[412.217345] Starting dynamic subtest: vcs0
[412.278290] Dynamic subtest vcs0: SUCCESS (0.071s)
[412.293117] Starting dynamic subtest: vcs1
[412.353212] Dynamic subtest vcs1: SUCCESS (0.073s)
[412.365756] Starting dynamic subtest: vecs0
[412.425504] Dynamic subtest vecs0: SUCCESS (0.071s)
[412.436698] Starting dynamic subtest: ccs0
[412.498711] Dynamic subtest ccs0: SUCCESS (0.071s)
[412.501380] Subtest basic-await: SUCCESS (0.439s)
[414.400980] [078/133] (610s left) gem_eio (in-flight-contexts-immediate)
[414.649894] Starting subtest: in-flight-contexts-immediate
[428.364995] Subtest in-flight-contexts-immediate: SUCCESS (13.689s)
[428.523813] [079/133] (596s left) i915_pm_rps (thresholds-idle)
[428.576661] Starting subtest: thresholds-idle
[428.581067] Subtest thresholds-idle: SKIP (0.000s)
[428.669137] [080/133] (596s left) kms_cdclk (mode-transition)
[428.749708] Starting subtest: mode-transition
[428.752018] Starting dynamic subtest: pipe-A-eDP-1
[429.042539] Dynamic subtest pipe-A-eDP-1: SKIP (0.291s)
[429.052703] Starting dynamic subtest: pipe-B-eDP-1
[429.057288] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[429.067070] Starting dynamic subtest: pipe-C-eDP-1
[429.071728] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[429.081149] Starting dynamic subtest: pipe-D-eDP-1
[429.085519] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[429.089836] Subtest mode-transition: SKIP (0.299s)
[429.849557] [081/133] (594s left) gem_exec_schedule (u-lateslice)
[429.921627] Starting subtest: u-lateslice
[429.923823] Starting dynamic subtest: rcs0
[429.932446] Dynamic subtest rcs0: SUCCESS (0.021s)
[429.945087] Starting dynamic subtest: bcs0
[429.950673] Dynamic subtest bcs0: SUCCESS (0.017s)
[429.960157] Starting dynamic subtest: vcs0
[429.968564] Dynamic subtest vcs0: SUCCESS (0.017s)
[429.977931] Starting dynamic subtest: vcs1
[429.985981] Dynamic subtest vcs1: SUCCESS (0.017s)
[429.995948] Starting dynamic subtest: vecs0
[430.003797] Dynamic subtest vecs0: SUCCESS (0.017s)
[430.012543] Starting dynamic subtest: ccs0
[430.024197] Dynamic subtest ccs0: SUCCESS (0.019s)
[430.026510] Subtest u-lateslice: SUCCESS (0.113s)
[431.437576] [082/133] (593s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[431.529238] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[435.469069] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.942s)
[436.658079] [083/133] (588s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[437.047475] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[437.867805] [084/133] (587s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[437.967671] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[437.971733] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[438.039645] [085/133] (586s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[438.141812] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[438.144055] Starting dynamic subtest: pipe-A
[438.513449] Dynamic subtest pipe-A: SUCCESS (0.375s)
[438.523523] Starting dynamic subtest: pipe-B
[439.920440] Dynamic subtest pipe-B: SUCCESS (1.406s)
[439.932278] Starting dynamic subtest: pipe-C
[441.323842] Dynamic subtest pipe-C: SUCCESS (1.402s)
[441.334135] Starting dynamic subtest: pipe-D
[442.780951] Dynamic subtest pipe-D: SUCCESS (1.454s)
[442.783265] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.642s)
[443.896926] [086/133] (581s left) kms_cursor_legacy (forked-move)
[444.018886] Starting subtest: forked-move
[444.021127] Starting dynamic subtest: pipe-A
[449.475223] Dynamic subtest pipe-A: SUCCESS (5.453s)
[449.497932] Starting dynamic subtest: pipe-B
[454.937035] Dynamic subtest pipe-B: SUCCESS (5.458s)
[454.949015] Starting dynamic subtest: pipe-C
[460.408989] Dynamic subtest pipe-C: SUCCESS (5.468s)
[460.420531] Starting dynamic subtest: pipe-D
[465.878231] Dynamic subtest pipe-D: SUCCESS (5.464s)
[465.890342] Starting dynamic subtest: all-pipes
[471.403711] Dynamic subtest all-pipes: SUCCESS (5.521s)
[471.406361] Subtest forked-move: SUCCESS (27.377s)
[471.571107] [087/133] (553s left) kms_pm_rpm (modeset-non-lpsp)
[473.527059] Starting subtest: modeset-non-lpsp
[474.686981] Subtest modeset-non-lpsp: SKIP (1.159s)
[475.074173] [088/133] (549s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[475.175534] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[475.177845] Starting dynamic subtest: pipe-A
[475.521534] Dynamic subtest pipe-A: SUCCESS (0.349s)
[475.533550] Starting dynamic subtest: pipe-B
[476.957163] Dynamic subtest pipe-B: SUCCESS (1.434s)
[476.967206] Starting dynamic subtest: pipe-C
[478.391911] Dynamic subtest pipe-C: SUCCESS (1.433s)
[478.402180] Starting dynamic subtest: pipe-D
[479.832605] Dynamic subtest pipe-D: SUCCESS (1.439s)
[479.835023] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.659s)
[481.002287] [089/133] (544s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[481.109088] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[481.121459] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.010s)
[481.199566] [090/133] (543s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[481.303924] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[481.308213] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[481.413391] [091/133] (543s left) kms_properties (colorop-properties-legacy)
[481.509983] Starting subtest: colorop-properties-legacy
[481.512717] Starting dynamic subtest: pipe-A-eDP-1
[481.842601] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.333s)
[481.852696] Starting dynamic subtest: pipe-B-eDP-1
[482.867946] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.025s)
[482.878292] Starting dynamic subtest: pipe-C-eDP-1
[483.916497] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.046s)
[483.926772] Starting dynamic subtest: pipe-D-eDP-1
[484.957331] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.038s)
[484.959265] Subtest colorop-properties-legacy: SUCCESS (3.448s)
[485.759283] [092/133] (539s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[485.860978] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[485.865465] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[485.963474] [093/133] (539s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[486.058969] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[486.061330] Starting dynamic subtest: A-eDP1
[489.677983] Dynamic subtest A-eDP1: SUCCESS (3.615s)
[489.688853] Starting dynamic subtest: B-eDP1
[492.869346] Dynamic subtest B-eDP1: SUCCESS (3.187s)
[492.879186] Starting dynamic subtest: C-eDP1
[495.958963] Dynamic subtest C-eDP1: SUCCESS (3.088s)
[495.968829] Starting dynamic subtest: D-eDP1
[499.168507] Dynamic subtest D-eDP1: SUCCESS (3.208s)
[499.170919] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.106s)
[499.969372] [094/133] (525s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[500.071756] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[500.076263] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[500.152896] [095/133] (525s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[500.387410] Starting subtest: cursor-rapid-movement-128x42
[500.438883] Subtest cursor-rapid-movement-128x42: SKIP (0.045s)
[500.648931] [096/133] (524s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[500.748432] Starting subtest: yf-tiled-32bpp-rotate-0
[500.752805] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[500.847342] [097/133] (524s left) gem_exec_whisper (basic-queues)
[500.913671] Starting subtest: basic-queues
[507.375683] Subtest basic-queues: SUCCESS (6.458s)
[507.565032] [098/133] (517s left) kms_setmode (basic-clone-single-crtc)
[507.616270] Starting subtest: basic-clone-single-crtc
[507.622649] Subtest basic-clone-single-crtc: SKIP (0.000s)
[507.711306] [099/133] (517s left) kms_properties (get_properties-sanity-atomic)
[507.800146] Starting subtest: get_properties-sanity-atomic
[507.802301] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[507.890950] [100/133] (517s left) kms_joiner (invalid-modeset-ultra-joiner)
[507.983954] Starting subtest: invalid-modeset-ultra-joiner
[507.987989] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[508.057328] [101/133] (517s left) syncobj_timeline (etime-single-wait-submitted)
[508.119719] Starting subtest: etime-single-wait-submitted
[508.220974] Subtest etime-single-wait-submitted: SUCCESS (0.101s)
[508.298400] [102/133] (517s left) kms_plane_multiple (tiling-x)
[508.395433] Starting subtest: tiling-x
[508.397724] Starting dynamic subtest: pipe-A-eDP-1
[512.210304] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.816s)
[512.220209] Starting dynamic subtest: pipe-B-eDP-1
[516.552594] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.339s)
[516.562751] Starting dynamic subtest: pipe-C-eDP-1
[520.890182] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.336s)
[520.900684] Starting dynamic subtest: pipe-D-eDP-1
[525.234801] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.340s)
[525.237141] Subtest tiling-x: SUCCESS (16.836s)
[526.049190] [103/133] (499s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[526.155183] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[526.157564] Starting dynamic subtest: A-eDP1
[529.769780] Dynamic subtest A-eDP1: SUCCESS (3.610s)
[529.780521] Starting dynamic subtest: B-eDP1
[532.960109] Dynamic subtest B-eDP1: SUCCESS (3.190s)
[532.970634] Starting dynamic subtest: C-eDP1
[536.049805] Dynamic subtest C-eDP1: SUCCESS (3.086s)
[536.059938] Starting dynamic subtest: D-eDP1
[539.267699] Dynamic subtest D-eDP1: SUCCESS (3.217s)
[539.270125] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.110s)
[540.070350] [104/133] (485s left) gem_mmap_gtt (basic-small-copy)
[540.134481] Subtest basic-small-copy: SKIP (0.000s)
[540.198369] [105/133] (485s left) kms_atomic (atomic-plane-damage)
[540.291269] Starting subtest: atomic-plane-damage
[540.344001] Starting dynamic subtest: pipe-A-eDP-1
[540.880326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.536s)
[540.882295] Subtest atomic-plane-damage: SUCCESS (0.588s)
[541.702065] [106/133] (484s left) kms_flip (2x-flip-vs-panning)
[541.803366] Starting subtest: 2x-flip-vs-panning
[541.812174] Subtest 2x-flip-vs-panning: SKIP (0.010s)
[541.931307] [107/133] (483s left) gem_linear_blits (interruptible)
[542.390495] Starting subtest: interruptible
[546.884044] Subtest interruptible: SUCCESS (4.491s)
[547.071583] [108/133] (478s left) gem_ctx_isolation (dirty-switch)
[547.172049] Starting subtest: dirty-switch
[547.174010] Starting dynamic subtest: rcs0
[547.404187] Dynamic subtest rcs0: SUCCESS (0.235s)
[547.416523] Starting dynamic subtest: bcs0
[547.655568] Dynamic subtest bcs0: SUCCESS (0.250s)
[547.667716] Starting dynamic subtest: vcs0
[547.892437] Dynamic subtest vcs0: SUCCESS (0.236s)
[547.904493] Starting dynamic subtest: vcs1
[549.560710] Dynamic subtest vcs1: SUCCESS (1.667s)
[549.573414] Starting dynamic subtest: vecs0
[551.570747] Dynamic subtest vecs0: SUCCESS (2.008s)
[551.586661] Starting dynamic subtest: ccs0
[553.541688] Dynamic subtest ccs0: SUCCESS (1.968s)
[553.544079] Subtest dirty-switch: SUCCESS (6.371s)
[553.775836] [109/133] (472s left) prime_self_import (basic-with_fd_dup)
[553.815712] Starting subtest: basic-with_fd_dup
[553.844098] Subtest basic-with_fd_dup: SUCCESS (0.027s)
[553.965023] [110/133] (472s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[554.090056] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[554.262050] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.175s)
[554.391380] [111/133] (471s left) api_intel_bb (reset-flags)
[554.452871] Starting subtest: reset-flags
[554.460721] Subtest reset-flags: SUCCESS (0.010s)
[554.585309] [112/133] (471s left) kms_psr (psr-cursor-mmap-gtt)
[554.685753] Starting subtest: psr-cursor-mmap-gtt
[554.687632] Starting dynamic subtest: eDP-1
[556.396541] Dynamic subtest eDP-1: SUCCESS (1.711s)
[556.398963] Subtest psr-cursor-mmap-gtt: SUCCESS (1.712s)
[557.258594] [113/133] (468s left) kms_invalid_mode (clock-too-high)
[557.357468] Starting subtest: clock-too-high
[557.359603] Starting dynamic subtest: pipe-A-eDP-1
[557.363578] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[557.373724] Starting dynamic subtest: pipe-B-eDP-1
[557.378056] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[557.388044] Starting dynamic subtest: pipe-C-eDP-1
[557.392074] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[557.401316] Starting dynamic subtest: pipe-D-eDP-1
[557.405180] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[557.409088] Subtest clock-too-high: SKIP (0.004s)
[557.445867] [114/133] (468s left) gem_exec_schedule (fairslice)
[557.542466] Starting subtest: fairslice
[557.544279] Starting dynamic subtest: rcs0
[559.565597] Dynamic subtest rcs0: SUCCESS (2.023s)
[559.586548] Starting dynamic subtest: bcs0
[561.582867] Dynamic subtest bcs0: SUCCESS (2.017s)
[561.592628] Starting dynamic subtest: vcs0
[563.605296] Dynamic subtest vcs0: SUCCESS (2.022s)
[563.615206] Starting dynamic subtest: vcs1
[565.625161] Dynamic subtest vcs1: SUCCESS (2.019s)
[565.636330] Starting dynamic subtest: vecs0
[567.648953] Dynamic subtest vecs0: SUCCESS (2.019s)
[567.659215] Starting dynamic subtest: ccs0
[569.673458] Dynamic subtest ccs0: SUCCESS (2.025s)
[569.675624] Subtest fairslice: SUCCESS (12.130s)
[569.972628] [115/133] (456s left) gem_ctx_persistence (processes)
[570.042232] Starting subtest: processes
[571.327096] Subtest processes: SUCCESS (1.284s)
[571.412300] [116/133] (454s left) perf_pmu (busy-idle-check-all)
[571.457263] Starting subtest: busy-idle-check-all
[571.459470] Starting dynamic subtest: rcs0
[572.132476] Dynamic subtest rcs0: SUCCESS (0.677s)
[572.145679] Starting dynamic subtest: bcs0
[572.788664] Dynamic subtest bcs0: SUCCESS (0.654s)
[572.801579] Starting dynamic subtest: vcs0
[574.320939] Dynamic subtest vcs0: SUCCESS (1.528s)
[574.332685] Starting dynamic subtest: vcs1
[576.304912] Dynamic subtest vcs1: SUCCESS (1.981s)
[576.317485] Starting dynamic subtest: vecs0
[578.353664] Dynamic subtest vecs0: SUCCESS (2.045s)
[578.365972] Starting dynamic subtest: ccs0
[579.007773] Dynamic subtest ccs0: SUCCESS (0.652s)
[579.010190] Subtest busy-idle-check-all: SUCCESS (7.552s)
[579.110506] [117/133] (447s left) syncobj_wait (multi-wait-for-submit-submitted)
[579.182981] Starting subtest: multi-wait-for-submit-submitted
[579.284091] Subtest multi-wait-for-submit-submitted: SUCCESS (0.102s)
[579.365161] [118/133] (446s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[579.468639] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[579.472583] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[579.557266] [119/133] (446s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[579.647002] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[586.449919] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.804s)
[586.589567] [120/133] (439s left) gem_lmem_swapping (massive)
[586.631943] Subtest massive: SKIP (0.000s)
[586.671135] [121/133] (439s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[586.760678] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[593.501906] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.742s)
[593.603167] [122/133] (432s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[593.662016] Starting subtest: verify-pxp-stale-buf-optout-execution
[594.722248] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.058s)
[594.852539] [123/133] (431s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[594.917647] Starting subtest: yf-tiled-to-vebox-yf-tiled
[594.922269] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[595.115185] [124/133] (431s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[595.211969] Starting subtest: fbcpsr-tiling-linear
[601.794737] Subtest fbcpsr-tiling-linear: SUCCESS (6.583s)
[601.931343] [125/133] (424s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[602.030068] Starting subtest: crc-primary-basic-yf-tiled-ccs
[602.032167] Starting dynamic subtest: pipe-A-eDP-1
[602.054043] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[602.064925] Starting dynamic subtest: pipe-B-eDP-1
[602.085487] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[602.094842] Starting dynamic subtest: pipe-C-eDP-1
[602.116414] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[602.126477] Starting dynamic subtest: pipe-D-eDP-1
[602.149895] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[602.154012] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[604.208092] [126/133] (422s left) prime_busy (hang)
[604.271066] Starting subtest: hang
[604.273489] Starting dynamic subtest: rcs0
[630.852471] Dynamic subtest rcs0: SUCCESS (26.576s)
[630.861897] Starting dynamic subtest: bcs0
[638.014258] Dynamic subtest bcs0: SUCCESS (7.168s)
[638.023502] Starting dynamic subtest: vcs0
[644.992451] Dynamic subtest vcs0: SUCCESS (6.983s)
[645.004049] Starting dynamic subtest: vcs1
[651.963839] Dynamic subtest vcs1: SUCCESS (6.976s)
[651.975703] Starting dynamic subtest: vecs0
[659.001459] Dynamic subtest vecs0: SUCCESS (7.038s)
[659.011003] Starting dynamic subtest: ccs0
[672.848182] Dynamic subtest ccs0: SUCCESS (13.850s)
[672.850580] Subtest hang: SUCCESS (68.601s)
[672.968914] [127/133] (353s left) i915_drm_fdinfo (virtual-busy-hang)
[673.012430] Starting subtest: virtual-busy-hang
[673.016890] Subtest virtual-busy-hang: SKIP (0.000s)
[673.087740] [128/133] (353s left) gem_exec_fence (syncobj-repeat)
[673.602338] Starting subtest: syncobj-repeat
[673.784225] Subtest syncobj-repeat: SUCCESS (0.182s)
[675.408504] [129/133] (351s left) gem_fence_thrash (bo-copy)
[675.443869] Starting subtest: bo-copy
[675.471479] Subtest bo-copy: SKIP (0.024s)
[675.532805] [130/133] (351s left) gem_softpin (reverse)
[675.598702] Starting subtest: reverse
[675.606453] Subtest reverse: SUCCESS (0.008s)
[675.738841] [131/133] (351s left) perf_pmu (busy-accuracy-98)
[675.778389] Starting subtest: busy-accuracy-98
[675.780733] Starting dynamic subtest: rcs0
[678.828154] Dynamic subtest rcs0: SUCCESS (3.049s)
[678.839200] Starting dynamic subtest: bcs0
[681.886631] Dynamic subtest bcs0: SUCCESS (3.057s)
[681.897553] Starting dynamic subtest: vcs0
[684.931642] Dynamic subtest vcs0: SUCCESS (3.043s)
[684.942664] Starting dynamic subtest: vcs1
[687.976258] Dynamic subtest vcs1: SUCCESS (3.042s)
[687.988562] Starting dynamic subtest: vecs0
[691.021592] Dynamic subtest vecs0: SUCCESS (3.043s)
[691.034060] Starting dynamic subtest: ccs0
[694.077242] Dynamic subtest ccs0: SUCCESS (3.053s)
[694.079800] Subtest busy-accuracy-98: SUCCESS (18.301s)
[694.194478] [132/133] (332s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[694.313406] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[694.317861] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[694.414320] [133/133] (332s left) gem_exec_params (no-blt)
[694.489116] Starting subtest: no-blt
[694.494003] Subtest no-blt: SUCCESS (0.004s)
[694.616126] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: gem_mmap_gtt
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: gem_pwrite_snooped
results: parsing output: 6/ for test: kms_pipe_crc_basic
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_exec_parallel
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: gem_flink_basic
results: parsing output: 12/ for test: gem_ctx_shared
results: parsing output: 13/ for test: gem_ctx_bad_destroy
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: kms_cursor_crc
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_vblank
results: parsing output: 22/ for test: i915_pm_rc6_residency
results: parsing output: 23/ for test: gem_userptr_blits
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: api_intel_bb
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: syncobj_basic
results: parsing output: 28/ for test: vgem_basic
results: parsing output: 29/ for test: kms_pipe_b_c_ivb
results: parsing output: 30/ for test: i915_pm_freq_api
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: fbdev
results: parsing output: 33/ for test: syncobj_wait
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: gem_create
results: parsing output: 38/ for test: perf
results: parsing output: 39/ for test: gem_exec_schedule
results: parsing output: 40/ for test: kms_plane
results: parsing output: 41/ for test: kms_getfb
results: parsing output: 42/ for test: gem_exec_params
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_tiled_pread_basic
results: parsing output: 45/ for test: kms_pm_lpsp
results: parsing output: 46/ for test: kms_flip_scaled_crc
results: parsing output: 47/ for test: syncobj_eventfd
results: parsing output: 48/ for test: kms_async_flips
results: parsing output: 49/ for test: gem_basic
results: parsing output: 50/ for test: kms_content_protection
results: parsing output: 51/ for test: gem_unfence_active_buffers
results: parsing output: 52/ for test: gem_exec_balancer
results: parsing output: 53/ for test: kms_chamelium_frames
results: parsing output: 54/ for test: gem_render_copy_redux
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: gem_mmap_wc
results: parsing output: 58/ for test: gem_mmap
results: parsing output: 59/ for test: kms_vblank
results: parsing output: 60/ for test: kms_rotation_crc
results: parsing output: 61/ for test: dmabuf_sync_file
results: parsing output: 62/ for test: kms_draw_crc
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: vgem_slow
results: parsing output: 65/ for test: vgem_basic
results: parsing output: 66/ for test: gem_eio
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: gen9_exec_parse
results: parsing output: 72/ for test: kms_vblank
results: parsing output: 73/ for test: kms_cursor_edge_walk
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_color_pipeline
results: parsing output: 76/ for test: gem_exec_fence
results: parsing output: 77/ for test: gem_eio
results: parsing output: 78/ for test: i915_pm_rps
results: parsing output: 79/ for test: kms_cdclk
results: parsing output: 80/ for test: gem_exec_schedule
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_chamelium_hpd
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: kms_plane_scaling
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: kms_pm_rpm
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_properties
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: gem_exec_whisper
results: parsing output: 97/ for test: kms_setmode
results: parsing output: 98/ for test: kms_properties
results: parsing output: 99/ for test: kms_joiner
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_plane_multiple
results: parsing output: 102/ for test: kms_flip
results: parsing output: 103/ for test: gem_mmap_gtt
results: parsing output: 104/ for test: kms_atomic
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: gem_linear_blits
results: parsing output: 107/ for test: gem_ctx_isolation
results: parsing output: 108/ for test: prime_self_import
results: parsing output: 109/ for test: gem_pxp
results: parsing output: 110/ for test: api_intel_bb
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_invalid_mode
results: parsing output: 113/ for test: gem_exec_schedule
results: parsing output: 114/ for test: gem_ctx_persistence
results: parsing output: 115/ for test: perf_pmu
results: parsing output: 116/ for test: syncobj_wait
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_lmem_swapping
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_pxp
results: parsing output: 122/ for test: gem_render_copy
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_ccs
results: parsing output: 125/ for test: prime_busy
results: parsing output: 126/ for test: i915_drm_fdinfo
results: parsing output: 127/ for test: gem_exec_fence
results: parsing output: 128/ for test: gem_fence_thrash
results: parsing output: 129/ for test: gem_softpin
results: parsing output: 130/ for test: perf_pmu
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_params
Done.