[50.401642] Initializing watchdogs
[50.401705]   /dev/watchdog0
[50.417510] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[50.423360] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[50.425852] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[50.427947] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[50.531019] Starting subtest: fbc-tiling-linear
[51.663850] Subtest fbc-tiling-linear: SUCCESS (1.133s)
[51.801699] [002/156] (959s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[51.837808] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[51.873715] [003/156] (959s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[51.967318] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[51.968774] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[52.045734] [004/156] (958s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[52.142611] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[52.144016] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[52.259160] [005/156] (958s left) kms_force_connector_basic (prune-stale-modes)
[52.382774] Starting subtest: prune-stale-modes
[52.533243] Subtest prune-stale-modes: SUCCESS (0.150s)
[52.743562] [006/156] (958s left) perf_pmu (most-busy-check-all)
[52.783561] Starting subtest: most-busy-check-all
[52.784286] Starting dynamic subtest: rcs0
[53.304099] Dynamic subtest rcs0: SUCCESS (0.520s)
[53.313211] Starting dynamic subtest: bcs0
[53.825120] Dynamic subtest bcs0: SUCCESS (0.521s)
[53.834473] Starting dynamic subtest: vcs0
[54.343177] Dynamic subtest vcs0: SUCCESS (0.518s)
[54.351984] Starting dynamic subtest: vcs1
[54.866226] Dynamic subtest vcs1: SUCCESS (0.522s)
[54.875088] Starting dynamic subtest: vecs0
[55.385194] Dynamic subtest vecs0: SUCCESS (0.519s)
[55.386208] Subtest most-busy-check-all: SUCCESS (2.602s)
[55.454027] [007/156] (955s left) gem_ccs (large-ctrl-surf-copy)
[55.491053] Starting subtest: large-ctrl-surf-copy
[55.492618] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[55.565674] [008/156] (955s left) kms_plane_alpha_blend (coverage-7efc)
[55.655276] Starting subtest: coverage-7efc
[55.725263] Starting dynamic subtest: pipe-A-HDMI-A-1
[56.046868] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.321s)
[56.078370] Starting dynamic subtest: pipe-D-HDMI-A-1
[56.557163] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.484s)
[56.557887] Subtest coverage-7efc: SUCCESS (0.902s)
[56.710496] [009/156] (954s left) gem_mmap_gtt (fault-concurrent)
[56.748413] Starting subtest: fault-concurrent
[59.341411] Subtest fault-concurrent: SUCCESS (2.559s)
[59.431436] [010/156] (951s left) api_intel_allocator (gem-pool)
[59.466303] Starting subtest: gem-pool
[59.472121] Subtest gem-pool: SUCCESS (0.006s)
[59.572418] [011/156] (951s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[59.667114] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[59.668463] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[59.754741] [012/156] (951s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[59.846561] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[59.847972] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[59.901878] [013/156] (951s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[59.990853] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[59.991593] Starting dynamic subtest: pipe-A-HDMI-A-1
[59.998493] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[60.005047] Starting dynamic subtest: pipe-B-HDMI-A-1
[60.011712] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[60.018227] Starting dynamic subtest: pipe-C-HDMI-A-1
[60.024972] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[60.031396] Starting dynamic subtest: pipe-D-HDMI-A-1
[60.038109] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[60.039411] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[60.243486] [014/156] (951s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[60.334007] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[60.334729] Starting dynamic subtest: pipe-A-HDMI-A-1
[60.341712] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[60.350843] Starting dynamic subtest: pipe-B-HDMI-A-1
[60.357776] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[60.364261] Starting dynamic subtest: pipe-C-HDMI-A-1
[60.370987] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[60.377609] Starting dynamic subtest: pipe-D-HDMI-A-1
[60.384439] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[60.385781] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[60.587119] [015/156] (950s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[60.682678] Starting subtest: hdr-rgb565-draw-blt
[60.684123] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[60.768110] [016/156] (950s left) kms_pm_rpm (legacy-planes-dpms)
[62.200711] Starting subtest: legacy-planes-dpms
[62.201687] Starting dynamic subtest: plane-65
[70.516373] Dynamic subtest plane-65: SUCCESS (8.316s)
[70.526551] Starting dynamic subtest: plane-95
[78.909085] Dynamic subtest plane-95: SUCCESS (8.392s)
[78.927565] Starting dynamic subtest: plane-125
[87.229787] Dynamic subtest plane-125: SUCCESS (8.320s)
[87.239220] Starting dynamic subtest: plane-135
[95.631230] Dynamic subtest plane-135: SUCCESS (8.400s)
[95.640250] Starting dynamic subtest: plane-145
[103.938148] Dynamic subtest plane-145: SUCCESS (8.306s)
[103.947875] Starting dynamic subtest: plane-155
[112.428315] Dynamic subtest plane-155: SUCCESS (8.489s)
[112.429504] Subtest legacy-planes-dpms: SUCCESS (50.230s)
[112.627129] [017/156] (898s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[112.672283] Starting subtest: x-tiled-to-vebox-y-tiled
[112.672987] Starting dynamic subtest: smem
[112.770837] Dynamic subtest smem: SUCCESS (0.098s)
[112.771553] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.099s)
[112.960167] [018/156] (898s left) sysfs_heartbeat_interval (invalid)
[112.997580] Starting subtest: invalid
[112.998299] Starting dynamic subtest: vecs0
[112.998994] Dynamic subtest vecs0: SUCCESS (0.000s)
[113.005681] Starting dynamic subtest: vcs0
[113.006407] Dynamic subtest vcs0: SUCCESS (0.000s)
[113.012911] Starting dynamic subtest: bcs0
[113.013610] Dynamic subtest bcs0: SUCCESS (0.000s)
[113.020241] Starting dynamic subtest: rcs0
[113.020934] Dynamic subtest rcs0: SUCCESS (0.000s)
[113.027412] Starting dynamic subtest: vcs1
[113.028100] Dynamic subtest vcs1: SUCCESS (0.000s)
[113.028768] Subtest invalid: SUCCESS (0.004s)
[113.061286] [019/156] (898s left) kms_frontbuffer_tracking (fbc-stridechange)
[113.154928] Starting subtest: fbc-stridechange
[114.123353] Subtest fbc-stridechange: SUCCESS (0.969s)
[114.245576] [020/156] (897s left) gem_ctx_param (basic-default)
[114.279423] Starting subtest: basic-default
[114.280140] Subtest basic-default: SUCCESS (0.000s)
[114.340307] [021/156] (897s left) kms_psr (fbc-pr-primary-mmap-gtt)
[114.430421] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[114.467830] [022/156] (897s left) i915_pm_freq_mult (media-freq)
[114.501974] Starting subtest: media-freq
[114.502688] Starting dynamic subtest: gt0
[114.504466] Dynamic subtest gt0: SKIP (0.000s)
[114.505874] Subtest media-freq: SKIP (0.001s)
[114.568414] [023/156] (897s left) prime_busy (before-wait)
[114.606393] Starting subtest: before-wait
[114.611183] Starting dynamic subtest: rcs0
[114.640971] Dynamic subtest rcs0: SUCCESS (0.029s)
[114.653601] Starting dynamic subtest: bcs0
[114.657072] Dynamic subtest bcs0: SUCCESS (0.016s)
[114.666869] Starting dynamic subtest: vcs0
[114.682743] Dynamic subtest vcs0: SUCCESS (0.025s)
[114.694865] Starting dynamic subtest: vcs1
[114.696571] Dynamic subtest vcs1: SUCCESS (0.013s)
[114.704151] Starting dynamic subtest: vecs0
[114.717899] Dynamic subtest vecs0: SUCCESS (0.021s)
[114.826048] Subtest before-wait: SUCCESS (0.219s)
[114.896176] [024/156] (896s left) gem_exec_gttfill (engines)
[114.941048] Starting subtest: engines
[114.941788] Starting dynamic subtest: rcs0
[138.476452] Dynamic subtest rcs0: SUCCESS (23.535s)
[138.486280] Starting dynamic subtest: bcs0
[161.613843] Dynamic subtest bcs0: SUCCESS (23.135s)
[161.623381] Starting dynamic subtest: vcs0
[184.889407] Dynamic subtest vcs0: SUCCESS (23.273s)
[184.897018] Starting dynamic subtest: vcs1
[207.863733] Dynamic subtest vcs1: SUCCESS (22.973s)
[207.871953] Starting dynamic subtest: vecs0
[231.133248] Dynamic subtest vecs0: SUCCESS (23.268s)
[231.134095] Subtest engines: SUCCESS (116.188s)
[231.326158] [025/156] (780s left) syncobj_timeline (etime-multi-wait-all-submitted)
[231.427791] Starting subtest: etime-multi-wait-all-submitted
[231.533749] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s)
[231.612239] [026/156] (780s left) gem_ctx_param (get-priority-new-ctx)
[231.646371] Starting subtest: get-priority-new-ctx
[231.647373] Subtest get-priority-new-ctx: SUCCESS (0.000s)
[231.704676] [027/156] (780s left) kms_big_fb (linear-32bpp-rotate-90)
[231.813812] Starting subtest: linear-32bpp-rotate-90
[231.899505] Subtest linear-32bpp-rotate-90: SKIP (0.085s)
[232.007517] [028/156] (780s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[232.047774] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[232.090969] [029/156] (779s left) gem_ctx_shared (disjoint-timelines)
[232.132512] Starting subtest: disjoint-timelines
[232.170880] Subtest disjoint-timelines: SUCCESS (0.037s)
[232.383642] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[232.385371] [030/156] (779s left) kms_psr (fbc-psr2-cursor-plane-move)
[232.475848] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[232.519074] [031/156] (779s left) gem_bad_reloc (negative-reloc-bltcopy)
[232.559334] Starting subtest: negative-reloc-bltcopy
[233.675026] Subtest negative-reloc-bltcopy: SUCCESS (1.107s)
[233.794346] [032/156] (778s left) kms_chamelium_hpd (hdmi-hpd)
[233.938446] Subtest hdmi-hpd: SKIP (0.000s)
[234.044723] [033/156] (778s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[234.140356] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[234.183705] [034/156] (778s left) gem_eio (wait-10ms)
[234.228654] Starting subtest: wait-10ms
[234.392958] Subtest wait-10ms: SUCCESS (0.164s)
[234.512130] [035/156] (777s left) kms_content_protection (legacy-hdcp14)
[234.609923] Starting subtest: legacy-hdcp14
[234.615403] Subtest legacy-hdcp14: SKIP (0.004s)
[234.715869] [036/156] (777s left) syncobj_wait (reset-signaled)
[234.751157] Starting subtest: reset-signaled
[234.751920] Subtest reset-signaled: SUCCESS (0.000s)
[234.819634] [037/156] (777s left) gem_ctx_isolation (dirty-switch)
[234.860589] Starting subtest: dirty-switch
[234.861314] Starting dynamic subtest: rcs0
[235.047124] Dynamic subtest rcs0: SUCCESS (0.178s)
[235.054845] Starting dynamic subtest: bcs0
[235.267832] Dynamic subtest bcs0: SUCCESS (0.220s)
[235.275172] Starting dynamic subtest: vcs0
[235.488745] Dynamic subtest vcs0: SUCCESS (0.220s)
[235.496277] Starting dynamic subtest: vcs1
[235.698279] Dynamic subtest vcs1: SUCCESS (0.218s)
[235.706679] Starting dynamic subtest: vecs0
[235.922967] Dynamic subtest vecs0: SUCCESS (0.216s)
[235.923750] Subtest dirty-switch: SUCCESS (1.054s)
[236.109635] [038/156] (776s left) gem_flink_basic (bad-open)
[236.143906] Starting subtest: bad-open
[236.144651] Subtest bad-open: SUCCESS (0.000s)
[236.208761] [039/156] (776s left) gem_caching (writes)
[236.247413] Starting subtest: writes
[245.519139] Subtest writes: SUCCESS (9.272s)
[245.624162] [040/156] (766s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[245.716616] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[245.717324] Starting dynamic subtest: pipe-A
[246.102175] Dynamic subtest pipe-A: SUCCESS (0.386s)
[246.113844] Starting dynamic subtest: pipe-B
[246.544770] Dynamic subtest pipe-B: SUCCESS (0.442s)
[246.557060] Starting dynamic subtest: pipe-C
[246.995893] Dynamic subtest pipe-C: SUCCESS (0.451s)
[247.008376] Starting dynamic subtest: pipe-D
[247.438856] Dynamic subtest pipe-D: SUCCESS (0.442s)
[247.439593] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (1.723s)
[247.626058] [041/156] (764s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[247.662748] Starting subtest: single-wait-all-for-submit-available-submitted
[247.663471] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[247.741819] [042/156] (764s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[247.842747] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[247.844131] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[247.936215] [043/156] (764s left) kms_pm_rpm (system-suspend-idle)
[249.357170] Starting subtest: system-suspend-idle
[269.596396] Subtest system-suspend-idle: SUCCESS (4.968s)
[275.817122] [044/156] (742s left) kms_atomic_transition (modeset-transition)
[275.917056] Starting subtest: modeset-transition
[275.917828] Starting dynamic subtest: 1x-outputs
[277.181634] Dynamic subtest 1x-outputs: SUCCESS (1.263s)
[277.182562] Subtest modeset-transition: SUCCESS (1.264s)
[277.346147] [045/156] (741s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[277.443059] Starting subtest: pipe-fbc-rte
[277.447130] Subtest pipe-fbc-rte: SKIP (0.000s)
[277.554584] [046/156] (741s left) gem_spin_batch (resubmit-all)
[277.596815] Starting subtest: resubmit-all
[277.597620] Starting dynamic subtest: rcs0
[277.601395] Dynamic subtest rcs0: SUCCESS (0.004s)
[277.614711] Starting dynamic subtest: bcs0
[277.615518] Dynamic subtest bcs0: SUCCESS (0.003s)
[277.624898] Starting dynamic subtest: vcs0
[277.625622] Dynamic subtest vcs0: SUCCESS (0.003s)
[277.633903] Starting dynamic subtest: vcs1
[277.634626] Dynamic subtest vcs1: SUCCESS (0.003s)
[277.642473] Starting dynamic subtest: vecs0
[277.643178] Dynamic subtest vecs0: SUCCESS (0.002s)
[277.643872] Subtest resubmit-all: SUCCESS (0.021s)
[277.804850] [047/156] (741s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[277.897254] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[277.898715] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[278.011254] [048/156] (740s left) syncobj_basic (illegal-fd-to-handle)
[278.045699] Starting subtest: illegal-fd-to-handle
[278.046511] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[278.121496] [049/156] (740s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[278.214048] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[278.215458] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[278.307803] [050/156] (740s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[278.404985] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[279.094642] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (0.688s)
[279.217509] [051/156] (739s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[279.252706] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[279.356367] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[279.436867] [052/156] (739s left) api_intel_bb (misplaced-blitter)
[279.471529] Starting subtest: misplaced-blitter
[279.475391] Subtest misplaced-blitter: SUCCESS (0.004s)
[279.595340] [053/156] (739s left) kms_atomic_interruptible (universal-setplane-cursor)
[279.686267] Starting subtest: universal-setplane-cursor
[279.687990] Starting dynamic subtest: pipe-A-HDMI-A-1
[285.914443] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.222s)
[285.915180] Subtest universal-setplane-cursor: SUCCESS (6.224s)
[286.082747] [054/156] (733s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[286.187507] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[286.188892] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[286.258831] [055/156] (732s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[286.352006] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[286.353429] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[286.461084] [056/156] (732s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[286.548887] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[286.549636] Starting dynamic subtest: pipe-A-valid-mode
[287.601973] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.052s)
[287.602752] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.054s)
[287.757148] [057/156] (731s left) gem_pxp (create-protected-buffer)
[287.864874] Starting subtest: create-protected-buffer
[287.865935] Subtest create-protected-buffer: SUCCESS (0.000s)
[287.970151] [058/156] (731s left) gem_fence_thrash (bo-write-verify-threaded-x)
[288.002757] Starting subtest: bo-write-verify-threaded-x
[292.677290] Subtest bo-write-verify-threaded-x: SUCCESS (4.639s)
[292.752779] [059/156] (726s left) gen9_exec_parse (allowed-single)
[292.790996] Subtest allowed-single: SKIP (0.000s)
[292.837881] [060/156] (726s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[292.934265] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[292.935791] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[293.037828] [061/156] (726s left) gem_exec_balancer (bonded-semaphore)
[293.080462] Starting subtest: bonded-semaphore
[297.404699] Subtest bonded-semaphore: SUCCESS (4.289s)
[297.570320] [062/156] (721s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[297.660571] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[297.681950] Starting dynamic subtest: pipe-A-plane-0
[297.890253] Dynamic subtest pipe-A-plane-0: SUCCESS (0.208s)
[297.908211] Starting dynamic subtest: pipe-A-plane-3
[298.057090] Dynamic subtest pipe-A-plane-3: SUCCESS (0.165s)
[298.075590] Starting dynamic subtest: pipe-A-plane-5
[298.223818] Dynamic subtest pipe-A-plane-5: SUCCESS (0.165s)
[298.371042] Starting dynamic subtest: pipe-B-plane-0
[298.545351] Dynamic subtest pipe-B-plane-0: SUCCESS (0.183s)
[298.562903] Starting dynamic subtest: pipe-B-plane-3
[298.720442] Dynamic subtest pipe-B-plane-3: SUCCESS (0.172s)
[298.734167] Starting dynamic subtest: pipe-B-plane-5
[298.887262] Dynamic subtest pipe-B-plane-5: SUCCESS (0.165s)
[298.960412] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (1.300s)
[299.141431] [063/156] (720s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[299.239695] Starting subtest: fbc-rgb565-draw-mmap-wc
[300.494831] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (1.256s)
[300.608481] [064/156] (718s left) kms_pwrite_crc (basic)
[300.699191] Starting subtest: basic
[301.010317] Subtest basic: SUCCESS (0.311s)
[301.183571] [065/156] (718s left) gem_flink_basic (bad-flink)
[301.220501] Starting subtest: bad-flink
[301.221653] Subtest bad-flink: SUCCESS (0.000s)
[301.298417] [066/156] (718s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[301.390344] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[301.391816] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[301.507596] [067/156] (718s left) gem_ctx_persistence (legacy-engines-persistence)
[301.546662] Starting subtest: legacy-engines-persistence
[301.547442] Starting dynamic subtest: render
[302.300177] Dynamic subtest render: SUCCESS (0.752s)
[302.312657] Starting dynamic subtest: bsd1
[303.073169] Dynamic subtest bsd1: SUCCESS (0.771s)
[303.086028] Starting dynamic subtest: bsd2
[303.833279] Dynamic subtest bsd2: SUCCESS (0.758s)
[303.845855] Starting dynamic subtest: blt
[304.610359] Dynamic subtest blt: SUCCESS (0.775s)
[304.622509] Starting dynamic subtest: vebox
[305.378291] Dynamic subtest vebox: SUCCESS (0.765s)
[305.379467] Subtest legacy-engines-persistence: SUCCESS (3.833s)
[305.451088] [068/156] (714s left) gen7_exec_parse (basic-allowed)
[305.488789] Subtest basic-allowed: SKIP (0.000s)
[305.531446] [069/156] (714s left) gem_exec_schedule (reorder-wide)
[305.699151] Starting subtest: reorder-wide
[305.700167] Starting dynamic subtest: rcs0
[305.774985] Dynamic subtest rcs0: SUCCESS (0.074s)
[305.787614] Starting dynamic subtest: bcs0
[305.851125] Dynamic subtest bcs0: SUCCESS (0.074s)
[305.865331] Starting dynamic subtest: vcs0
[305.926473] Dynamic subtest vcs0: SUCCESS (0.073s)
[305.940329] Starting dynamic subtest: vcs1
[306.003201] Dynamic subtest vcs1: SUCCESS (0.074s)
[306.018241] Starting dynamic subtest: vecs0
[306.079508] Dynamic subtest vecs0: SUCCESS (0.074s)
[306.081042] Subtest reorder-wide: SUCCESS (0.382s)
[306.262924] [070/156] (713s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[306.357132] Starting subtest: psrhdr-slowdraw
[306.358537] Subtest psrhdr-slowdraw: SKIP (0.000s)
[306.456726] [071/156] (713s left) perf (non-zero-reason)
[306.559376] Starting subtest: non-zero-reason
[306.560085] Starting dynamic subtest: 0-rcs0
[314.306370] Dynamic subtest 0-rcs0: SUCCESS (7.713s)
[314.307335] Subtest non-zero-reason: SUCCESS (7.714s)
[314.345717] [072/156] (705s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[314.440372] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[314.441807] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[314.538024] [073/156] (705s left) i915_drm_fdinfo (all-busy-check-all)
[314.576186] Starting subtest: all-busy-check-all
[315.093727] Subtest all-busy-check-all: SUCCESS (0.517s)
[315.175382] [074/156] (704s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[315.267290] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[315.268692] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[315.340318] [075/156] (704s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[315.428598] Starting subtest: compare-crc-sanitycheck-nv12
[315.430427] Starting dynamic subtest: pipe-A-HDMI-A-1
[315.765632] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.335s)
[315.775577] Starting dynamic subtest: pipe-B-HDMI-A-1
[316.083421] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.317s)
[316.097945] Starting dynamic subtest: pipe-C-HDMI-A-1
[316.407961] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.322s)
[316.421165] Starting dynamic subtest: pipe-D-HDMI-A-1
[316.733653] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.323s)
[316.734399] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.306s)
[316.925345] [076/156] (703s left) gem_exec_reloc (basic-write-gtt)
[316.966765] Starting subtest: basic-write-gtt
[316.973095] Subtest basic-write-gtt: SUCCESS (0.006s)
[317.098556] [077/156] (703s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[317.192153] Starting subtest: cursor-rapid-movement-max-size
[317.194265] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[317.303118] [078/156] (702s left) kms_color_pipeline (plane-lut1d)
[317.391842] Starting subtest: plane-lut1d
[317.481144] Starting dynamic subtest: pipe-A-plane-0
[317.572263] Dynamic subtest pipe-A-plane-0: SUCCESS (0.092s)
[317.587435] Starting dynamic subtest: pipe-A-plane-1
[317.669505] Dynamic subtest pipe-A-plane-1: SUCCESS (0.097s)
[317.683755] Starting dynamic subtest: pipe-A-plane-2
[317.761324] Dynamic subtest pipe-A-plane-2: SUCCESS (0.089s)
[317.966010] Starting dynamic subtest: pipe-B-plane-0
[318.048975] Dynamic subtest pipe-B-plane-0: SUCCESS (0.091s)
[318.059382] Starting dynamic subtest: pipe-B-plane-1
[318.132938] Dynamic subtest pipe-B-plane-1: SUCCESS (0.082s)
[318.146900] Starting dynamic subtest: pipe-B-plane-2
[318.232368] Dynamic subtest pipe-B-plane-2: SUCCESS (0.096s)
[318.456973] Starting dynamic subtest: pipe-C-plane-0
[318.540129] Dynamic subtest pipe-C-plane-0: SUCCESS (0.091s)
[318.557200] Starting dynamic subtest: pipe-C-plane-1
[318.640080] Dynamic subtest pipe-C-plane-1: SUCCESS (0.098s)
[318.651143] Starting dynamic subtest: pipe-C-plane-2
[318.731925] Dynamic subtest pipe-C-plane-2: SUCCESS (0.090s)
[318.954835] Starting dynamic subtest: pipe-D-plane-0
[319.037339] Dynamic subtest pipe-D-plane-0: SUCCESS (0.092s)
[319.051699] Starting dynamic subtest: pipe-D-plane-1
[319.137380] Dynamic subtest pipe-D-plane-1: SUCCESS (0.098s)
[319.153620] Starting dynamic subtest: pipe-D-plane-2
[319.237424] Dynamic subtest pipe-D-plane-2: SUCCESS (0.098s)
[319.285746] Subtest plane-lut1d: SUCCESS (1.893s)
[319.411613] [079/156] (700s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[319.514270] Starting subtest: yf-tiled-16bpp-rotate-180
[319.515680] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[319.593712] [080/156] (700s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[319.691051] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[319.692450] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[319.797058] [081/156] (700s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[319.888846] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[319.890290] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[319.985516] [082/156] (700s left) gem_exec_reloc (basic-wc-read)
[320.024930] Starting subtest: basic-wc-read
[320.029933] Subtest basic-wc-read: SUCCESS (0.005s)
[320.162718] [083/156] (700s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[320.255753] Starting subtest: short-busy-flip-before-cursor-toggle
[320.257216] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[320.324871] [084/156] (700s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[320.417028] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[320.426952] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.009s)
[320.492735] [085/156] (699s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[320.586321] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[320.587748] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[320.684754] [086/156] (699s left) kms_draw_crc (draw-method-render)
[320.774130] Starting subtest: draw-method-render
[320.774899] Starting dynamic subtest: xrgb8888-untiled
[320.937997] Dynamic subtest xrgb8888-untiled: SUCCESS (0.163s)
[320.957921] Starting dynamic subtest: rgb565-untiled
[321.112904] Dynamic subtest rgb565-untiled: SUCCESS (0.172s)
[321.131879] Starting dynamic subtest: xrgb2101010-untiled
[321.271017] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.156s)
[321.289888] Starting dynamic subtest: xbgr16161616f-untiled
[321.437668] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.164s)
[321.454970] Starting dynamic subtest: xrgb8888-xtiled
[321.529637] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.089s)
[321.547960] Starting dynamic subtest: rgb565-xtiled
[321.621300] Dynamic subtest rgb565-xtiled: SUCCESS (0.088s)
[321.640756] Starting dynamic subtest: xrgb2101010-xtiled
[321.704319] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.081s)
[321.724256] Starting dynamic subtest: xbgr16161616f-xtiled
[321.788442] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.081s)
[321.808173] Starting dynamic subtest: xrgb8888-ytiled
[321.879581] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.089s)
[321.898291] Starting dynamic subtest: rgb565-ytiled
[321.971127] Dynamic subtest rgb565-ytiled: SUCCESS (0.089s)
[321.993956] Starting dynamic subtest: xrgb2101010-ytiled
[322.054545] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.080s)
[322.078216] Starting dynamic subtest: xbgr16161616f-ytiled
[322.146112] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.088s)
[322.149429] Subtest draw-method-render: SUCCESS (1.373s)
[322.261412] [087/156] (698s left) kms_cursor_crc (cursor-sliding-256x85)
[322.356021] Starting subtest: cursor-sliding-256x85
[322.359639] Starting dynamic subtest: pipe-A-HDMI-A-1
[323.586505] Dynamic subtest pipe-A-HDMI-A-1: FAIL (1.053s)
[323.596625] Starting dynamic subtest: pipe-D-HDMI-A-1
[325.360969] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.912s)
[325.361733] Subtest cursor-sliding-256x85: FAIL (3.006s)
[325.551052] [088/156] (695s left) kms_cursor_crc (cursor-onscreen-256x256)
[325.647170] Starting subtest: cursor-onscreen-256x256
[325.651671] Starting dynamic subtest: pipe-A-HDMI-A-1
[326.817234] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.165s)
[326.855504] Starting dynamic subtest: pipe-D-HDMI-A-1
[327.999495] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.154s)
[328.000231] Subtest cursor-onscreen-256x256: SUCCESS (2.352s)
[328.170205] [089/156] (692s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[328.268772] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[328.270164] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[328.380987] [090/156] (692s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[328.473345] Starting subtest: fbcpsr-slowdraw
[328.474760] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[328.558421] [091/156] (692s left) kms_lease (cursor-implicit-plane)
[328.646716] Starting subtest: cursor-implicit-plane
[328.648560] Starting dynamic subtest: pipe-A-HDMI-A-1
[328.821185] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.172s)
[328.833064] Starting dynamic subtest: pipe-B-HDMI-A-1
[328.987633] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.163s)
[328.999944] Starting dynamic subtest: pipe-C-HDMI-A-1
[329.162769] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.172s)
[329.173424] Starting dynamic subtest: pipe-D-HDMI-A-1
[329.334678] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.170s)
[329.335471] Subtest cursor-implicit-plane: SUCCESS (0.688s)
[329.471611] [092/156] (691s left) syncobj_wait (invalid-reset-zero-handles)
[329.513274] Starting subtest: invalid-reset-zero-handles
[329.513974] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[329.581377] [093/156] (691s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[329.677372] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[329.678851] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[329.772578] [094/156] (691s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[329.872641] Starting subtest: yf-tiled-64bpp-rotate-90
[329.874150] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[329.974306] [095/156] (690s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[330.067764] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[330.069172] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[330.161738] [096/156] (690s left) gem_exec_fence (long-history)
[330.487981] Starting subtest: long-history
[336.215172] Subtest long-history: SUCCESS (5.719s)
[336.610114] [097/156] (684s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[336.705396] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[336.706876] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[336.809178] [098/156] (684s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[336.898942] Starting subtest: bad-rotation-90-yf-tiled-ccs
[336.899685] Starting dynamic subtest: pipe-A-HDMI-A-1
[336.907016] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[336.916157] Starting dynamic subtest: pipe-B-HDMI-A-1
[336.923593] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[336.932728] Starting dynamic subtest: pipe-C-HDMI-A-1
[336.939699] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[336.948720] Starting dynamic subtest: pipe-D-HDMI-A-1
[336.956170] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[336.957629] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.007s)
[337.161639] [099/156] (683s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[337.252139] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[339.618218] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (2.362s)
[339.759944] [100/156] (681s left) kms_chamelium_audio (dp-audio-edid)
[339.912180] Subtest dp-audio-edid: SKIP (0.000s)
[340.018374] [101/156] (681s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[340.113658] Starting subtest: addfb25-yf-tiled-legacy
[340.114374] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[340.224630] [102/156] (680s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[340.316172] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[340.317593] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[340.423888] [103/156] (680s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[340.515735] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[340.517154] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[340.623222] [104/156] (680s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[340.715490] Starting subtest: fbc-2p-pri-indfb-multidraw
[340.716887] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[340.824463] [105/156] (680s left) kms_sequence (queue-busy)
[340.913745] Starting subtest: queue-busy
[340.914495] Starting dynamic subtest: pipe-A-HDMI-A-1
[342.206358] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.292s)
[342.217891] Starting dynamic subtest: pipe-B-HDMI-A-1
[343.498403] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.285s)
[343.510427] Starting dynamic subtest: pipe-C-HDMI-A-1
[344.764829] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.268s)
[344.778338] Starting dynamic subtest: pipe-D-HDMI-A-1
[346.060022] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.294s)
[346.062312] Subtest queue-busy: SUCCESS (5.148s)
[346.191305] [106/156] (675s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[346.286871] Starting subtest: plane-upscale-20x20-with-rotation
[346.287930] Starting dynamic subtest: pipe-A
[347.428875] Dynamic subtest pipe-A: SKIP (1.140s)
[347.445691] Starting dynamic subtest: pipe-B
[348.659456] Dynamic subtest pipe-B: SKIP (1.229s)
[348.669190] Starting dynamic subtest: pipe-C
[349.829814] Dynamic subtest pipe-C: SKIP (1.169s)
[349.839243] Starting dynamic subtest: pipe-D
[350.983589] Dynamic subtest pipe-D: SKIP (1.154s)
[350.985002] Subtest plane-upscale-20x20-with-rotation: SKIP (4.696s)
[351.169149] [107/156] (670s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[351.274025] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[351.275413] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[351.373066] [108/156] (670s left) gem_exec_reloc (basic-write-wc)
[351.411750] Starting subtest: basic-write-wc
[351.417308] Subtest basic-write-wc: SUCCESS (0.005s)
[351.539887] [109/156] (669s left) syncobj_timeline (reset-signaled)
[351.583427] Starting subtest: reset-signaled
[351.584215] Subtest reset-signaled: SUCCESS (0.000s)
[351.665030] [110/156] (669s left) kms_psr (pr-cursor-plane-move)
[351.757847] Subtest pr-cursor-plane-move: SKIP (0.000s)
[351.803478] [111/156] (669s left) syncobj_wait (invalid-wait-illegal-handle)
[351.838994] Starting subtest: invalid-wait-illegal-handle
[351.839713] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[351.899150] [112/156] (669s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[351.993726] Starting subtest: extended-pageflip-modeset-hang-oldfb
[351.994441] Starting dynamic subtest: pipe-A
[371.759960] Dynamic subtest pipe-A: SUCCESS (19.763s)
[371.771156] Starting dynamic subtest: pipe-D
[391.714951] Dynamic subtest pipe-D: SUCCESS (19.952s)
[391.717493] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (39.721s)
[391.862657] [113/156] (629s left) kms_psr (psr2-primary-blt)
[391.959293] Subtest psr2-primary-blt: SKIP (0.000s)
[392.005537] [114/156] (629s left) gem_softpin (evict-snoop-interruptible)
[392.046202] Starting subtest: evict-snoop-interruptible
[392.047683] Subtest evict-snoop-interruptible: SKIP (0.000s)
[392.124234] [115/156] (629s left) kms_force_connector_basic (force-load-detect)
[392.246549] Starting subtest: force-load-detect
[392.247959] Subtest force-load-detect: SKIP (0.000s)
[392.409972] [116/156] (629s left) kms_chamelium_edid (hdmi-mode-timings)
[392.555203] Subtest hdmi-mode-timings: SKIP (0.000s)
[392.657420] [117/156] (629s left) syncobj_timeline (invalid-signal-zero-handles)
[392.698693] Starting subtest: invalid-signal-zero-handles
[392.699469] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[392.758327] [118/156] (629s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[392.849947] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[392.851442] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[392.950511] [119/156] (628s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[393.044203] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[393.045620] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[393.158812] [120/156] (628s left) gem_mmap_gtt (fault-concurrent-x)
[393.192547] Starting subtest: fault-concurrent-X
[395.918381] Subtest fault-concurrent-X: SUCCESS (2.717s)
[396.034979] [121/156] (625s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[396.076007] Starting subtest: y-tiled-to-vebox-y-tiled
[396.076760] Starting dynamic subtest: smem
[396.171823] Dynamic subtest smem: SUCCESS (0.095s)
[396.172654] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.096s)
[396.405579] [122/156] (625s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[396.499834] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[396.501293] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[396.620321] [123/156] (625s left) vgem_basic (debugfs)
[396.661755] Starting subtest: debugfs
[396.662501] Subtest debugfs: SUCCESS (0.000s)
[396.735987] [124/156] (625s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[396.825939] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[396.826678] Starting dynamic subtest: pipe-A-valid-mode
[398.089611] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.263s)
[398.090381] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.265s)
[398.227559] [125/156] (623s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[398.341111] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[398.342529] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[398.442119] [126/156] (623s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[398.533878] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[398.535392] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[398.635471] [127/156] (623s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[398.728947] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[398.730485] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[398.824766] [128/156] (623s left) kms_vblank (wait-forked-hang)
[398.913646] Starting subtest: wait-forked-hang
[398.915443] Starting dynamic subtest: pipe-A-HDMI-A-1
[412.720489] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (13.805s)
[412.735733] Starting dynamic subtest: pipe-D-HDMI-A-1
[427.050516] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (14.323s)
[427.051256] Subtest wait-forked-hang: SUCCESS (28.135s)
[427.248362] [129/156] (595s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[427.343691] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[427.344413] Starting dynamic subtest: pipe-A-valid-mode
[429.239752] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.894s)
[429.240528] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (1.896s)
[429.392213] [130/156] (593s left) gem_ctx_exec (basic-invalid-context)
[429.434548] Starting subtest: basic-invalid-context
[429.435584] Subtest basic-invalid-context: SUCCESS (0.002s)
[429.553283] [131/156] (592s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[429.648061] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[429.649539] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[429.739556] [132/156] (592s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[429.828442] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[429.873687] [133/156] (592s left) gem_ppgtt (blt-vs-render-ctxn)
[429.912083] Starting subtest: blt-vs-render-ctxN
[462.578665] Subtest blt-vs-render-ctxN: SUCCESS (32.652s)
[462.696333] [134/156] (559s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[462.769034] Starting subtest: basic-gtt-cpu-noreloc
[462.772934] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.004s)
[462.889201] [135/156] (559s left) gem_softpin (invalid)
[462.927139] Starting subtest: invalid
[462.931680] Subtest invalid: SUCCESS (0.004s)
[463.044800] [136/156] (559s left) gem_exec_capture (capture)
[463.083239] Starting subtest: capture
[463.083975] Starting dynamic subtest: rcs0-smem
[464.366278] Dynamic subtest rcs0-smem: SUCCESS (1.282s)
[464.376582] Starting dynamic subtest: bcs0-smem
[465.646149] Dynamic subtest bcs0-smem: SUCCESS (1.271s)
[465.656142] Starting dynamic subtest: vcs0-smem
[466.920495] Dynamic subtest vcs0-smem: SUCCESS (1.274s)
[466.929958] Starting dynamic subtest: vcs1-smem
[468.190194] Dynamic subtest vcs1-smem: SUCCESS (1.271s)
[468.199804] Starting dynamic subtest: vecs0-smem
[469.462089] Dynamic subtest vecs0-smem: SUCCESS (1.271s)
[469.462770] Subtest capture: SUCCESS (6.374s)
[469.567110] [137/156] (553s left) gem_tiled_blits (basic)
[469.669712] Starting subtest: basic
[469.688907] Subtest basic: SUCCESS (0.019s)
[469.816447] [138/156] (552s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[469.959965] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[469.961320] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[470.050678] [139/156] (552s left) gem_mmap_offset (partial-remap)
[470.086155] Starting subtest: partial-remap
[470.086868] Starting dynamic subtest: smem0
[470.091256] Dynamic subtest smem0: SUCCESS (0.003s)
[470.091941] Subtest partial-remap: SUCCESS (0.004s)
[470.193805] [140/156] (552s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[470.288423] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[470.289911] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[470.369793] [141/156] (552s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[470.462203] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[470.463674] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[470.562776] [142/156] (552s left) i915_power (sanity)
[470.596784] Starting subtest: sanity
[474.617161] Subtest sanity: SUCCESS (4.010s)
[474.719641] [143/156] (548s left) gem_exec_schedule (preempt-queue)
[474.778326] Starting subtest: preempt-queue
[474.780463] Starting dynamic subtest: rcs0
[474.887246] Dynamic subtest rcs0: SUCCESS (0.107s)
[474.897446] Starting dynamic subtest: bcs0
[474.991173] Dynamic subtest bcs0: SUCCESS (0.103s)
[475.003470] Starting dynamic subtest: vcs0
[475.099000] Dynamic subtest vcs0: SUCCESS (0.107s)
[475.110699] Starting dynamic subtest: vcs1
[475.205997] Dynamic subtest vcs1: SUCCESS (0.106s)
[475.218196] Starting dynamic subtest: vecs0
[475.311240] Dynamic subtest vecs0: SUCCESS (0.104s)
[475.312127] Subtest preempt-queue: SUCCESS (0.533s)
[475.611898] [144/156] (547s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[475.703653] Starting subtest: cursorB-vs-flipA-legacy
[475.705437] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[475.781685] [145/156] (547s left) i915_pm_rps (reset)
[475.823971] Starting subtest: reset
[480.361175] Subtest reset: SUCCESS (4.532s)
[480.467169] [146/156] (542s left) kms_psr (fbc-psr-cursor-plane-onoff)
[480.559197] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[480.607875] [147/156] (542s left) kms_setmode (clone-exclusive-crtc)
[480.643434] Starting subtest: clone-exclusive-crtc
[480.645544] Subtest clone-exclusive-crtc: SKIP (0.000s)
[480.702979] [148/156] (542s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[480.795474] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[480.796965] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[480.896825] [149/156] (542s left) kms_flip (flip-vs-rmfb)
[480.986326] Starting subtest: flip-vs-rmfb
[480.988410] Starting dynamic subtest: A-HDMI-A1
[488.680172] Dynamic subtest A-HDMI-A1: SUCCESS (7.691s)
[488.699709] Starting dynamic subtest: B-HDMI-A1
[496.344356] Dynamic subtest B-HDMI-A1: SUCCESS (7.661s)
[496.359574] Starting dynamic subtest: C-HDMI-A1
[504.011779] Dynamic subtest C-HDMI-A1: SUCCESS (7.665s)
[504.029450] Starting dynamic subtest: D-HDMI-A1
[511.669305] Dynamic subtest D-HDMI-A1: SUCCESS (7.655s)
[511.674057] Subtest flip-vs-rmfb: SUCCESS (30.689s)
[511.844940] [150/156] (511s left) gem_exec_whisper (basic-contexts-all)
[511.907834] Starting subtest: basic-contexts-all
[513.634699] Subtest basic-contexts-all: SUCCESS (1.727s)
[513.819270] [151/156] (509s left) dmabuf_sync_file (export-basic)
[513.861227] Starting subtest: export-basic
[513.863613] Subtest export-basic: SUCCESS (0.001s)
[513.916337] [152/156] (509s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[514.012014] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[514.013399] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[514.118892] [153/156] (509s left) gem_sync (basic-each)
[514.164623] Starting subtest: basic-each
[516.286698] Subtest basic-each: SUCCESS (2.123s)
[516.468843] [154/156] (506s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[516.570898] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[516.571886] Starting dynamic subtest: pipe-A-HDMI-A-1
[516.581457] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[516.596569] Starting dynamic subtest: pipe-B-HDMI-A-1
[516.606583] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[516.620540] Starting dynamic subtest: pipe-C-HDMI-A-1
[516.629407] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[516.640976] Starting dynamic subtest: pipe-D-HDMI-A-1
[516.652315] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[516.654481] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.007s)
[516.835403] [155/156] (506s left) syncobj_wait (multi-wait-for-submit-submitted)
[516.872220] Starting subtest: multi-wait-for-submit-submitted
[516.980331] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[517.062571] [156/156] (506s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[517.158729] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[517.160161] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[517.251376] 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.