[57.744395] Initializing watchdogs
[57.744441]   /dev/watchdog0
[57.759041] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.763816] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.766040] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.769101] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[57.885158] Starting subtest: fbc-tiling-linear
[62.686438] Subtest fbc-tiling-linear: SUCCESS (4.807s)
[63.865644] [002/156] (954s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[63.923328] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[63.978922] [003/156] (954s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[64.082810] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[64.087226] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[64.185687] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[64.273744] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[64.277855] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[64.366505] [005/156] (954s left) kms_force_connector_basic (prune-stale-modes)
[64.435701] Starting subtest: prune-stale-modes
[64.880481] Subtest prune-stale-modes: SUCCESS (0.445s)
[65.034445] [006/156] (953s left) perf_pmu (most-busy-check-all)
[65.079831] Starting subtest: most-busy-check-all
[65.081882] Starting dynamic subtest: rcs0
[66.307528] Dynamic subtest rcs0: SUCCESS (1.225s)
[66.317797] Starting dynamic subtest: bcs0
[68.288899] Dynamic subtest bcs0: SUCCESS (1.980s)
[68.300759] Starting dynamic subtest: vcs0
[70.272611] Dynamic subtest vcs0: SUCCESS (1.982s)
[70.285109] Starting dynamic subtest: vcs1
[72.320066] Dynamic subtest vcs1: SUCCESS (2.046s)
[72.329590] Starting dynamic subtest: vecs0
[74.303392] Dynamic subtest vecs0: SUCCESS (1.983s)
[74.313589] Starting dynamic subtest: ccs0
[76.288229] Dynamic subtest ccs0: SUCCESS (1.983s)
[76.291108] Subtest most-busy-check-all: SUCCESS (11.207s)
[76.411509] [007/156] (942s left) gem_ccs (large-ctrl-surf-copy)
[76.466928] Starting subtest: large-ctrl-surf-copy
[76.471863] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[76.562592] [008/156] (941s left) kms_plane_alpha_blend (coverage-7efc)
[76.661631] Starting subtest: coverage-7efc
[76.960751] Starting dynamic subtest: pipe-A-eDP-1
[77.539102] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[77.567197] Starting dynamic subtest: pipe-D-eDP-1
[78.856709] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.295s)
[78.859045] Subtest coverage-7efc: SUCCESS (2.194s)
[79.707083] [009/156] (938s left) gem_mmap_gtt (fault-concurrent)
[79.760786] Subtest fault-concurrent: SKIP (0.000s)
[79.821113] [010/156] (938s left) api_intel_allocator (gem-pool)
[79.880115] Starting subtest: gem-pool
[79.887037] Subtest gem-pool: SUCCESS (0.008s)
[80.008148] [011/156] (938s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[80.104570] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[80.108675] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[80.190510] [012/156] (938s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[80.276962] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[80.281537] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[80.348265] [013/156] (938s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[80.446806] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[80.449168] Starting dynamic subtest: pipe-A-eDP-1
[80.472068] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[80.480939] Starting dynamic subtest: pipe-B-eDP-1
[80.502488] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[80.509960] Starting dynamic subtest: pipe-C-eDP-1
[80.530681] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[80.538143] Starting dynamic subtest: pipe-D-eDP-1
[80.559623] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[80.564083] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[82.772347] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[82.862825] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[82.865094] Starting dynamic subtest: pipe-A-eDP-1
[82.887987] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.898305] Starting dynamic subtest: pipe-B-eDP-1
[82.919967] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.927577] Starting dynamic subtest: pipe-C-eDP-1
[82.949765] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.957794] Starting dynamic subtest: pipe-D-eDP-1
[82.980337] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.984397] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.002s)
[85.115140] [015/156] (933s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[85.215781] Starting subtest: hdr-rgb565-draw-blt
[85.220060] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[85.311963] [016/156] (933s left) kms_pm_rpm (legacy-planes-dpms)
[87.461156] Starting subtest: legacy-planes-dpms
[87.463872] Starting dynamic subtest: plane-65
[96.076914] Dynamic subtest plane-65: SUCCESS (8.619s)
[96.088056] Starting dynamic subtest: plane-95
[104.402038] Dynamic subtest plane-95: SUCCESS (8.324s)
[104.413226] Starting dynamic subtest: plane-125
[112.734223] Dynamic subtest plane-125: SUCCESS (8.331s)
[112.745165] Starting dynamic subtest: plane-135
[121.092917] Dynamic subtest plane-135: SUCCESS (8.358s)
[121.095478] Subtest legacy-planes-dpms: SUCCESS (33.636s)
[121.505703] [017/156] (897s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[121.567079] Starting subtest: x-tiled-to-vebox-y-tiled
[121.571796] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[121.742365] [018/156] (897s left) sysfs_heartbeat_interval (invalid)
[121.797486] Starting subtest: invalid
[121.799899] Starting dynamic subtest: vecs0
[121.802235] Dynamic subtest vecs0: SUCCESS (0.000s)
[121.810834] Starting dynamic subtest: ccs0
[121.813131] Dynamic subtest ccs0: SUCCESS (0.000s)
[121.823259] Starting dynamic subtest: vcs0
[121.827384] Dynamic subtest vcs0: SUCCESS (0.000s)
[121.834842] Starting dynamic subtest: bcs0
[121.836841] Dynamic subtest bcs0: SUCCESS (0.000s)
[121.844623] Starting dynamic subtest: rcs0
[121.846807] Dynamic subtest rcs0: SUCCESS (0.000s)
[121.854681] Starting dynamic subtest: vcs1
[121.857065] Dynamic subtest vcs1: SUCCESS (0.000s)
[121.858974] Subtest invalid: SUCCESS (0.003s)
[121.905235] [019/156] (897s left) kms_frontbuffer_tracking (fbc-stridechange)
[122.002029] Starting subtest: fbc-stridechange
[125.534061] Subtest fbc-stridechange: SUCCESS (3.537s)
[126.682974] [020/156] (892s left) gem_ctx_param (basic-default)
[126.741921] Starting subtest: basic-default
[126.744521] Subtest basic-default: SUCCESS (0.000s)
[126.815796] [021/156] (892s left) kms_psr (fbc-pr-primary-mmap-gtt)
[126.905072] Starting subtest: fbc-pr-primary-mmap-gtt
[126.909382] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[126.984802] [022/156] (892s left) i915_pm_freq_mult (media-freq)
[127.036108] Starting subtest: media-freq
[127.038432] Starting dynamic subtest: gt0
[127.042582] Dynamic subtest gt0: SKIP (0.000s)
[127.052999] Starting dynamic subtest: gt1
[127.058208] Dynamic subtest gt1: SKIP (0.000s)
[127.062821] Subtest media-freq: SKIP (0.001s)
[127.146276] [023/156] (891s left) prime_busy (before-wait)
[127.201655] Starting subtest: before-wait
[127.203747] Starting dynamic subtest: rcs0
[127.231922] Dynamic subtest rcs0: SUCCESS (0.028s)
[127.242962] Starting dynamic subtest: bcs0
[127.288514] Dynamic subtest bcs0: SUCCESS (0.056s)
[127.300952] Starting dynamic subtest: vcs0
[127.329051] Dynamic subtest vcs0: SUCCESS (0.041s)
[127.337695] Starting dynamic subtest: vcs1
[127.369682] Dynamic subtest vcs1: SUCCESS (0.040s)
[127.378752] Starting dynamic subtest: vecs0
[127.411369] Dynamic subtest vecs0: SUCCESS (0.041s)
[127.420721] Starting dynamic subtest: ccs0
[127.453162] Dynamic subtest ccs0: SUCCESS (0.042s)
[127.561907] Subtest before-wait: SUCCESS (0.359s)
[127.631427] [024/156] (891s left) gem_exec_gttfill (engines)
[127.699486] Starting subtest: engines
[127.701776] Starting dynamic subtest: rcs0
[150.486915] Dynamic subtest rcs0: SUCCESS (22.790s)
[150.497035] Starting dynamic subtest: bcs0
[173.783647] Dynamic subtest bcs0: SUCCESS (23.293s)
[173.795588] Starting dynamic subtest: vcs0
[197.034266] Dynamic subtest vcs0: SUCCESS (23.248s)
[197.044257] Starting dynamic subtest: vcs1
[220.335747] Dynamic subtest vcs1: SUCCESS (23.300s)
[220.346039] Starting dynamic subtest: vecs0
[243.570492] Dynamic subtest vecs0: SUCCESS (23.233s)
[243.579769] Starting dynamic subtest: ccs0
[266.852569] Dynamic subtest ccs0: SUCCESS (23.280s)
[266.855382] Subtest engines: SUCCESS (139.151s)
[267.037718] [025/156] (752s left) syncobj_timeline (etime-multi-wait-all-submitted)
[267.137659] Starting subtest: etime-multi-wait-all-submitted
[267.243979] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[267.318425] [026/156] (751s left) gem_ctx_param (get-priority-new-ctx)
[267.371739] Starting subtest: get-priority-new-ctx
[267.374272] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[267.455272] [027/156] (751s left) kms_big_fb (linear-32bpp-rotate-90)
[267.563771] Starting subtest: linear-32bpp-rotate-90
[267.582815] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[267.708111] [028/156] (751s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[267.750882] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[267.791701] [029/156] (751s left) gem_ctx_shared (disjoint-timelines)
[267.852899] Starting subtest: disjoint-timelines
[267.949959] Subtest disjoint-timelines: SUCCESS (0.097s)
[268.193626] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[268.197172] [030/156] (751s left) kms_psr (fbc-psr2-cursor-plane-move)
[268.282030] Starting subtest: fbc-psr2-cursor-plane-move
[268.284071] Starting dynamic subtest: eDP-1
[268.288254] Dynamic subtest eDP-1: SKIP (0.000s)
[268.292432] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[268.354184] [031/156] (751s left) gem_bad_reloc (negative-reloc-bltcopy)
[268.411827] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[268.482656] [032/156] (751s left) kms_chamelium_hpd (hdmi-hpd)
[268.874893] Subtest hdmi-hpd: SKIP (0.000s)
[269.691596] [033/156] (749s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[269.782875] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[269.787335] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[269.842582] [034/156] (749s left) gem_eio (wait-10ms)
[270.066667] Starting subtest: wait-10ms
[270.407094] Subtest wait-10ms: SUCCESS (0.340s)
[270.542073] [035/156] (749s left) kms_content_protection (legacy-hdcp14)
[270.685354] Starting subtest: legacy-hdcp14
[270.689518] Subtest legacy-hdcp14: SKIP (0.000s)
[270.825193] [036/156] (748s left) syncobj_wait (reset-signaled)
[270.884476] Starting subtest: reset-signaled
[270.886411] Subtest reset-signaled: SUCCESS (0.000s)
[270.998723] [037/156] (748s left) gem_ctx_isolation (dirty-switch)
[271.064102] Starting subtest: dirty-switch
[271.065685] Starting dynamic subtest: rcs0
[271.246630] Dynamic subtest rcs0: SUCCESS (0.185s)
[271.258736] Starting dynamic subtest: bcs0
[271.449557] Dynamic subtest bcs0: SUCCESS (0.202s)
[271.460080] Starting dynamic subtest: vcs0
[271.674340] Dynamic subtest vcs0: SUCCESS (0.224s)
[271.684090] Starting dynamic subtest: vcs1
[273.488682] Dynamic subtest vcs1: SUCCESS (1.813s)
[273.499979] Starting dynamic subtest: vecs0
[275.522530] Dynamic subtest vecs0: SUCCESS (2.034s)
[275.532249] Starting dynamic subtest: ccs0
[277.525390] Dynamic subtest ccs0: SUCCESS (2.002s)
[277.527848] Subtest dirty-switch: SUCCESS (6.463s)
[277.770563] [038/156] (741s left) gem_flink_basic (bad-open)
[277.818086] Starting subtest: bad-open
[277.820400] Subtest bad-open: SUCCESS (0.000s)
[277.916277] [039/156] (741s left) gem_caching (writes)
[277.973481] Subtest writes: SKIP (0.000s)
[278.039389] [040/156] (741s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[278.139300] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[278.141296] Starting dynamic subtest: pipe-A
[278.476316] Dynamic subtest pipe-A: SUCCESS (0.339s)
[278.488072] Starting dynamic subtest: pipe-B
[279.899832] Dynamic subtest pipe-B: SUCCESS (1.424s)
[279.912225] Starting dynamic subtest: pipe-C
[281.319296] Dynamic subtest pipe-C: SUCCESS (1.419s)
[281.328704] Starting dynamic subtest: pipe-D
[282.758070] Dynamic subtest pipe-D: SUCCESS (1.437s)
[282.760424] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.621s)
[283.956134] [041/156] (735s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[284.016904] Starting subtest: single-wait-all-for-submit-available-submitted
[284.019322] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[284.108747] [042/156] (735s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[284.212412] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[284.216748] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[284.309857] [043/156] (735s left) kms_pm_rpm (system-suspend-idle)
[286.469921] Starting subtest: system-suspend-idle
[306.304199] Subtest system-suspend-idle: SUCCESS (4.314s)
[306.703816] [044/156] (713s left) kms_atomic_transition (modeset-transition)
[306.815786] Starting subtest: modeset-transition
[306.818232] Starting dynamic subtest: 1x-outputs
[317.950519] Dynamic subtest 1x-outputs: SUCCESS (11.128s)
[317.952851] Subtest modeset-transition: SUCCESS (11.129s)
[318.825370] [045/156] (701s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[318.930769] Starting subtest: pipe-fbc-rte
[318.937976] Starting dynamic subtest: pipe-B-eDP-1
[324.358361] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.433s)
[324.365969] Subtest pipe-fbc-rte: SUCCESS (5.435s)
[326.646635] [046/156] (693s left) gem_spin_batch (resubmit-all)
[326.712193] Starting subtest: resubmit-all
[326.714776] Starting dynamic subtest: rcs0
[326.724359] Dynamic subtest rcs0: SUCCESS (0.012s)
[326.739612] Starting dynamic subtest: bcs0
[326.741776] Dynamic subtest bcs0: SUCCESS (0.002s)
[326.751480] Starting dynamic subtest: vcs0
[326.753628] Dynamic subtest vcs0: SUCCESS (0.002s)
[326.763028] Starting dynamic subtest: vcs1
[326.765136] Dynamic subtest vcs1: SUCCESS (0.002s)
[326.774779] Starting dynamic subtest: vecs0
[326.777035] Dynamic subtest vecs0: SUCCESS (0.002s)
[326.786662] Starting dynamic subtest: ccs0
[326.788863] Dynamic subtest ccs0: SUCCESS (0.002s)
[326.791046] Subtest resubmit-all: SUCCESS (0.025s)
[327.880611] [047/156] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[327.994680] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[327.999169] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[328.083479] [048/156] (692s left) syncobj_basic (illegal-fd-to-handle)
[328.145003] Starting subtest: illegal-fd-to-handle
[328.147038] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[328.249896] [049/156] (691s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[328.349290] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[328.353472] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[328.457133] [050/156] (691s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[328.564118] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[329.653636] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.090s)
[329.767450] [051/156] (690s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[329.813278] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[329.916961] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[329.994801] [052/156] (690s left) api_intel_bb (misplaced-blitter)
[330.048896] Starting subtest: misplaced-blitter
[330.057920] Subtest misplaced-blitter: SUCCESS (0.010s)
[330.198657] [053/156] (690s left) kms_atomic_interruptible (universal-setplane-cursor)
[330.299928] Starting subtest: universal-setplane-cursor
[330.302672] Starting dynamic subtest: pipe-A-eDP-1
[337.764699] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.464s)
[337.766930] Subtest universal-setplane-cursor: SUCCESS (7.465s)
[338.621318] [054/156] (681s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[338.723837] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[338.727983] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[338.820211] [055/156] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[338.938633] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[338.942709] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[339.030258] [056/156] (681s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[339.124638] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[339.129041] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[339.208454] [057/156] (681s left) gem_pxp (create-protected-buffer)
[339.327806] Starting subtest: create-protected-buffer
[339.330366] Subtest create-protected-buffer: SUCCESS (0.000s)
[339.445597] [058/156] (681s left) gem_fence_thrash (bo-write-verify-threaded-x)
[339.475191] Starting subtest: bo-write-verify-threaded-x
[339.503849] Subtest bo-write-verify-threaded-x: SKIP (0.026s)
[339.568750] [059/156] (681s left) gen9_exec_parse (allowed-single)
[339.633836] Subtest allowed-single: SKIP (0.000s)
[339.717853] [060/156] (680s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[339.809468] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[339.812931] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[339.899248] [061/156] (680s left) gem_exec_balancer (bonded-semaphore)
[339.963870] Starting subtest: bonded-semaphore
[339.967715] Subtest bonded-semaphore: SKIP (0.000s)
[340.274474] [062/156] (680s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[340.370540] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[340.374636] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[340.441344] [063/156] (680s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[340.547363] Starting subtest: fbc-rgb565-draw-mmap-wc
[344.519205] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (3.978s)
[345.716802] [064/156] (675s left) kms_pwrite_crc (basic)
[345.806458] Starting subtest: basic
[346.511788] Subtest basic: SUCCESS (0.706s)
[347.352766] [065/156] (673s left) gem_flink_basic (bad-flink)
[347.405069] Starting subtest: bad-flink
[347.407441] Subtest bad-flink: SUCCESS (0.000s)
[347.526576] [066/156] (673s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[347.620476] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[347.624563] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[347.719266] [067/156] (673s left) gem_ctx_persistence (legacy-engines-persistence)
[347.786442] Starting subtest: legacy-engines-persistence
[347.788825] Starting dynamic subtest: render
[348.579666] Dynamic subtest render: SUCCESS (0.792s)
[348.592662] Starting dynamic subtest: bsd1
[349.348439] Dynamic subtest bsd1: SUCCESS (0.767s)
[349.361037] Starting dynamic subtest: bsd2
[350.119789] Dynamic subtest bsd2: SUCCESS (0.769s)
[350.134147] Starting dynamic subtest: blt
[350.877820] Dynamic subtest blt: SUCCESS (0.756s)
[350.892627] Starting dynamic subtest: vebox
[351.648480] Dynamic subtest vebox: SUCCESS (0.768s)
[351.651352] Subtest legacy-engines-persistence: SUCCESS (3.864s)
[351.746993] [068/156] (669s left) gen7_exec_parse (basic-allowed)
[351.811496] Subtest basic-allowed: SKIP (0.000s)
[351.898421] [069/156] (669s left) gem_exec_schedule (reorder-wide)
[352.079531] Subtest reorder-wide: SKIP (0.000s)
[352.265465] [070/156] (668s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[352.375347] Starting subtest: psrhdr-slowdraw
[352.379539] Subtest psrhdr-slowdraw: SKIP (0.000s)
[352.458694] [071/156] (668s left) perf (non-zero-reason)
[352.602541] Starting subtest: non-zero-reason
[352.604784] Starting dynamic subtest: 0-rcs0
[357.992345] Dynamic subtest 0-rcs0: SUCCESS (5.389s)
[358.007602] Starting dynamic subtest: 1-vcs0
[361.923839] Dynamic subtest 1-vcs0: SUCCESS (3.912s)
[361.928559] Subtest non-zero-reason: SUCCESS (9.303s)
[361.968324] [072/156] (659s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[362.081090] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[362.085510] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[362.185359] [073/156] (659s left) i915_drm_fdinfo (all-busy-check-all)
[362.224214] Starting subtest: all-busy-check-all
[362.232008] Subtest all-busy-check-all: SKIP (0.004s)
[362.295033] [074/156] (658s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[362.418996] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[362.423129] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[362.516856] [075/156] (658s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[362.607651] Starting subtest: compare-crc-sanitycheck-nv12
[362.609574] Starting dynamic subtest: pipe-A-eDP-1
[363.306380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.699s)
[363.318343] Starting dynamic subtest: pipe-B-eDP-1
[364.609245] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.302s)
[364.620565] Starting dynamic subtest: pipe-C-eDP-1
[365.877793] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.269s)
[365.888031] Starting dynamic subtest: pipe-D-eDP-1
[367.124939] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[367.127379] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.520s)
[367.989768] [076/156] (653s left) gem_exec_reloc (basic-write-gtt)
[368.060305] Subtest basic-write-gtt: SKIP (0.000s)
[368.146775] [077/156] (653s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[368.466065] Starting subtest: cursor-rapid-movement-max-size
[368.472846] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[368.606545] [078/156] (652s left) kms_color_pipeline (plane-lut1d)
[368.698605] Starting subtest: plane-lut1d
[368.855640] Starting dynamic subtest: pipe-A-plane-0
[369.033102] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[369.047669] Starting dynamic subtest: pipe-A-plane-1
[369.166107] Dynamic subtest pipe-A-plane-1: SUCCESS (0.132s)
[369.176150] Starting dynamic subtest: pipe-A-plane-2
[369.321497] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[370.576768] Starting dynamic subtest: pipe-B-plane-0
[370.713060] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[370.724889] Starting dynamic subtest: pipe-B-plane-1
[370.857509] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[370.868738] Starting dynamic subtest: pipe-B-plane-2
[370.990487] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[372.211426] Starting dynamic subtest: pipe-C-plane-0
[372.333925] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[372.345020] Starting dynamic subtest: pipe-C-plane-1
[372.468095] Dynamic subtest pipe-C-plane-1: SUCCESS (0.132s)
[372.479775] Starting dynamic subtest: pipe-C-plane-2
[372.622831] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[373.847796] Starting dynamic subtest: pipe-D-plane-0
[373.971697] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[373.982886] Starting dynamic subtest: pipe-D-plane-1
[374.116109] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[374.126731] Starting dynamic subtest: pipe-D-plane-2
[374.282927] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[374.590486] Subtest plane-lut1d: SUCCESS (5.892s)
[375.399146] [079/156] (646s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[375.507979] Starting subtest: yf-tiled-16bpp-rotate-180
[375.512757] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[375.608921] [080/156] (645s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[375.716063] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[376.675063] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.960s)
[376.821247] [081/156] (644s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[376.921907] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[376.926506] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[377.016503] [082/156] (644s left) gem_exec_reloc (basic-wc-read)
[377.067851] Subtest basic-wc-read: SKIP (0.000s)
[377.136043] [083/156] (644s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[377.250564] Starting subtest: short-busy-flip-before-cursor-toggle
[377.254492] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[377.398579] [084/156] (644s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[377.490652] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[377.503821] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[377.592909] [085/156] (644s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[377.694703] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[377.698357] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[377.797231] [086/156] (643s left) kms_draw_crc (draw-method-render)
[377.893673] Starting subtest: draw-method-render
[377.895547] Starting dynamic subtest: xrgb8888-untiled
[378.170155] Dynamic subtest xrgb8888-untiled: SUCCESS (0.277s)
[378.182184] Starting dynamic subtest: rgb565-untiled
[378.403672] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[378.414455] Starting dynamic subtest: xrgb2101010-untiled
[378.625661] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[378.635910] Starting dynamic subtest: xbgr16161616f-untiled
[378.872054] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s)
[378.883230] Starting dynamic subtest: xrgb8888-xtiled
[378.992514] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[379.005757] Starting dynamic subtest: rgb565-xtiled
[379.103582] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[379.113801] Starting dynamic subtest: xrgb2101010-xtiled
[379.225763] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[379.235898] Starting dynamic subtest: xbgr16161616f-xtiled
[379.359217] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[379.371988] Starting dynamic subtest: xrgb8888-4tiled
[379.483069] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s)
[379.498158] Starting dynamic subtest: rgb565-4tiled
[379.593038] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[379.606120] Starting dynamic subtest: xrgb2101010-4tiled
[379.714907] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[379.725754] Starting dynamic subtest: xbgr16161616f-4tiled
[379.848080] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[379.849963] Subtest draw-method-render: SUCCESS (1.956s)
[379.964456] [087/156] (641s left) kms_cursor_crc (cursor-sliding-256x85)
[380.224665] Starting subtest: cursor-sliding-256x85
[380.279136] Subtest cursor-sliding-256x85: SKIP (0.051s)
[380.444227] [088/156] (641s left) kms_cursor_crc (cursor-onscreen-256x256)
[380.671601] Starting subtest: cursor-onscreen-256x256
[380.673535] Starting dynamic subtest: pipe-A-eDP-1
[382.808718] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.135s)
[382.839944] Starting dynamic subtest: pipe-D-eDP-1
[385.574280] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.743s)
[385.576756] Subtest cursor-onscreen-256x256: SUCCESS (4.902s)
[386.470359] [089/156] (635s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[386.575492] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[386.579490] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[386.667950] [090/156] (635s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[386.770442] Starting subtest: fbcpsr-slowdraw
[412.031694] Subtest fbcpsr-slowdraw: SUCCESS (25.265s)
[412.173508] [091/156] (609s left) kms_lease (cursor-implicit-plane)
[412.275697] Starting subtest: cursor-implicit-plane
[412.278044] Starting dynamic subtest: pipe-A-eDP-1
[412.720731] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.446s)
[412.732216] Starting dynamic subtest: pipe-B-eDP-1
[413.836708] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.114s)
[413.848831] Starting dynamic subtest: pipe-C-eDP-1
[414.949588] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.111s)
[414.961108] Starting dynamic subtest: pipe-D-eDP-1
[416.082727] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[416.085024] Subtest cursor-implicit-plane: SUCCESS (3.809s)
[416.946148] [092/156] (605s left) syncobj_wait (invalid-reset-zero-handles)
[416.999719] Starting subtest: invalid-reset-zero-handles
[417.002008] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[417.119387] [093/156] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[417.229017] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[417.233228] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[417.348854] [094/156] (604s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[417.456988] Starting subtest: yf-tiled-64bpp-rotate-90
[417.461440] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[417.539714] [095/156] (604s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[417.640300] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[417.644361] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[417.743638] [096/156] (604s left) gem_exec_fence (long-history)
[418.195289] Starting subtest: long-history
[425.546304] Subtest long-history: SUCCESS (7.350s)
[426.187805] [097/156] (596s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[426.291889] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[433.731963] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.445s)
[433.891087] [098/156] (588s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[433.993724] Starting subtest: bad-rotation-90-yf-tiled-ccs
[433.995930] Starting dynamic subtest: pipe-A-eDP-1
[434.017902] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[434.031016] Starting dynamic subtest: pipe-B-eDP-1
[434.051655] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[434.061585] Starting dynamic subtest: pipe-C-eDP-1
[434.082525] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[434.092529] Starting dynamic subtest: pipe-D-eDP-1
[434.116107] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[434.120155] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[436.247254] [099/156] (586s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[436.378891] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[454.745644] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.365s)
[455.634855] [100/156] (566s left) kms_chamelium_audio (dp-audio-edid)
[456.028707] Subtest dp-audio-edid: SKIP (0.000s)
[456.847668] [101/156] (565s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[456.936061] Starting subtest: addfb25-yf-tiled-legacy
[456.938509] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[457.047421] [102/156] (565s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[457.149682] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[457.153901] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[457.235098] [103/156] (565s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[457.343485] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[457.347634] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[457.438581] [104/156] (565s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[457.529653] Starting subtest: fbc-2p-pri-indfb-multidraw
[457.533972] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[457.644527] [105/156] (564s left) kms_sequence (queue-busy)
[457.740655] Starting subtest: queue-busy
[457.742522] Starting dynamic subtest: pipe-A-eDP-1
[459.666079] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.926s)
[459.677352] Starting dynamic subtest: pipe-B-eDP-1
[462.248739] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.579s)
[462.260832] Starting dynamic subtest: pipe-C-eDP-1
[464.877962] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.629s)
[464.889652] Starting dynamic subtest: pipe-D-eDP-1
[467.505372] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.624s)
[467.507713] Subtest queue-busy: SUCCESS (9.764s)
[468.381963] [106/156] (554s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[468.478666] Starting subtest: plane-upscale-20x20-with-rotation
[468.481020] Starting dynamic subtest: pipe-A
[468.703125] Dynamic subtest pipe-A: SUCCESS (0.227s)
[468.714380] Starting dynamic subtest: pipe-B
[469.990615] Dynamic subtest pipe-B: SUCCESS (1.286s)
[470.001084] Starting dynamic subtest: pipe-C
[471.310895] Dynamic subtest pipe-C: SUCCESS (1.319s)
[471.324019] Starting dynamic subtest: pipe-D
[472.648239] Dynamic subtest pipe-D: SUCCESS (1.336s)
[472.650531] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.172s)
[473.829851] [107/156] (548s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[473.930281] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[473.934466] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[474.036527] [108/156] (548s left) gem_exec_reloc (basic-write-wc)
[474.095162] Subtest basic-write-wc: SKIP (0.000s)
[474.182314] [109/156] (548s left) syncobj_timeline (reset-signaled)
[474.231675] Starting subtest: reset-signaled
[474.233638] Subtest reset-signaled: SUCCESS (0.000s)
[474.323824] [110/156] (548s left) kms_psr (pr-cursor-plane-move)
[474.410432] Starting subtest: pr-cursor-plane-move
[474.414885] Subtest pr-cursor-plane-move: SKIP (0.000s)
[474.483243] [111/156] (548s left) syncobj_wait (invalid-wait-illegal-handle)
[474.533460] Starting subtest: invalid-wait-illegal-handle
[474.535834] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[474.631077] [112/156] (548s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[474.721479] Starting subtest: extended-pageflip-modeset-hang-oldfb
[474.723969] Starting dynamic subtest: pipe-A
[482.622733] Dynamic subtest pipe-A: SUCCESS (7.900s)
[482.635298] Starting dynamic subtest: pipe-D
[491.248817] Dynamic subtest pipe-D: SUCCESS (8.622s)
[491.251107] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.527s)
[492.097238] [113/156] (530s left) kms_psr (psr2-primary-blt)
[492.200174] Starting subtest: psr2-primary-blt
[492.202476] Starting dynamic subtest: eDP-1
[493.970208] Dynamic subtest eDP-1: SUCCESS (1.770s)
[493.972693] Subtest psr2-primary-blt: SUCCESS (1.772s)
[494.853249] [114/156] (528s left) gem_softpin (evict-snoop-interruptible)
[494.919252] Starting subtest: evict-snoop-interruptible
[494.923741] Subtest evict-snoop-interruptible: SKIP (0.000s)
[495.054559] [115/156] (527s left) kms_force_connector_basic (force-load-detect)
[495.139441] Starting subtest: force-load-detect
[495.144014] Subtest force-load-detect: SKIP (0.000s)
[495.273722] [116/156] (527s left) kms_chamelium_edid (hdmi-mode-timings)
[495.652296] Subtest hdmi-mode-timings: SKIP (0.000s)
[496.463440] [117/156] (526s left) syncobj_timeline (invalid-signal-zero-handles)
[496.518117] Starting subtest: invalid-signal-zero-handles
[496.520539] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[496.617801] [118/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[496.717977] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[496.722519] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[496.826744] [119/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[496.921468] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[496.925633] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[497.022680] [120/156] (526s left) gem_mmap_gtt (fault-concurrent-x)
[497.081089] Subtest fault-concurrent-X: SKIP (0.000s)
[497.148383] [121/156] (526s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[497.214682] Starting subtest: y-tiled-to-vebox-y-tiled
[497.219306] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[497.403601] [122/156] (525s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[497.496170] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[497.500313] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[497.607450] [123/156] (525s left) vgem_basic (debugfs)
[497.652426] Starting subtest: debugfs
[497.654753] Subtest debugfs: SUCCESS (0.000s)
[497.710720] [124/156] (525s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[497.803589] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[497.805870] Starting dynamic subtest: pipe-A-default-mode
[498.938122] Dynamic subtest pipe-A-default-mode: SKIP (1.133s)
[498.942681] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.134s)
[499.098952] [125/156] (524s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[499.212442] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[499.216591] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[499.334854] [126/156] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[499.439651] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[499.443511] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[499.539482] [127/156] (524s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[499.630237] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[499.634120] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[499.712975] [128/156] (523s left) kms_vblank (wait-forked-hang)
[499.807083] Starting subtest: wait-forked-hang
[499.809245] Starting dynamic subtest: pipe-A-eDP-1
[527.594972] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.784s)
[527.612131] Starting dynamic subtest: pipe-D-eDP-1
[542.553336] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.957s)
[542.556177] Subtest wait-forked-hang: SUCCESS (42.745s)
[543.386871] [129/156] (480s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[543.493373] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[543.495720] Starting dynamic subtest: pipe-A-default-mode
[544.644151] Dynamic subtest pipe-A-default-mode: SKIP (1.148s)
[544.648526] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.149s)
[544.802238] [130/156] (478s left) gem_ctx_exec (basic-invalid-context)
[544.860948] Starting subtest: basic-invalid-context
[544.866068] Subtest basic-invalid-context: SUCCESS (0.005s)
[544.982316] [131/156] (478s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[545.086549] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[545.090612] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[545.182691] [132/156] (478s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[545.283056] Starting subtest: fbc-psr2-cursor-plane-update-sf
[545.285473] Starting dynamic subtest: pipe-A-eDP-1
[545.360230] Dynamic subtest pipe-A-eDP-1: SKIP (0.081s)
[545.372749] Starting dynamic subtest: pipe-B-eDP-1
[546.485679] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[546.490467] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.207s)
[547.792097] [133/156] (476s left) gem_ppgtt (blt-vs-render-ctxn)
[547.851671] Starting subtest: blt-vs-render-ctxN
[580.638641] Subtest blt-vs-render-ctxN: SUCCESS (32.782s)
[580.759323] [134/156] (443s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[580.860526] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[580.942753] [135/156] (442s left) gem_softpin (invalid)
[581.003956] Starting subtest: invalid
[581.012751] Subtest invalid: SUCCESS (0.008s)
[581.152523] [136/156] (442s left) gem_exec_capture (capture)
[581.213175] Starting subtest: capture
[581.215542] Starting dynamic subtest: rcs0-smem
[582.060153] Dynamic subtest rcs0-smem: SUCCESS (0.843s)
[582.070929] Starting dynamic subtest: bcs0-smem
[583.097219] Dynamic subtest bcs0-smem: SUCCESS (1.037s)
[583.110812] Starting dynamic subtest: vcs0-smem
[583.924877] Dynamic subtest vcs0-smem: SUCCESS (0.825s)
[583.935237] Starting dynamic subtest: vcs1-smem
[584.747373] Dynamic subtest vcs1-smem: SUCCESS (0.823s)
[584.758421] Starting dynamic subtest: vecs0-smem
[586.153975] Dynamic subtest vecs0-smem: FAIL (1.345s)
[586.166928] Starting dynamic subtest: ccs0-smem
[587.426066] Dynamic subtest ccs0-smem: SUCCESS (1.331s)
[587.428470] Subtest capture: FAIL (6.210s)
[587.542820] [137/156] (436s left) gem_tiled_blits (basic)
[587.591780] Subtest basic: SKIP (0.000s)
[587.659971] [138/156] (436s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[587.761258] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[595.036818] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.281s)
[595.187598] [139/156] (428s left) gem_mmap_offset (partial-remap)
[595.238143] Starting subtest: partial-remap
[595.240976] Starting dynamic subtest: smem0
[595.249707] Dynamic subtest smem0: SUCCESS (0.003s)
[595.251725] Subtest partial-remap: SUCCESS (0.004s)
[595.359634] [140/156] (428s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[595.460174] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[595.464706] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[595.564257] [141/156] (428s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[595.661475] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[595.665639] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[595.752488] [142/156] (428s left) i915_power (sanity)
[595.798756] Starting subtest: sanity
[599.841492] Subtest sanity: SUCCESS (4.035s)
[601.837021] [143/156] (422s left) gem_exec_schedule (preempt-queue)
[601.921391] Subtest preempt-queue: SKIP (0.000s)
[602.223671] [144/156] (422s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[602.351746] Starting subtest: cursorB-vs-flipA-legacy
[602.356258] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[602.512410] [145/156] (421s left) i915_pm_rps (reset)
[602.572433] Starting subtest: reset
[610.985526] Subtest reset: SUCCESS (8.412s)
[611.129703] [146/156] (413s left) kms_psr (fbc-psr-cursor-plane-onoff)
[611.233133] Starting subtest: fbc-psr-cursor-plane-onoff
[611.235439] Starting dynamic subtest: eDP-1
[611.239891] Dynamic subtest eDP-1: SKIP (0.000s)
[611.244260] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[611.309071] [147/156] (413s left) kms_setmode (clone-exclusive-crtc)
[611.369244] Starting subtest: clone-exclusive-crtc
[611.375750] Subtest clone-exclusive-crtc: SKIP (0.000s)
[611.489782] [148/156] (413s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[611.598256] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[618.298619] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.704s)
[618.439582] [149/156] (406s left) kms_flip (flip-vs-rmfb)
[618.539244] Starting subtest: flip-vs-rmfb
[618.541647] Starting dynamic subtest: A-eDP1
[627.210768] Dynamic subtest A-eDP1: SUCCESS (8.674s)
[627.223952] Starting dynamic subtest: B-eDP1
[635.839454] Dynamic subtest B-eDP1: SUCCESS (8.627s)
[635.849914] Starting dynamic subtest: C-eDP1
[644.475637] Dynamic subtest C-eDP1: SUCCESS (8.634s)
[644.488785] Starting dynamic subtest: D-eDP1
[653.099313] Dynamic subtest D-eDP1: SUCCESS (8.620s)
[653.103555] Subtest flip-vs-rmfb: SUCCESS (34.565s)
[654.275908] [150/156] (370s left) gem_exec_whisper (basic-contexts-all)
[654.346117] Starting subtest: basic-contexts-all
[665.533260] Subtest basic-contexts-all: SUCCESS (11.182s)
[665.716141] [151/156] (358s left) dmabuf_sync_file (export-basic)
[665.755472] Starting subtest: export-basic
[665.757720] Subtest export-basic: SUCCESS (0.001s)
[665.816163] [152/156] (358s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[665.916045] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[665.920050] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[666.021528] [153/156] (358s left) gem_sync (basic-each)
[666.099865] Starting subtest: basic-each
[668.406281] Subtest basic-each: SUCCESS (2.317s)
[668.597934] [154/156] (356s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[668.694197] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[668.696744] Starting dynamic subtest: pipe-A-eDP-1
[668.723854] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[668.739964] Starting dynamic subtest: pipe-B-eDP-1
[668.764796] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[668.776932] Starting dynamic subtest: pipe-C-eDP-1
[668.801229] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[668.812048] Starting dynamic subtest: pipe-D-eDP-1
[668.837217] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[668.841753] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[670.933757] [155/156] (353s left) syncobj_wait (multi-wait-for-submit-submitted)
[671.001273] Starting subtest: multi-wait-for-submit-submitted
[671.108325] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[671.199424] [156/156] (353s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[671.299022] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[671.303344] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[671.390070] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
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_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.