[61.098378] Initializing watchdogs
[61.098461]   /dev/watchdog0
[61.129580] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[61.144593] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.149527] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.152743] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[62.645293] Starting subtest: fbc-tiling-linear
[65.153687] Subtest fbc-tiling-linear: SUCCESS (2.517s)
[65.334452] [002/156] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[65.382136] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[65.456562] [003/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[66.948191] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[66.950133] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[67.394694] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[68.921820] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[68.923817] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[69.076668] [005/156] (952s left) kms_force_connector_basic (prune-stale-modes)
[69.212504] Starting subtest: prune-stale-modes
[69.235100] Subtest prune-stale-modes: SUCCESS (0.023s)
[70.889680] [006/156] (951s left) perf_pmu (most-busy-check-all)
[70.955607] Starting subtest: most-busy-check-all
[70.956486] Starting dynamic subtest: rcs0
[71.525534] Dynamic subtest rcs0: SUCCESS (0.570s)
[71.538195] Starting dynamic subtest: bcs0
[72.077507] Dynamic subtest bcs0: SUCCESS (0.551s)
[72.089548] Starting dynamic subtest: vcs0
[72.626130] Dynamic subtest vcs0: SUCCESS (0.549s)
[72.637560] Starting dynamic subtest: vcs1
[73.173966] Dynamic subtest vcs1: SUCCESS (0.548s)
[73.185646] Starting dynamic subtest: vecs0
[73.723199] Dynamic subtest vecs0: SUCCESS (0.549s)
[73.724297] Subtest most-busy-check-all: SUCCESS (2.771s)
[73.849333] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[73.900959] Starting subtest: large-ctrl-surf-copy
[73.902897] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[74.019025] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[75.505756] Starting subtest: coverage-7efc
[75.787854] Starting dynamic subtest: pipe-A-HDMI-A-3
[76.290392] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.502s)
[76.320543] Starting dynamic subtest: pipe-D-HDMI-A-3
[76.881367] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.571s)
[76.882252] Subtest coverage-7efc: SUCCESS (1.377s)
[77.080024] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[77.126778] Subtest fault-concurrent: SKIP (0.000s)
[77.205023] [010/156] (945s left) api_intel_allocator (gem-pool)
[77.250831] Starting subtest: gem-pool
[77.275106] Subtest gem-pool: SUCCESS (0.025s)
[77.451552] [011/156] (944s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[78.942537] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[78.944384] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[79.111062] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[80.600400] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[80.602156] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[80.731379] [013/156] (941s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[82.235904] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[82.236849] Starting dynamic subtest: pipe-A-HDMI-A-3
[82.245788] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.001s)
[82.255572] Starting dynamic subtest: pipe-B-HDMI-A-3
[82.264939] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.001s)
[82.274538] Starting dynamic subtest: pipe-C-HDMI-A-3
[82.283960] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.001s)
[82.293420] Starting dynamic subtest: pipe-D-HDMI-A-3
[82.303155] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.001s)
[82.304972] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.009s)
[82.508500] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[83.996530] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[83.998802] Starting dynamic subtest: pipe-A-HDMI-A-3
[84.007591] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[84.017289] Starting dynamic subtest: pipe-B-HDMI-A-3
[84.026288] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[84.035721] Starting dynamic subtest: pipe-C-HDMI-A-3
[84.044573] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[84.053920] Starting dynamic subtest: pipe-D-HDMI-A-3
[84.062951] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[84.064717] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[84.271385] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[85.766502] Starting subtest: hdr-rgb565-draw-blt
[85.768201] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[85.909784] [016/156] (936s left) kms_pm_rpm (legacy-planes-dpms)
[89.973413] Starting subtest: legacy-planes-dpms
[89.975511] Starting dynamic subtest: plane-65
[96.792541] Dynamic subtest plane-65: SUCCESS (6.820s)
[96.840952] Starting dynamic subtest: plane-95
[104.951738] Dynamic subtest plane-95: SUCCESS (8.158s)
[105.001539] Starting dynamic subtest: plane-125
[111.935753] Dynamic subtest plane-125: SUCCESS (6.982s)
[111.984070] Starting dynamic subtest: plane-135
[118.786230] Dynamic subtest plane-135: SUCCESS (6.848s)
[118.834284] Starting dynamic subtest: plane-145
[125.637580] Dynamic subtest plane-145: SUCCESS (6.849s)
[125.686393] Starting dynamic subtest: plane-155
[132.966462] Dynamic subtest plane-155: SUCCESS (7.326s)
[132.968589] Subtest legacy-planes-dpms: SUCCESS (42.997s)
[133.988056] [017/156] (888s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[134.041562] Starting subtest: x-tiled-to-vebox-y-tiled
[134.042540] Starting dynamic subtest: smem
[134.124207] Dynamic subtest smem: SUCCESS (0.082s)
[134.133972] Starting dynamic subtest: lmem0
[134.879548] Dynamic subtest lmem0: SUCCESS (0.752s)
[134.880489] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.835s)
[135.145757] [018/156] (887s left) sysfs_heartbeat_interval (invalid)
[135.197028] Starting subtest: invalid
[135.197929] Starting dynamic subtest: vecs0
[135.198807] Dynamic subtest vecs0: SUCCESS (0.000s)
[135.209229] Starting dynamic subtest: vcs0
[135.210243] Dynamic subtest vcs0: SUCCESS (0.000s)
[135.219806] Starting dynamic subtest: bcs0
[135.220727] Dynamic subtest bcs0: SUCCESS (0.001s)
[135.230050] Starting dynamic subtest: rcs0
[135.231226] Dynamic subtest rcs0: SUCCESS (0.000s)
[135.240863] Starting dynamic subtest: vcs1
[135.242070] Dynamic subtest vcs1: SUCCESS (0.000s)
[135.243118] Subtest invalid: SUCCESS (0.005s)
[135.322305] [019/156] (887s left) kms_frontbuffer_tracking (fbc-stridechange)
[136.814963] Starting subtest: fbc-stridechange
[138.880454] Subtest fbc-stridechange: SUCCESS (2.066s)
[139.092308] [020/156] (884s left) gem_ctx_param (basic-default)
[139.138804] Starting subtest: basic-default
[139.139679] Subtest basic-default: SUCCESS (0.000s)
[139.240281] [021/156] (884s left) kms_psr (fbc-pr-primary-mmap-gtt)
[140.728945] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[140.838417] [022/156] (882s left) i915_pm_freq_mult (media-freq)
[140.897259] Starting subtest: media-freq
[140.898166] Starting dynamic subtest: gt0
[140.900175] Dynamic subtest gt0: SKIP (0.000s)
[140.902039] Subtest media-freq: SKIP (0.001s)
[141.010713] [023/156] (882s left) prime_busy (before-wait)
[141.060310] Starting subtest: before-wait
[141.066824] Starting dynamic subtest: rcs0
[141.100818] Dynamic subtest rcs0: SUCCESS (0.034s)
[141.110782] Starting dynamic subtest: bcs0
[141.140074] Dynamic subtest bcs0: SUCCESS (0.039s)
[141.151314] Starting dynamic subtest: vcs0
[141.181065] Dynamic subtest vcs0: SUCCESS (0.040s)
[141.203498] Starting dynamic subtest: vcs1
[141.225834] Dynamic subtest vcs1: SUCCESS (0.044s)
[141.247977] Starting dynamic subtest: vecs0
[141.268225] Dynamic subtest vecs0: SUCCESS (0.041s)
[141.379679] Subtest before-wait: SUCCESS (0.316s)
[141.512787] [024/156] (881s left) gem_exec_gttfill (engines)
[141.570526] Starting subtest: engines
[141.571492] Starting dynamic subtest: rcs0
[169.090591] Dynamic subtest rcs0: SUCCESS (27.516s)
[169.101832] Starting dynamic subtest: bcs0
[196.850134] Dynamic subtest bcs0: SUCCESS (27.758s)
[196.860947] Starting dynamic subtest: vcs0
[224.641963] Dynamic subtest vcs0: SUCCESS (27.791s)
[224.652515] Starting dynamic subtest: vcs1
[252.418417] Dynamic subtest vcs1: SUCCESS (27.776s)
[252.429162] Starting dynamic subtest: vecs0
[280.141367] Dynamic subtest vecs0: SUCCESS (27.722s)
[280.142271] Subtest engines: SUCCESS (138.567s)
[280.444555] [025/156] (743s left) syncobj_timeline (etime-multi-wait-all-submitted)
[280.543922] Starting subtest: etime-multi-wait-all-submitted
[280.654130] Subtest etime-multi-wait-all-submitted: SUCCESS (0.107s)
[280.756957] [026/156] (742s left) gem_ctx_param (get-priority-new-ctx)
[280.802426] Starting subtest: get-priority-new-ctx
[280.803276] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[280.903908] [027/156] (742s left) kms_big_fb (linear-32bpp-rotate-90)
[282.420459] Starting subtest: linear-32bpp-rotate-90
[282.530630] Subtest linear-32bpp-rotate-90: SKIP (0.108s)
[282.674133] [028/156] (741s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[287.047214] Starting subtest: heavy-verify-multi-ccs
[287.048192] Starting dynamic subtest: lmem0
[287.052330] Dynamic subtest lmem0: SKIP (0.000s)
[287.054954] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[290.872761] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[290.874880] [029/156] (732s left) gem_ctx_shared (disjoint-timelines)
[294.168239] Starting subtest: disjoint-timelines
[294.246939] Subtest disjoint-timelines: SUCCESS (0.078s)
[294.567217] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[294.567907] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[294.570127] [030/156] (729s left) kms_psr (fbc-psr2-cursor-plane-move)
[296.061405] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[296.139087] [031/156] (727s left) gem_bad_reloc (negative-reloc-bltcopy)
[296.192483] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[296.318402] [032/156] (727s left) kms_chamelium_hpd (hdmi-hpd)
[297.852824] Subtest hdmi-hpd: SKIP (0.000s)
[297.985936] [033/156] (726s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[299.478903] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[299.549737] [034/156] (724s left) gem_eio (wait-10ms)
[299.685228] Starting subtest: wait-10ms
[300.022568] Subtest wait-10ms: SUCCESS (0.338s)
[301.611766] [035/156] (722s left) kms_content_protection (legacy-hdcp14)
[303.176344] Starting subtest: legacy-hdcp14
[303.181365] Subtest legacy-hdcp14: SKIP (0.002s)
[303.379151] [036/156] (720s left) syncobj_wait (reset-signaled)
[303.425345] Starting subtest: reset-signaled
[303.426247] Subtest reset-signaled: SUCCESS (0.000s)
[303.551449] [037/156] (720s left) gem_ctx_isolation (dirty-switch)
[303.611475] Starting subtest: dirty-switch
[303.612343] Starting dynamic subtest: rcs0
[304.411360] Dynamic subtest rcs0: SUCCESS (0.801s)
[304.424511] Starting dynamic subtest: bcs0
[305.258765] Dynamic subtest bcs0: SUCCESS (0.847s)
[305.270666] Starting dynamic subtest: vcs0
[306.098918] Dynamic subtest vcs0: SUCCESS (0.839s)
[306.111173] Starting dynamic subtest: vcs1
[306.940172] Dynamic subtest vcs1: SUCCESS (0.840s)
[306.951969] Starting dynamic subtest: vecs0
[307.783692] Dynamic subtest vecs0: SUCCESS (0.843s)
[307.784617] Subtest dirty-switch: SUCCESS (4.174s)
[308.061548] [038/156] (716s left) gem_flink_basic (bad-open)
[308.107927] Starting subtest: bad-open
[308.108891] Subtest bad-open: SUCCESS (0.000s)
[308.243298] [039/156] (716s left) gem_caching (writes)
[308.294971] Starting subtest: writes
[313.560399] Subtest writes: SUCCESS (5.265s)
[313.733074] [040/156] (710s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[315.219903] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[315.220874] Starting dynamic subtest: pipe-A
[316.005016] Dynamic subtest pipe-A: SUCCESS (0.785s)
[316.019541] Starting dynamic subtest: pipe-B
[316.780860] Dynamic subtest pipe-B: SUCCESS (0.774s)
[316.815648] Starting dynamic subtest: pipe-C
[317.569144] Dynamic subtest pipe-C: SUCCESS (0.785s)
[317.582158] Starting dynamic subtest: pipe-D
[318.343164] Dynamic subtest pipe-D: SUCCESS (0.773s)
[318.344223] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.124s)
[318.569891] [041/156] (706s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[318.617124] Starting subtest: single-wait-all-for-submit-available-submitted
[318.617973] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[318.741328] [042/156] (706s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[320.235675] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[320.237427] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[320.422846] [043/156] (704s left) kms_pm_rpm (system-suspend-idle)
[323.980226] Starting subtest: system-suspend-idle
[342.659808] Subtest system-suspend-idle: SUCCESS (4.387s)
[348.653750] [044/156] (681s left) kms_atomic_transition (modeset-transition)
[350.113526] Starting subtest: modeset-transition
[350.114537] Starting dynamic subtest: 1x-outputs
[352.191220] Dynamic subtest 1x-outputs: SUCCESS (2.111s)
[352.192344] Subtest modeset-transition: SUCCESS (2.112s)
[352.394191] [045/156] (677s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[353.869246] Starting subtest: pipe-fbc-rte
[353.874616] Subtest pipe-fbc-rte: SKIP (0.000s)
[354.042935] [046/156] (676s left) gem_spin_batch (resubmit-all)
[354.101700] Starting subtest: resubmit-all
[354.102789] Starting dynamic subtest: rcs0
[354.124514] Dynamic subtest rcs0: SUCCESS (0.022s)
[354.138457] Starting dynamic subtest: bcs0
[354.139484] Dynamic subtest bcs0: SUCCESS (0.002s)
[354.152710] Starting dynamic subtest: vcs0
[354.154112] Dynamic subtest vcs0: SUCCESS (0.002s)
[354.167165] Starting dynamic subtest: vcs1
[354.168491] Dynamic subtest vcs1: SUCCESS (0.003s)
[354.181313] Starting dynamic subtest: vecs0
[354.182718] Dynamic subtest vecs0: SUCCESS (0.002s)
[354.184109] Subtest resubmit-all: SUCCESS (0.037s)
[354.482469] [047/156] (675s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[355.960111] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[355.961845] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[356.131533] [048/156] (674s left) syncobj_basic (illegal-fd-to-handle)
[356.178171] Starting subtest: illegal-fd-to-handle
[356.179092] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[356.287057] [049/156] (674s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[357.768427] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[357.770274] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[357.959283] [050/156] (672s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[359.456760] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[360.722451] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.269s)
[360.912693] [051/156] (669s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[360.959950] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[361.064639] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[361.232161] [052/156] (669s left) api_intel_bb (misplaced-blitter)
[361.278581] Starting subtest: misplaced-blitter
[361.301804] Subtest misplaced-blitter: SUCCESS (0.024s)
[361.483347] [053/156] (669s left) kms_atomic_interruptible (universal-setplane-cursor)
[362.966876] Starting subtest: universal-setplane-cursor
[362.969265] Starting dynamic subtest: pipe-A-HDMI-A-3
[369.187696] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (6.237s)
[369.188548] Subtest universal-setplane-cursor: SUCCESS (6.240s)
[369.376621] [054/156] (661s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[370.881263] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[370.883388] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[371.060074] [055/156] (660s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[372.550613] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[372.552421] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[372.711823] [056/156] (658s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[374.201704] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[374.202606] Starting dynamic subtest: pipe-A-valid-mode
[375.550758] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.350s)
[375.551657] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.352s)
[375.760800] [057/156] (655s left) gem_pxp (create-protected-buffer)
[378.335339] Starting subtest: create-protected-buffer
[378.337640] Subtest create-protected-buffer: SKIP (0.000s)
[378.463124] [058/156] (652s left) gem_fence_thrash (bo-write-verify-threaded-x)
[378.507869] Starting subtest: bo-write-verify-threaded-x
[378.512579] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[378.595185] [059/156] (652s left) gen9_exec_parse (allowed-single)
[378.648621] Subtest allowed-single: SKIP (0.000s)
[378.727308] [060/156] (652s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[380.222124] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[380.223783] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[380.397521] [061/156] (651s left) gem_exec_balancer (bonded-semaphore)
[380.456201] Starting subtest: bonded-semaphore
[380.458212] Subtest bonded-semaphore: SKIP (0.000s)
[380.932761] [062/156] (650s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[382.422623] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[382.448722] Starting dynamic subtest: pipe-A-plane-0
[383.016476] Dynamic subtest pipe-A-plane-0: SUCCESS (0.566s)
[383.066019] Starting dynamic subtest: pipe-A-plane-3
[383.332343] Dynamic subtest pipe-A-plane-3: SUCCESS (0.310s)
[383.376147] Starting dynamic subtest: pipe-A-plane-5
[383.665640] Dynamic subtest pipe-A-plane-5: SUCCESS (0.330s)
[383.862702] Starting dynamic subtest: pipe-B-plane-0
[384.233003] Dynamic subtest pipe-B-plane-0: SUCCESS (0.383s)
[384.269036] Starting dynamic subtest: pipe-B-plane-3
[384.549999] Dynamic subtest pipe-B-plane-3: SUCCESS (0.314s)
[384.595832] Starting dynamic subtest: pipe-B-plane-5
[384.866352] Dynamic subtest pipe-B-plane-5: SUCCESS (0.313s)
[384.940077] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.516s)
[385.152028] [063/156] (646s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[386.646174] Starting subtest: fbc-rgb565-draw-mmap-wc
[386.648032] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[386.814084] [064/156] (645s left) kms_pwrite_crc (basic)
[388.303546] Starting subtest: basic
[388.944882] Subtest basic: SUCCESS (0.641s)
[389.161708] [065/156] (642s left) gem_flink_basic (bad-flink)
[389.207223] Starting subtest: bad-flink
[389.208148] Subtest bad-flink: SUCCESS (0.000s)
[389.341422] [066/156] (642s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[390.834587] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[390.836513] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[391.009500] [067/156] (641s left) gem_ctx_persistence (legacy-engines-persistence)
[391.079972] Starting subtest: legacy-engines-persistence
[391.080874] Starting dynamic subtest: render
[391.884541] Dynamic subtest render: SUCCESS (0.804s)
[391.902155] Starting dynamic subtest: bsd1
[392.633444] Dynamic subtest bsd1: SUCCESS (0.746s)
[392.649438] Starting dynamic subtest: bsd2
[393.415639] Dynamic subtest bsd2: SUCCESS (0.779s)
[393.433941] Starting dynamic subtest: blt
[394.166337] Dynamic subtest blt: SUCCESS (0.746s)
[394.181821] Starting dynamic subtest: vebox
[394.941686] Dynamic subtest vebox: SUCCESS (0.772s)
[394.947486] Subtest legacy-engines-persistence: SUCCESS (3.864s)
[395.101842] [068/156] (637s left) gen7_exec_parse (basic-allowed)
[395.153593] Subtest basic-allowed: SKIP (0.000s)
[395.244655] [069/156] (637s left) gem_exec_schedule (reorder-wide)
[395.424718] Subtest reorder-wide: SKIP (0.000s)
[395.685366] [070/156] (636s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[397.180254] Starting subtest: psrhdr-slowdraw
[397.182175] Subtest psrhdr-slowdraw: SKIP (0.000s)
[397.358067] [071/156] (635s left) perf (non-zero-reason)
[397.514913] Starting subtest: non-zero-reason
[397.515823] Starting dynamic subtest: 0-rcs0
[408.527380] Dynamic subtest 0-rcs0: SUCCESS (11.010s)
[408.528652] Subtest non-zero-reason: SUCCESS (11.012s)
[408.609761] [072/156] (624s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[410.103743] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[410.105453] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[410.309598] [073/156] (622s left) i915_drm_fdinfo (all-busy-check-all)
[410.364938] Starting subtest: all-busy-check-all
[410.384112] Subtest all-busy-check-all: SKIP (0.018s)
[410.493722] [074/156] (622s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[412.030558] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[412.032482] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[412.198809] [075/156] (620s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[413.689664] Starting subtest: compare-crc-sanitycheck-nv12
[413.690557] Starting dynamic subtest: pipe-A-HDMI-A-3
[414.330734] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.640s)
[414.347312] Starting dynamic subtest: pipe-B-HDMI-A-3
[414.864999] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.531s)
[414.882982] Starting dynamic subtest: pipe-C-HDMI-A-3
[415.377075] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.509s)
[415.395874] Starting dynamic subtest: pipe-D-HDMI-A-3
[415.898608] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.520s)
[415.899574] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.212s)
[416.111058] [076/156] (616s left) gem_exec_reloc (basic-write-gtt)
[416.165449] Subtest basic-write-gtt: SKIP (0.000s)
[416.285047] [077/156] (616s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[418.103105] Starting subtest: cursor-rapid-movement-max-size
[418.105874] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[418.294131] [078/156] (614s left) kms_color_pipeline (plane-lut1d)
[419.783740] Starting subtest: plane-lut1d
[419.985027] Starting dynamic subtest: pipe-A-plane-0
[420.231872] Dynamic subtest pipe-A-plane-0: SUCCESS (0.247s)
[420.279501] Starting dynamic subtest: pipe-A-plane-1
[420.431777] Dynamic subtest pipe-A-plane-1: SUCCESS (0.193s)
[420.476220] Starting dynamic subtest: pipe-A-plane-2
[420.648446] Dynamic subtest pipe-A-plane-2: SUCCESS (0.209s)
[420.968475] Starting dynamic subtest: pipe-B-plane-0
[421.124410] Dynamic subtest pipe-B-plane-0: SUCCESS (0.197s)
[421.163073] Starting dynamic subtest: pipe-B-plane-1
[421.324935] Dynamic subtest pipe-B-plane-1: SUCCESS (0.193s)
[421.362731] Starting dynamic subtest: pipe-B-plane-2
[421.527526] Dynamic subtest pipe-B-plane-2: SUCCESS (0.193s)
[421.868442] Starting dynamic subtest: pipe-C-plane-0
[422.026274] Dynamic subtest pipe-C-plane-0: SUCCESS (0.180s)
[422.061326] Starting dynamic subtest: pipe-C-plane-1
[422.237524] Dynamic subtest pipe-C-plane-1: SUCCESS (0.209s)
[422.280755] Starting dynamic subtest: pipe-C-plane-2
[422.454334] Dynamic subtest pipe-C-plane-2: SUCCESS (0.209s)
[422.781138] Starting dynamic subtest: pipe-D-plane-0
[422.961967] Dynamic subtest pipe-D-plane-0: SUCCESS (0.213s)
[423.005260] Starting dynamic subtest: pipe-D-plane-1
[423.178545] Dynamic subtest pipe-D-plane-1: SUCCESS (0.209s)
[423.228339] Starting dynamic subtest: pipe-D-plane-2
[423.393185] Dynamic subtest pipe-D-plane-2: SUCCESS (0.208s)
[423.432380] Subtest plane-lut1d: SUCCESS (3.650s)
[423.641627] [079/156] (609s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[425.151436] Starting subtest: yf-tiled-16bpp-rotate-180
[425.153246] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[425.334431] [080/156] (608s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[426.859665] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[426.861985] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[427.002259] [081/156] (606s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[428.496162] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[428.497918] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[428.671206] [082/156] (604s left) gem_exec_reloc (basic-wc-read)
[428.725330] Subtest basic-wc-read: SKIP (0.000s)
[428.848634] [083/156] (604s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[430.337001] Starting subtest: short-busy-flip-before-cursor-toggle
[430.339090] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[430.519353] [084/156] (603s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[432.029878] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[432.049886] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.019s)
[432.204121] [085/156] (601s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[433.698433] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[433.700252] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[433.861307] [086/156] (600s left) kms_draw_crc (draw-method-render)
[435.351622] Starting subtest: draw-method-render
[435.352511] Starting dynamic subtest: xrgb8888-untiled
[435.772612] Dynamic subtest xrgb8888-untiled: SUCCESS (0.419s)
[435.822323] Starting dynamic subtest: rgb565-untiled
[436.154480] Dynamic subtest rgb565-untiled: SUCCESS (0.375s)
[436.176950] Starting dynamic subtest: xrgb2101010-untiled
[436.472449] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.315s)
[436.522457] Starting dynamic subtest: xbgr16161616f-untiled
[436.854163] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.375s)
[436.870237] Starting dynamic subtest: xrgb8888-xtiled
[437.022454] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.166s)
[437.072369] Starting dynamic subtest: rgb565-xtiled
[437.222266] Dynamic subtest rgb565-xtiled: SUCCESS (0.192s)
[437.272312] Starting dynamic subtest: xrgb2101010-xtiled
[437.405622] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.175s)
[437.457032] Starting dynamic subtest: xbgr16161616f-xtiled
[437.605468] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.192s)
[437.656886] Starting dynamic subtest: xrgb8888-ytiled
[437.805500] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.192s)
[437.856790] Starting dynamic subtest: rgb565-ytiled
[438.003887] Dynamic subtest rgb565-ytiled: SUCCESS (0.192s)
[438.028323] Starting dynamic subtest: xrgb2101010-ytiled
[438.172135] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.165s)
[438.218446] Starting dynamic subtest: xbgr16161616f-ytiled
[438.371711] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[438.382334] Subtest draw-method-render: SUCCESS (3.024s)
[438.611322] [087/156] (595s left) kms_cursor_crc (cursor-sliding-256x85)
[440.345607] Starting subtest: cursor-sliding-256x85
[440.351832] Starting dynamic subtest: pipe-A-HDMI-A-3
[444.544108] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (4.191s)
[444.581727] Starting dynamic subtest: pipe-D-HDMI-A-3
[448.952595] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (4.383s)
[448.955216] Subtest cursor-sliding-256x85: SUCCESS (8.606s)
[449.297407] [088/156] (584s left) kms_cursor_crc (cursor-onscreen-256x256)
[451.000950] Starting subtest: cursor-onscreen-256x256
[451.007307] Starting dynamic subtest: pipe-A-HDMI-A-3
[453.768640] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.760s)
[453.805643] Starting dynamic subtest: pipe-D-HDMI-A-3
[456.459487] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.667s)
[456.460493] Subtest cursor-onscreen-256x256: SUCCESS (5.457s)
[456.837945] [089/156] (577s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[458.334101] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[458.336219] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[458.536739] [090/156] (575s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[460.037724] Starting subtest: fbcpsr-slowdraw
[460.039452] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[460.260486] [091/156] (574s left) kms_lease (cursor-implicit-plane)
[461.760435] Starting subtest: cursor-implicit-plane
[461.763200] Starting dynamic subtest: pipe-A-HDMI-A-3
[462.148296] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.385s)
[462.166756] Starting dynamic subtest: pipe-B-HDMI-A-3
[462.378824] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.227s)
[462.398257] Starting dynamic subtest: pipe-C-HDMI-A-3
[462.612924] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.230s)
[462.629380] Starting dynamic subtest: pipe-D-HDMI-A-3
[462.844584] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.230s)
[462.847410] Subtest cursor-implicit-plane: SUCCESS (1.085s)
[463.062358] [092/156] (571s left) syncobj_wait (invalid-reset-zero-handles)
[463.110634] Starting subtest: invalid-reset-zero-handles
[463.111539] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[463.245155] [093/156] (571s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[464.739090] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[464.740734] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[464.944389] [094/156] (569s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[466.455232] Starting subtest: yf-tiled-64bpp-rotate-90
[466.457242] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[466.638227] [095/156] (568s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[468.132925] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[468.134536] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[468.318176] [096/156] (566s left) gem_exec_fence (long-history)
[468.744268] Starting subtest: long-history
[474.837492] Subtest long-history: SUCCESS (6.090s)
[475.250713] [097/156] (560s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[476.744312] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[476.746163] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[476.921834] [098/156] (558s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[478.410706] Starting subtest: bad-rotation-90-yf-tiled-ccs
[478.411632] Starting dynamic subtest: pipe-A-HDMI-A-3
[478.420046] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[478.434111] Starting dynamic subtest: pipe-B-HDMI-A-3
[478.442282] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[478.456000] Starting dynamic subtest: pipe-C-HDMI-A-3
[478.465020] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[478.478722] Starting dynamic subtest: pipe-D-HDMI-A-3
[478.487510] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[478.489261] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[478.691237] [099/156] (556s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[480.181614] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[482.165943] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.984s)
[482.364756] [100/156] (553s left) kms_chamelium_audio (dp-audio-edid)
[483.901542] Subtest dp-audio-edid: SKIP (0.000s)
[484.039235] [101/156] (551s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[485.531247] Starting subtest: addfb25-yf-tiled-legacy
[485.532206] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[485.735234] [102/156] (549s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[487.238757] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[487.240993] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[487.429916] [103/156] (548s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[488.924083] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[488.925713] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[489.154767] [104/156] (546s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[490.649395] Starting subtest: fbc-2p-pri-indfb-multidraw
[490.651242] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[490.844986] [105/156] (545s left) kms_sequence (queue-busy)
[492.349019] Starting subtest: queue-busy
[492.349976] Starting dynamic subtest: pipe-A-HDMI-A-3
[494.910604] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.561s)
[494.926578] Starting dynamic subtest: pipe-B-HDMI-A-3
[497.334317] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (2.421s)
[497.350142] Starting dynamic subtest: pipe-C-HDMI-A-3
[499.756775] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (2.420s)
[499.774183] Starting dynamic subtest: pipe-D-HDMI-A-3
[502.190377] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.432s)
[502.191187] Subtest queue-busy: SUCCESS (9.844s)
[502.397980] [106/156] (533s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[503.887674] Starting subtest: plane-upscale-20x20-with-rotation
[503.888626] Starting dynamic subtest: pipe-A
[504.302957] Dynamic subtest pipe-A: SKIP (0.413s)
[504.317397] Starting dynamic subtest: pipe-B
[504.714808] Dynamic subtest pipe-B: SKIP (0.410s)
[504.729233] Starting dynamic subtest: pipe-C
[505.138391] Dynamic subtest pipe-C: SKIP (0.424s)
[505.152333] Starting dynamic subtest: pipe-D
[505.562751] Dynamic subtest pipe-D: SKIP (0.422s)
[505.564696] Subtest plane-upscale-20x20-with-rotation: SKIP (1.674s)
[505.767726] [107/156] (530s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[507.263506] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[507.265536] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[507.401081] [108/156] (528s left) gem_exec_reloc (basic-write-wc)
[507.453899] Subtest basic-write-wc: SKIP (0.000s)
[507.562156] [109/156] (528s left) syncobj_timeline (reset-signaled)
[507.611028] Starting subtest: reset-signaled
[507.612084] Subtest reset-signaled: SUCCESS (0.000s)
[507.730920] [110/156] (528s left) kms_psr (pr-cursor-plane-move)
[509.218935] Subtest pr-cursor-plane-move: SKIP (0.000s)
[509.411424] [111/156] (527s left) syncobj_wait (invalid-wait-illegal-handle)
[509.484788] Starting subtest: invalid-wait-illegal-handle
[509.485788] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[509.609433] [112/156] (527s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[511.103727] Starting subtest: extended-pageflip-modeset-hang-oldfb
[511.105196] Starting dynamic subtest: pipe-A
[518.848802] Dynamic subtest pipe-A: SUCCESS (7.744s)
[518.866321] Starting dynamic subtest: pipe-D
[526.578273] Dynamic subtest pipe-D: SUCCESS (7.726s)
[526.579259] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.478s)
[526.816417] [113/156] (510s left) kms_psr (psr2-primary-blt)
[528.305141] Subtest psr2-primary-blt: SKIP (0.000s)
[528.384642] [114/156] (508s left) gem_softpin (evict-snoop-interruptible)
[528.440124] Starting subtest: evict-snoop-interruptible
[528.441916] Subtest evict-snoop-interruptible: SKIP (0.000s)
[528.580216] [115/156] (508s left) kms_force_connector_basic (force-load-detect)
[528.713199] Starting subtest: force-load-detect
[528.715129] Subtest force-load-detect: SKIP (0.000s)
[529.658347] [116/156] (507s left) kms_chamelium_edid (hdmi-mode-timings)
[531.194593] Subtest hdmi-mode-timings: SKIP (0.000s)
[531.330136] [117/156] (505s left) syncobj_timeline (invalid-signal-zero-handles)
[531.376640] Starting subtest: invalid-signal-zero-handles
[531.377568] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[531.527094] [118/156] (505s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[533.017492] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[533.019230] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[533.193417] [119/156] (504s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[534.687165] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[534.688971] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[534.863146] [120/156] (502s left) gem_mmap_gtt (fault-concurrent-x)
[534.909425] Subtest fault-concurrent-X: SKIP (0.000s)
[534.993923] [121/156] (502s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[535.048552] Starting subtest: y-tiled-to-vebox-y-tiled
[535.049548] Starting dynamic subtest: smem
[535.132078] Dynamic subtest smem: SUCCESS (0.083s)
[535.148170] Starting dynamic subtest: lmem0
[535.880903] Dynamic subtest lmem0: SUCCESS (0.744s)
[535.882209] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.830s)
[536.149773] [122/156] (501s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[537.642531] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[537.644213] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[537.832461] [123/156] (499s left) vgem_basic (debugfs)
[537.880330] Starting subtest: debugfs
[537.881225] Subtest debugfs: SUCCESS (0.000s)
[537.979795] [124/156] (499s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[539.466723] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[539.469273] Starting dynamic subtest: pipe-A-valid-mode
[540.737390] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.269s)
[540.738254] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.272s)
[540.950181] [125/156] (496s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[542.444646] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[542.446515] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[542.618691] [126/156] (495s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[544.113925] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[544.115642] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[544.313252] [127/156] (493s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[545.824190] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[545.826589] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[546.001808] [128/156] (492s left) kms_vblank (wait-forked-hang)
[547.491707] Starting subtest: wait-forked-hang
[547.493097] Starting dynamic subtest: pipe-A-HDMI-A-3
[573.754551] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (26.266s)
[573.771979] Starting dynamic subtest: pipe-D-HDMI-A-3
[598.766703] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (25.000s)
[598.767616] Subtest wait-forked-hang: SUCCESS (51.274s)
[598.972968] [129/156] (439s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[600.462573] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[600.463582] Starting dynamic subtest: pipe-A-valid-mode
[603.052627] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.587s)
[603.053533] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.590s)
[603.257435] [130/156] (435s left) gem_ctx_exec (basic-invalid-context)
[603.305863] Starting subtest: basic-invalid-context
[603.310474] Subtest basic-invalid-context: SUCCESS (0.005s)
[603.525046] [131/156] (435s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[605.020453] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[605.022219] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[605.194895] [132/156] (433s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[606.687150] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[606.762982] [133/156] (431s left) gem_ppgtt (blt-vs-render-ctxn)
[606.815158] Starting subtest: blt-vs-render-ctxN
[639.562839] Subtest blt-vs-render-ctxN: SUCCESS (32.742s)
[639.801773] [134/156] (399s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[639.910454] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[640.014808] [135/156] (398s left) gem_softpin (invalid)
[640.067700] Starting subtest: invalid
[640.078051] Subtest invalid: SUCCESS (0.012s)
[640.255225] [136/156] (398s left) gem_exec_capture (capture)
[640.308807] Starting subtest: capture
[640.309668] Starting dynamic subtest: rcs0-smem
[641.208860] Dynamic subtest rcs0-smem: SUCCESS (0.899s)
[641.224935] Starting dynamic subtest: rcs0-lmem0
[642.090844] Dynamic subtest rcs0-lmem0: SUCCESS (0.880s)
[642.105396] Starting dynamic subtest: bcs0-smem
[643.463055] Dynamic subtest bcs0-smem: SUCCESS (1.371s)
[643.479446] Starting dynamic subtest: bcs0-lmem0
[644.480863] Dynamic subtest bcs0-lmem0: SUCCESS (1.016s)
[644.495409] Starting dynamic subtest: vcs0-smem
[645.340761] Dynamic subtest vcs0-smem: SUCCESS (0.858s)
[645.355518] Starting dynamic subtest: vcs0-lmem0
[646.196789] Dynamic subtest vcs0-lmem0: SUCCESS (0.855s)
[646.211414] Starting dynamic subtest: vcs1-smem
[647.053345] Dynamic subtest vcs1-smem: SUCCESS (0.854s)
[647.067924] Starting dynamic subtest: vcs1-lmem0
[647.908861] Dynamic subtest vcs1-lmem0: SUCCESS (0.855s)
[647.923467] Starting dynamic subtest: vecs0-smem
[649.319197] Dynamic subtest vecs0-smem: FAIL (1.387s)
[649.333661] Starting dynamic subtest: vecs0-lmem0
[650.316666] Dynamic subtest vecs0-lmem0: FAIL (0.991s)
[650.317491] Subtest capture: FAIL (9.982s)
[650.479618] [137/156] (388s left) gem_tiled_blits (basic)
[650.531217] Subtest basic: SKIP (0.000s)
[650.613566] [138/156] (388s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[652.112273] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[652.114169] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[652.315617] [139/156] (386s left) gem_mmap_offset (partial-remap)
[652.365473] Starting subtest: partial-remap
[652.366470] Starting dynamic subtest: lmem0
[652.370957] Dynamic subtest lmem0: SUCCESS (0.006s)
[652.385824] Starting dynamic subtest: smem0
[652.388175] Dynamic subtest smem0: SUCCESS (0.005s)
[652.389596] Subtest partial-remap: SUCCESS (0.014s)
[652.546841] [140/156] (386s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[654.040829] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[654.042737] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[654.191983] [141/156] (385s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[655.687229] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[655.689170] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[655.866783] [142/156] (383s left) i915_power (sanity)
[655.912320] Starting subtest: sanity
[659.951424] Subtest sanity: SUCCESS (4.035s)
[660.105055] [143/156] (379s left) gem_exec_schedule (preempt-queue)
[660.176892] Subtest preempt-queue: SKIP (0.000s)
[660.569795] [144/156] (379s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[662.057710] Starting subtest: cursorB-vs-flipA-legacy
[662.061077] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[662.176552] [145/156] (377s left) i915_pm_rps (reset)
[662.235824] Starting subtest: reset
[666.967504] Subtest reset: SUCCESS (4.732s)
[667.178065] [146/156] (372s left) kms_psr (fbc-psr-cursor-plane-onoff)
[668.665996] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[668.748139] [147/156] (371s left) kms_setmode (clone-exclusive-crtc)
[668.794814] Starting subtest: clone-exclusive-crtc
[668.797524] Subtest clone-exclusive-crtc: SKIP (0.000s)
[668.932839] [148/156] (371s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[670.442794] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[670.444509] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[670.643990] [149/156] (369s left) kms_flip (flip-vs-rmfb)
[672.134717] Starting subtest: flip-vs-rmfb
[672.140309] Starting dynamic subtest: A-HDMI-A3
[679.924482] Dynamic subtest A-HDMI-A3: SUCCESS (7.785s)
[679.966821] Starting dynamic subtest: B-HDMI-A3
[687.669503] Dynamic subtest B-HDMI-A3: SUCCESS (7.742s)
[687.700489] Starting dynamic subtest: C-HDMI-A3
[695.412482] Dynamic subtest C-HDMI-A3: SUCCESS (7.739s)
[695.461637] Starting dynamic subtest: D-HDMI-A3
[703.173406] Dynamic subtest D-HDMI-A3: SUCCESS (7.756s)
[703.178560] Subtest flip-vs-rmfb: SUCCESS (31.048s)
[703.423056] [150/156] (336s left) gem_exec_whisper (basic-contexts-all)
[703.481800] Starting subtest: basic-contexts-all
[710.412478] Subtest basic-contexts-all: SUCCESS (6.927s)
[710.716674] [151/156] (329s left) dmabuf_sync_file (export-basic)
[710.779520] Starting subtest: export-basic
[710.780421] Subtest export-basic: SUCCESS (0.001s)
[710.886092] [152/156] (329s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[712.381153] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[712.383100] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[712.562248] [153/156] (328s left) gem_sync (basic-each)
[712.622544] Starting subtest: basic-each
[715.019589] Subtest basic-each: SUCCESS (2.397s)
[715.295900] [154/156] (325s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[716.784662] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[716.785667] Starting dynamic subtest: pipe-A-HDMI-A-3
[716.795048] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[716.810191] Starting dynamic subtest: pipe-B-HDMI-A-3
[716.819177] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[716.834007] Starting dynamic subtest: pipe-C-HDMI-A-3
[716.843145] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[716.857664] Starting dynamic subtest: pipe-D-HDMI-A-3
[716.867364] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[716.869333] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.011s)
[717.064692] [155/156] (323s left) syncobj_wait (multi-wait-for-submit-submitted)
[717.113343] Starting subtest: multi-wait-for-submit-submitted
[717.224860] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[717.369280] [156/156] (323s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[718.863059] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[718.864673] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[719.027044] 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.