[68.062726] Initializing watchdogs
[68.062807]   /dev/watchdog0
[68.093681] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[68.108740] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[68.113716] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.117287] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[69.630278] Starting subtest: fbc-tiling-linear
[72.685556] Subtest fbc-tiling-linear: SUCCESS (3.056s)
[72.885466] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[72.936047] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[73.007121] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[74.504417] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[74.507341] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[74.668737] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[76.207916] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[76.210973] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[76.360708] [005/156] (952s left) kms_force_connector_basic (prune-stale-modes)
[76.505111] Starting subtest: prune-stale-modes
[76.527125] Subtest prune-stale-modes: SUCCESS (0.022s)
[78.188610] [006/156] (950s left) perf_pmu (most-busy-check-all)
[78.257011] Starting subtest: most-busy-check-all
[78.258695] Starting dynamic subtest: rcs0
[78.826850] Dynamic subtest rcs0: SUCCESS (0.569s)
[78.840432] Starting dynamic subtest: bcs0
[79.378881] Dynamic subtest bcs0: SUCCESS (0.551s)
[79.390722] Starting dynamic subtest: vcs0
[79.926834] Dynamic subtest vcs0: SUCCESS (0.547s)
[79.938384] Starting dynamic subtest: vcs1
[80.474479] Dynamic subtest vcs1: SUCCESS (0.547s)
[80.486039] Starting dynamic subtest: vecs0
[81.024096] Dynamic subtest vecs0: SUCCESS (0.549s)
[81.026015] Subtest most-busy-check-all: SUCCESS (2.766s)
[81.138891] [007/156] (947s left) gem_ccs (large-ctrl-surf-copy)
[81.192229] Starting subtest: large-ctrl-surf-copy
[81.196021] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[81.301102] [008/156] (947s left) kms_plane_alpha_blend (coverage-7efc)
[82.790802] Starting subtest: coverage-7efc
[83.070351] Starting dynamic subtest: pipe-A-HDMI-A-4
[83.578200] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.506s)
[83.607166] Starting dynamic subtest: pipe-D-HDMI-A-4
[84.171848] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.574s)
[84.173511] Subtest coverage-7efc: SUCCESS (1.382s)
[84.363316] [009/156] (944s left) gem_mmap_gtt (fault-concurrent)
[84.414832] Subtest fault-concurrent: SKIP (0.000s)
[84.492040] [010/156] (944s left) api_intel_allocator (gem-pool)
[84.538455] Starting subtest: gem-pool
[84.561695] Subtest gem-pool: SUCCESS (0.025s)
[84.741701] [011/156] (944s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[86.236967] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[86.239826] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[86.416645] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[87.907242] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[87.910157] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[88.024428] [013/156] (941s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[89.530817] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[89.532911] Starting dynamic subtest: pipe-A-HDMI-A-4
[89.551171] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[89.561505] Starting dynamic subtest: pipe-B-HDMI-A-4
[89.580159] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[89.590185] Starting dynamic subtest: pipe-C-HDMI-A-4
[89.604409] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[89.613724] Starting dynamic subtest: pipe-D-HDMI-A-4
[89.629681] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[89.632720] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[89.798249] [014/156] (939s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[91.291576] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[91.293019] Starting dynamic subtest: pipe-A-HDMI-A-4
[91.307691] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[91.317748] Starting dynamic subtest: pipe-B-HDMI-A-4
[91.332642] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[91.342373] Starting dynamic subtest: pipe-C-HDMI-A-4
[91.357763] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[91.367542] Starting dynamic subtest: pipe-D-HDMI-A-4
[91.384188] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[91.390265] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[91.559668] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[93.062475] Starting subtest: hdr-rgb565-draw-blt
[93.065898] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[93.242635] [016/156] (936s left) kms_pm_rpm (legacy-planes-dpms)
[97.098547] Starting subtest: legacy-planes-dpms
[97.101282] Starting dynamic subtest: plane-65
[103.985265] Dynamic subtest plane-65: SUCCESS (6.891s)
[104.033942] Starting dynamic subtest: plane-95
[110.831636] Dynamic subtest plane-95: SUCCESS (6.844s)
[110.879863] Starting dynamic subtest: plane-125
[117.824182] Dynamic subtest plane-125: SUCCESS (6.990s)
[117.872253] Starting dynamic subtest: plane-135
[124.812263] Dynamic subtest plane-135: SUCCESS (6.986s)
[124.860112] Starting dynamic subtest: plane-145
[131.768119] Dynamic subtest plane-145: SUCCESS (6.954s)
[131.816767] Starting dynamic subtest: plane-155
[138.622255] Dynamic subtest plane-155: SUCCESS (6.851s)
[138.625206] Subtest legacy-planes-dpms: SUCCESS (41.528s)
[139.646316] [017/156] (890s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[139.704984] Starting subtest: x-tiled-to-vebox-y-tiled
[139.706532] Starting dynamic subtest: smem
[139.787833] Dynamic subtest smem: SUCCESS (0.083s)
[139.798111] Starting dynamic subtest: lmem0
[140.547291] Dynamic subtest lmem0: SUCCESS (0.756s)
[140.548935] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.839s)
[140.802788] [018/156] (889s left) sysfs_heartbeat_interval (invalid)
[140.855943] Starting subtest: invalid
[140.857574] Starting dynamic subtest: vecs0
[140.859116] Dynamic subtest vecs0: SUCCESS (0.000s)
[140.869428] Starting dynamic subtest: vcs0
[140.871116] Dynamic subtest vcs0: SUCCESS (0.001s)
[140.881356] Starting dynamic subtest: bcs0
[140.883186] Dynamic subtest bcs0: SUCCESS (0.000s)
[140.893218] Starting dynamic subtest: rcs0
[140.894897] Dynamic subtest rcs0: SUCCESS (0.000s)
[140.904927] Starting dynamic subtest: vcs1
[140.906736] Dynamic subtest vcs1: SUCCESS (0.000s)
[140.909027] Subtest invalid: SUCCESS (0.005s)
[140.968121] [019/156] (889s left) kms_frontbuffer_tracking (fbc-stridechange)
[142.465823] Starting subtest: fbc-stridechange
[144.476104] Subtest fbc-stridechange: SUCCESS (2.013s)
[144.693014] [020/156] (885s left) gem_ctx_param (basic-default)
[144.740179] Starting subtest: basic-default
[144.741651] Subtest basic-default: SUCCESS (0.000s)
[144.870755] [021/156] (885s left) kms_psr (fbc-pr-primary-mmap-gtt)
[146.364406] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[146.432373] [022/156] (883s left) i915_pm_freq_mult (media-freq)
[146.483047] Starting subtest: media-freq
[146.491235] Starting dynamic subtest: gt0
[146.493978] Dynamic subtest gt0: SKIP (0.000s)
[146.496955] Subtest media-freq: SKIP (0.001s)
[146.596321] [023/156] (883s left) prime_busy (before-wait)
[146.648383] Starting subtest: before-wait
[146.654777] Starting dynamic subtest: rcs0
[146.691051] Dynamic subtest rcs0: SUCCESS (0.036s)
[146.701340] Starting dynamic subtest: bcs0
[146.731692] Dynamic subtest bcs0: SUCCESS (0.040s)
[146.743034] Starting dynamic subtest: vcs0
[146.774019] Dynamic subtest vcs0: SUCCESS (0.042s)
[146.796113] Starting dynamic subtest: vcs1
[146.820275] Dynamic subtest vcs1: SUCCESS (0.045s)
[146.849525] Starting dynamic subtest: vecs0
[146.866925] Dynamic subtest vecs0: SUCCESS (0.045s)
[146.992689] Subtest before-wait: SUCCESS (0.340s)
[147.195704] [024/156] (883s left) gem_exec_gttfill (engines)
[147.255498] Starting subtest: engines
[147.257049] Starting dynamic subtest: rcs0
[174.792953] Dynamic subtest rcs0: SUCCESS (27.534s)
[174.803981] Starting dynamic subtest: bcs0
[202.605711] Dynamic subtest bcs0: SUCCESS (27.811s)
[202.616832] Starting dynamic subtest: vcs0
[230.427218] Dynamic subtest vcs0: SUCCESS (27.821s)
[230.438413] Starting dynamic subtest: vcs1
[258.168779] Dynamic subtest vcs1: SUCCESS (27.740s)
[258.179968] Starting dynamic subtest: vecs0
[286.026303] Dynamic subtest vecs0: SUCCESS (27.857s)
[286.027998] Subtest engines: SUCCESS (138.766s)
[286.439965] [025/156] (744s left) syncobj_timeline (etime-multi-wait-all-submitted)
[286.549889] Starting subtest: etime-multi-wait-all-submitted
[286.661536] Subtest etime-multi-wait-all-submitted: SUCCESS (0.109s)
[286.768297] [026/156] (743s left) gem_ctx_param (get-priority-new-ctx)
[286.815923] Starting subtest: get-priority-new-ctx
[286.817490] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[286.919694] [027/156] (743s left) kms_big_fb (linear-32bpp-rotate-90)
[288.446027] Starting subtest: linear-32bpp-rotate-90
[288.546831] Subtest linear-32bpp-rotate-90: SKIP (0.099s)
[288.688487] [028/156] (741s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[293.035906] Starting subtest: heavy-verify-multi-ccs
[293.037612] Starting dynamic subtest: lmem0
[293.040821] Dynamic subtest lmem0: SKIP (0.000s)
[293.044105] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[296.904077] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[296.906569] [029/156] (733s left) gem_ctx_shared (disjoint-timelines)
[300.197491] Starting subtest: disjoint-timelines
[300.272259] Subtest disjoint-timelines: SUCCESS (0.076s)
[300.589727] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[300.590654] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[300.593797] [030/156] (730s left) kms_psr (fbc-psr2-cursor-plane-move)
[302.090687] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[302.169402] [031/156] (728s left) gem_bad_reloc (negative-reloc-bltcopy)
[302.229054] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[302.323021] [032/156] (728s left) kms_chamelium_hpd (hdmi-hpd)
[303.869521] Subtest hdmi-hpd: SKIP (0.000s)
[304.003033] [033/156] (727s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[305.499501] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[305.582451] [034/156] (725s left) gem_eio (wait-10ms)
[305.720375] Starting subtest: wait-10ms
[306.098382] Subtest wait-10ms: SUCCESS (0.379s)
[307.704883] [035/156] (723s left) kms_content_protection (legacy-hdcp14)
[309.279994] Starting subtest: legacy-hdcp14
[309.285137] Subtest legacy-hdcp14: SKIP (0.002s)
[309.463897] [036/156] (721s left) syncobj_wait (reset-signaled)
[309.513212] Starting subtest: reset-signaled
[309.515179] Subtest reset-signaled: SUCCESS (0.000s)
[309.643330] [037/156] (721s left) gem_ctx_isolation (dirty-switch)
[309.700969] Starting subtest: dirty-switch
[309.702765] Starting dynamic subtest: rcs0
[310.509217] Dynamic subtest rcs0: SUCCESS (0.808s)
[310.521630] Starting dynamic subtest: bcs0
[311.335312] Dynamic subtest bcs0: SUCCESS (0.825s)
[311.348472] Starting dynamic subtest: vcs0
[312.171773] Dynamic subtest vcs0: SUCCESS (0.836s)
[312.184258] Starting dynamic subtest: vcs1
[313.015077] Dynamic subtest vcs1: SUCCESS (0.843s)
[313.027461] Starting dynamic subtest: vecs0
[313.867178] Dynamic subtest vecs0: SUCCESS (0.851s)
[313.868710] Subtest dirty-switch: SUCCESS (4.167s)
[314.166400] [038/156] (717s left) gem_flink_basic (bad-open)
[314.214644] Starting subtest: bad-open
[314.216153] Subtest bad-open: SUCCESS (0.000s)
[314.325263] [039/156] (717s left) gem_caching (writes)
[314.380203] Starting subtest: writes
[319.627151] Subtest writes: SUCCESS (5.250s)
[319.780184] [040/156] (711s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[321.282580] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[321.284099] Starting dynamic subtest: pipe-A
[322.070479] Dynamic subtest pipe-A: SUCCESS (0.790s)
[322.084985] Starting dynamic subtest: pipe-B
[322.844660] Dynamic subtest pipe-B: SUCCESS (0.773s)
[322.859985] Starting dynamic subtest: pipe-C
[323.617939] Dynamic subtest pipe-C: SUCCESS (0.772s)
[323.630796] Starting dynamic subtest: pipe-D
[324.390827] Dynamic subtest pipe-D: SUCCESS (0.772s)
[324.392682] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.111s)
[324.616803] [041/156] (707s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[324.670747] Starting subtest: single-wait-all-for-submit-available-submitted
[324.672300] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[324.784387] [042/156] (706s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[326.284410] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[326.290531] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[326.443047] [043/156] (705s left) kms_pm_rpm (system-suspend-idle)
[330.013587] Starting subtest: system-suspend-idle
[349.519783] Subtest system-suspend-idle: SUCCESS (4.652s)
[354.597903] [044/156] (681s left) kms_atomic_transition (modeset-transition)
[356.109045] Starting subtest: modeset-transition
[356.110616] Starting dynamic subtest: 1x-outputs
[358.233938] Dynamic subtest 1x-outputs: SUCCESS (2.107s)
[358.235436] Subtest modeset-transition: SUCCESS (2.108s)
[358.439574] [045/156] (677s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[359.968450] Starting subtest: pipe-fbc-rte
[359.978079] Subtest pipe-fbc-rte: SKIP (0.000s)
[360.188295] [046/156] (676s left) gem_spin_batch (resubmit-all)
[360.247408] Starting subtest: resubmit-all
[360.249015] Starting dynamic subtest: rcs0
[360.271361] Dynamic subtest rcs0: SUCCESS (0.023s)
[360.286733] Starting dynamic subtest: bcs0
[360.288533] Dynamic subtest bcs0: SUCCESS (0.002s)
[360.302765] Starting dynamic subtest: vcs0
[360.304617] Dynamic subtest vcs0: SUCCESS (0.003s)
[360.318522] Starting dynamic subtest: vcs1
[360.320647] Dynamic subtest vcs1: SUCCESS (0.002s)
[360.334021] Starting dynamic subtest: vecs0
[360.335752] Dynamic subtest vecs0: SUCCESS (0.002s)
[360.337548] Subtest resubmit-all: SUCCESS (0.039s)
[360.648382] [047/156] (675s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[362.157802] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[362.161581] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[362.345317] [048/156] (674s left) syncobj_basic (illegal-fd-to-handle)
[362.395197] Starting subtest: illegal-fd-to-handle
[362.397054] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[362.528420] [049/156] (673s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[364.033424] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[364.036560] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[364.212802] [050/156] (672s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[365.729593] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[366.995181] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.258s)
[367.213237] [051/156] (669s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[367.264912] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[367.367146] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[367.499605] [052/156] (669s left) api_intel_bb (misplaced-blitter)
[367.548573] Starting subtest: misplaced-blitter
[367.569189] Subtest misplaced-blitter: SUCCESS (0.021s)
[367.798349] [053/156] (669s left) kms_atomic_interruptible (universal-setplane-cursor)
[369.308416] Starting subtest: universal-setplane-cursor
[369.310400] Starting dynamic subtest: pipe-A-HDMI-A-4
[375.516470] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.199s)
[375.517878] Subtest universal-setplane-cursor: SUCCESS (6.201s)
[375.718914] [054/156] (661s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[377.231410] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[377.234251] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[377.425379] [055/156] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[378.935691] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[378.938939] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[379.097089] [056/156] (658s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[380.591507] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[380.592857] Starting dynamic subtest: pipe-A-valid-mode
[381.941845] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.351s)
[381.943474] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.353s)
[382.133317] [057/156] (655s left) gem_pxp (create-protected-buffer)
[384.710186] Starting subtest: create-protected-buffer
[384.713646] Subtest create-protected-buffer: SKIP (0.000s)
[384.818217] [058/156] (652s left) gem_fence_thrash (bo-write-verify-threaded-x)
[384.865459] Starting subtest: bo-write-verify-threaded-x
[384.870990] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[384.957719] [059/156] (652s left) gen9_exec_parse (allowed-single)
[385.014968] Subtest allowed-single: SKIP (0.000s)
[385.091238] [060/156] (652s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[386.590370] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[386.593428] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[386.781925] [061/156] (650s left) gem_exec_balancer (bonded-semaphore)
[386.843682] Starting subtest: bonded-semaphore
[386.847071] Subtest bonded-semaphore: SKIP (0.000s)
[387.328601] [062/156] (650s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[388.819511] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[388.842085] Starting dynamic subtest: pipe-A-plane-0
[389.376114] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[389.424741] Starting dynamic subtest: pipe-A-plane-3
[389.692070] Dynamic subtest pipe-A-plane-3: SUCCESS (0.310s)
[389.741222] Starting dynamic subtest: pipe-A-plane-5
[390.024749] Dynamic subtest pipe-A-plane-5: SUCCESS (0.329s)
[390.205789] Starting dynamic subtest: pipe-B-plane-0
[390.575542] Dynamic subtest pipe-B-plane-0: SUCCESS (0.383s)
[390.593335] Starting dynamic subtest: pipe-B-plane-3
[390.926291] Dynamic subtest pipe-B-plane-3: SUCCESS (0.349s)
[390.973169] Starting dynamic subtest: pipe-B-plane-5
[391.259033] Dynamic subtest pipe-B-plane-5: SUCCESS (0.329s)
[391.331457] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.514s)
[391.511448] [063/156] (646s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[393.014977] Starting subtest: fbc-rgb565-draw-mmap-wc
[393.019088] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[393.188861] [064/156] (644s left) kms_pwrite_crc (basic)
[394.720705] Starting subtest: basic
[395.361731] Subtest basic: SUCCESS (0.643s)
[395.561315] [065/156] (642s left) gem_flink_basic (bad-flink)
[395.628641] Starting subtest: bad-flink
[395.630858] Subtest bad-flink: SUCCESS (0.000s)
[395.746342] [066/156] (642s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[397.247293] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[397.250703] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[397.422056] [067/156] (640s left) gem_ctx_persistence (legacy-engines-persistence)
[397.486209] Starting subtest: legacy-engines-persistence
[397.487887] Starting dynamic subtest: render
[398.265677] Dynamic subtest render: SUCCESS (0.788s)
[398.283127] Starting dynamic subtest: bsd1
[399.005568] Dynamic subtest bsd1: SUCCESS (0.737s)
[399.024451] Starting dynamic subtest: bsd2
[399.801734] Dynamic subtest bsd2: SUCCESS (0.794s)
[399.820078] Starting dynamic subtest: blt
[400.571720] Dynamic subtest blt: SUCCESS (0.767s)
[400.589074] Starting dynamic subtest: vebox
[401.331673] Dynamic subtest vebox: SUCCESS (0.757s)
[401.333373] Subtest legacy-engines-persistence: SUCCESS (3.855s)
[401.520818] [068/156] (636s left) gen7_exec_parse (basic-allowed)
[401.576464] Subtest basic-allowed: SKIP (0.000s)
[401.656383] [069/156] (636s left) gem_exec_schedule (reorder-wide)
[401.882529] Subtest reorder-wide: SKIP (0.000s)
[402.156569] [070/156] (636s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[403.656418] Starting subtest: psrhdr-slowdraw
[403.659479] Subtest psrhdr-slowdraw: SKIP (0.000s)
[403.845415] [071/156] (634s left) perf (non-zero-reason)
[404.143640] Starting subtest: non-zero-reason
[404.145252] Starting dynamic subtest: 0-rcs0
[415.397483] Dynamic subtest 0-rcs0: SUCCESS (11.246s)
[415.399272] Subtest non-zero-reason: SUCCESS (11.248s)
[415.480650] [072/156] (623s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[416.981060] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[416.984377] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[417.159663] [073/156] (621s left) i915_drm_fdinfo (all-busy-check-all)
[417.214852] Starting subtest: all-busy-check-all
[417.235749] Subtest all-busy-check-all: SKIP (0.020s)
[417.352245] [074/156] (621s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[418.891677] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[418.894757] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[419.041049] [075/156] (619s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[420.540759] Starting subtest: compare-crc-sanitycheck-nv12
[420.542324] Starting dynamic subtest: pipe-A-HDMI-A-4
[421.188223] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.645s)
[421.206740] Starting dynamic subtest: pipe-B-HDMI-A-4
[421.722275] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.529s)
[421.737565] Starting dynamic subtest: pipe-C-HDMI-A-4
[422.227357] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.501s)
[422.243967] Starting dynamic subtest: pipe-D-HDMI-A-4
[422.743960] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.513s)
[422.745820] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.201s)
[422.954778] [076/156] (616s left) gem_exec_reloc (basic-write-gtt)
[423.022308] Subtest basic-write-gtt: SKIP (0.000s)
[423.125976] [077/156] (615s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[424.949890] Starting subtest: cursor-rapid-movement-max-size
[424.954416] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[425.161065] [078/156] (614s left) kms_color_pipeline (plane-lut1d)
[426.656306] Starting subtest: plane-lut1d
[426.860120] Starting dynamic subtest: pipe-A-plane-0
[427.122261] Dynamic subtest pipe-A-plane-0: SUCCESS (0.263s)
[427.146918] Starting dynamic subtest: pipe-A-plane-1
[427.308160] Dynamic subtest pipe-A-plane-1: SUCCESS (0.181s)
[427.349698] Starting dynamic subtest: pipe-A-plane-2
[427.511756] Dynamic subtest pipe-A-plane-2: SUCCESS (0.193s)
[427.841892] Starting dynamic subtest: pipe-B-plane-0
[427.998222] Dynamic subtest pipe-B-plane-0: SUCCESS (0.196s)
[428.014707] Starting dynamic subtest: pipe-B-plane-1
[428.170252] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[428.206565] Starting dynamic subtest: pipe-B-plane-2
[428.372797] Dynamic subtest pipe-B-plane-2: SUCCESS (0.193s)
[428.700902] Starting dynamic subtest: pipe-C-plane-0
[428.879992] Dynamic subtest pipe-C-plane-0: SUCCESS (0.213s)
[428.924415] Starting dynamic subtest: pipe-C-plane-1
[429.095938] Dynamic subtest pipe-C-plane-1: SUCCESS (0.209s)
[429.125655] Starting dynamic subtest: pipe-C-plane-2
[429.297142] Dynamic subtest pipe-C-plane-2: SUCCESS (0.195s)
[429.621275] Starting dynamic subtest: pipe-D-plane-0
[429.785798] Dynamic subtest pipe-D-plane-0: SUCCESS (0.196s)
[429.803104] Starting dynamic subtest: pipe-D-plane-1
[429.971440] Dynamic subtest pipe-D-plane-1: SUCCESS (0.183s)
[430.012084] Starting dynamic subtest: pipe-D-plane-2
[430.188327] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[430.243706] Subtest plane-lut1d: SUCCESS (3.587s)
[430.448399] [079/156] (608s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[431.961705] Starting subtest: yf-tiled-16bpp-rotate-180
[431.964855] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[432.141406] [080/156] (607s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[433.653227] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[433.656424] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[433.859382] [081/156] (605s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[435.360982] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[435.363836] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[435.551852] [082/156] (604s left) gem_exec_reloc (basic-wc-read)
[435.608013] Subtest basic-wc-read: SKIP (0.000s)
[435.710882] [083/156] (604s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[437.205374] Starting subtest: short-busy-flip-before-cursor-toggle
[437.208221] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[437.404451] [084/156] (602s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[438.905274] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[438.922329] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.018s)
[439.110073] [085/156] (600s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[440.613169] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[440.616923] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[440.802091] [086/156] (599s left) kms_draw_crc (draw-method-render)
[442.296976] Starting subtest: draw-method-render
[442.298603] Starting dynamic subtest: xrgb8888-untiled
[442.732714] Dynamic subtest xrgb8888-untiled: SUCCESS (0.435s)
[442.783972] Starting dynamic subtest: rgb565-untiled
[443.082625] Dynamic subtest rgb565-untiled: SUCCESS (0.342s)
[443.133773] Starting dynamic subtest: xrgb2101010-untiled
[443.432747] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.342s)
[443.483963] Starting dynamic subtest: xbgr16161616f-untiled
[443.832988] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.392s)
[443.878520] Starting dynamic subtest: xrgb8888-xtiled
[444.031570] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.189s)
[444.059703] Starting dynamic subtest: rgb565-xtiled
[444.216452] Dynamic subtest rgb565-xtiled: SUCCESS (0.181s)
[444.268637] Starting dynamic subtest: xrgb2101010-xtiled
[444.399838] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.176s)
[444.449614] Starting dynamic subtest: xbgr16161616f-xtiled
[444.600259] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.193s)
[444.649651] Starting dynamic subtest: xrgb8888-ytiled
[444.798849] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.192s)
[444.822071] Starting dynamic subtest: rgb565-ytiled
[444.967000] Dynamic subtest rgb565-ytiled: SUCCESS (0.165s)
[445.012100] Starting dynamic subtest: xrgb2101010-ytiled
[445.150084] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.176s)
[445.200188] Starting dynamic subtest: xbgr16161616f-ytiled
[445.350193] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.193s)
[445.361533] Subtest draw-method-render: SUCCESS (3.057s)
[445.630429] [087/156] (594s left) kms_cursor_crc (cursor-sliding-256x85)
[447.362472] Starting subtest: cursor-sliding-256x85
[447.369785] Starting dynamic subtest: pipe-A-HDMI-A-4
[451.817585] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.451s)
[451.855896] Starting dynamic subtest: pipe-D-HDMI-A-4
[456.175860] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.335s)
[456.177715] Subtest cursor-sliding-256x85: SUCCESS (8.816s)
[456.500834] [088/156] (583s left) kms_cursor_crc (cursor-onscreen-256x256)
[458.220854] Starting subtest: cursor-onscreen-256x256
[458.227951] Starting dynamic subtest: pipe-A-HDMI-A-4
[460.950586] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.724s)
[460.987135] Starting dynamic subtest: pipe-D-HDMI-A-4
[463.582056] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.607s)
[463.583957] Subtest cursor-onscreen-256x256: SUCCESS (5.362s)
[463.958743] [089/156] (576s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[465.457432] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[465.462969] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[465.644035] [090/156] (574s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[467.141263] Starting subtest: fbcpsr-slowdraw
[467.144352] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[467.329344] [091/156] (573s left) kms_lease (cursor-implicit-plane)
[468.823228] Starting subtest: cursor-implicit-plane
[468.824666] Starting dynamic subtest: pipe-A-HDMI-A-4
[469.216325] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.393s)
[469.234233] Starting dynamic subtest: pipe-B-HDMI-A-4
[469.444977] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.226s)
[469.464481] Starting dynamic subtest: pipe-C-HDMI-A-4
[469.669195] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.221s)
[469.689090] Starting dynamic subtest: pipe-D-HDMI-A-4
[469.903961] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.231s)
[469.905394] Subtest cursor-implicit-plane: SUCCESS (1.082s)
[470.124624] [092/156] (570s left) syncobj_wait (invalid-reset-zero-handles)
[470.173193] Starting subtest: invalid-reset-zero-handles
[470.174804] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[470.308994] [093/156] (570s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[471.807012] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[471.809616] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[471.953177] [094/156] (568s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[473.468052] Starting subtest: yf-tiled-64bpp-rotate-90
[473.471707] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[473.666158] [095/156] (567s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[475.168072] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[475.171972] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[475.340937] [096/156] (565s left) gem_exec_fence (long-history)
[475.789136] Starting subtest: long-history
[481.842672] Subtest long-history: SUCCESS (6.051s)
[482.225434] [097/156] (558s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[483.723696] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[483.727035] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[483.872208] [098/156] (557s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[485.366046] Starting subtest: bad-rotation-90-yf-tiled-ccs
[485.367733] Starting dynamic subtest: pipe-A-HDMI-A-4
[485.387711] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[485.402562] Starting dynamic subtest: pipe-B-HDMI-A-4
[485.417282] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[485.431300] Starting dynamic subtest: pipe-C-HDMI-A-4
[485.444306] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[485.458372] Starting dynamic subtest: pipe-D-HDMI-A-4
[485.474331] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[485.477108] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[485.640911] [099/156] (555s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[487.133383] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[489.114091] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.981s)
[489.308696] [100/156] (552s left) kms_chamelium_audio (dp-audio-edid)
[490.845609] Subtest dp-audio-edid: SKIP (0.000s)
[490.980514] [101/156] (550s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[492.477518] Starting subtest: addfb25-yf-tiled-legacy
[492.478965] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[492.668620] [102/156] (549s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[494.166850] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[494.169549] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[494.375329] [103/156] (547s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[495.877981] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[495.882109] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[496.084657] [104/156] (545s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[497.585173] Starting subtest: fbc-2p-pri-indfb-multidraw
[497.588862] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[497.762010] [105/156] (544s left) kms_sequence (queue-busy)
[499.254839] Starting subtest: queue-busy
[499.256491] Starting dynamic subtest: pipe-A-HDMI-A-4
[501.867990] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.610s)
[501.883152] Starting dynamic subtest: pipe-B-HDMI-A-4
[504.293690] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.424s)
[504.310819] Starting dynamic subtest: pipe-C-HDMI-A-4
[506.721492] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.425s)
[506.738374] Starting dynamic subtest: pipe-D-HDMI-A-4
[509.150858] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.426s)
[509.152444] Subtest queue-busy: SUCCESS (9.894s)
[509.356002] [106/156] (532s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[510.850393] Starting subtest: plane-upscale-20x20-with-rotation
[510.851938] Starting dynamic subtest: pipe-A
[511.255668] Dynamic subtest pipe-A: SKIP (0.403s)
[511.270341] Starting dynamic subtest: pipe-B
[511.680173] Dynamic subtest pipe-B: SKIP (0.421s)
[511.694957] Starting dynamic subtest: pipe-C
[512.101969] Dynamic subtest pipe-C: SKIP (0.421s)
[512.118962] Starting dynamic subtest: pipe-D
[512.524835] Dynamic subtest pipe-D: SKIP (0.422s)
[512.528188] Subtest plane-upscale-20x20-with-rotation: SKIP (1.673s)
[512.731592] [107/156] (529s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[514.237701] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[514.240901] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[514.364109] [108/156] (527s left) gem_exec_reloc (basic-write-wc)
[514.421604] Subtest basic-write-wc: SKIP (0.000s)
[514.526578] [109/156] (527s left) syncobj_timeline (reset-signaled)
[514.579014] Starting subtest: reset-signaled
[514.589858] Subtest reset-signaled: SUCCESS (0.000s)
[514.693102] [110/156] (527s left) kms_psr (pr-cursor-plane-move)
[516.186673] Subtest pr-cursor-plane-move: SKIP (0.000s)
[516.274469] [111/156] (526s left) syncobj_wait (invalid-wait-illegal-handle)
[516.323774] Starting subtest: invalid-wait-illegal-handle
[516.325329] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[516.454861] [112/156] (526s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[517.952006] Starting subtest: extended-pageflip-modeset-hang-oldfb
[517.953683] Starting dynamic subtest: pipe-A
[525.699242] Dynamic subtest pipe-A: SUCCESS (7.743s)
[525.726076] Starting dynamic subtest: pipe-D
[533.431607] Dynamic subtest pipe-D: SUCCESS (7.726s)
[533.433233] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.477s)
[533.643846] [113/156] (509s left) kms_psr (psr2-primary-blt)
[535.134908] Subtest psr2-primary-blt: SKIP (0.000s)
[535.212835] [114/156] (507s left) gem_softpin (evict-snoop-interruptible)
[535.268459] Starting subtest: evict-snoop-interruptible
[535.271583] Subtest evict-snoop-interruptible: SKIP (0.000s)
[535.389217] [115/156] (507s left) kms_force_connector_basic (force-load-detect)
[535.527535] Starting subtest: force-load-detect
[535.599784] Subtest force-load-detect: SKIP (0.000s)
[536.465285] [116/156] (506s left) kms_chamelium_edid (hdmi-mode-timings)
[538.008725] Subtest hdmi-mode-timings: SKIP (0.000s)
[538.136607] [117/156] (504s left) syncobj_timeline (invalid-signal-zero-handles)
[538.187861] Starting subtest: invalid-signal-zero-handles
[538.189431] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[538.308835] [118/156] (504s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[539.811079] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[539.814116] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[539.974973] [119/156] (503s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[541.473293] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[541.476023] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[541.655533] [120/156] (501s left) gem_mmap_gtt (fault-concurrent-x)
[541.708473] Subtest fault-concurrent-X: SKIP (0.000s)
[541.786081] [121/156] (501s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[541.840906] Starting subtest: y-tiled-to-vebox-y-tiled
[541.842395] Starting dynamic subtest: smem
[541.924587] Dynamic subtest smem: SUCCESS (0.083s)
[541.939506] Starting dynamic subtest: lmem0
[542.681525] Dynamic subtest lmem0: SUCCESS (0.752s)
[542.683147] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.838s)
[542.966704] [122/156] (500s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[544.464311] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[544.467042] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[544.627864] [123/156] (498s left) vgem_basic (debugfs)
[544.688992] Starting subtest: debugfs
[544.690431] Subtest debugfs: SUCCESS (0.000s)
[544.808801] [124/156] (498s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[546.302104] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[546.303893] Starting dynamic subtest: pipe-A-valid-mode
[547.654634] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.352s)
[547.656343] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.355s)
[547.865906] [125/156] (495s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[549.365649] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[549.368549] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[549.526030] [126/156] (494s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[551.029532] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[551.032744] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[551.188953] [127/156] (492s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[552.692390] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[552.695686] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[552.873005] [128/156] (491s left) kms_vblank (wait-forked-hang)
[554.367311] Starting subtest: wait-forked-hang
[554.369028] Starting dynamic subtest: pipe-A-HDMI-A-4
[581.362749] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.991s)
[581.381055] Starting dynamic subtest: pipe-D-HDMI-A-4
[606.294279] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (24.933s)
[606.295811] Subtest wait-forked-hang: SUCCESS (51.932s)
[606.509852] [129/156] (437s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[607.998557] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[607.999975] Starting dynamic subtest: pipe-A-valid-mode
[610.581730] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.580s)
[610.583214] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.582s)
[610.786804] [130/156] (433s left) gem_ctx_exec (basic-invalid-context)
[610.838832] Starting subtest: basic-invalid-context
[610.844052] Subtest basic-invalid-context: SUCCESS (0.006s)
[611.054679] [131/156] (433s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[612.550688] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[612.553582] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[612.726245] [132/156] (431s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[614.227713] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[614.296731] [133/156] (430s left) gem_ppgtt (blt-vs-render-ctxn)
[614.350231] Starting subtest: blt-vs-render-ctxN
[647.122055] Subtest blt-vs-render-ctxN: SUCCESS (32.769s)
[647.392063] [134/156] (397s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[647.507523] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[647.610034] [135/156] (397s left) gem_softpin (invalid)
[647.686428] Starting subtest: invalid
[647.698777] Subtest invalid: SUCCESS (0.015s)
[647.877062] [136/156] (396s left) gem_exec_capture (capture)
[647.934985] Starting subtest: capture
[647.936699] Starting dynamic subtest: rcs0-smem
[648.844104] Dynamic subtest rcs0-smem: SUCCESS (0.908s)
[648.860365] Starting dynamic subtest: rcs0-lmem0
[649.726723] Dynamic subtest rcs0-lmem0: SUCCESS (0.882s)
[649.742315] Starting dynamic subtest: bcs0-smem
[650.604674] Dynamic subtest bcs0-smem: SUCCESS (0.877s)
[650.619651] Starting dynamic subtest: bcs0-lmem0
[651.471652] Dynamic subtest bcs0-lmem0: SUCCESS (0.864s)
[651.486551] Starting dynamic subtest: vcs0-smem
[652.838393] Dynamic subtest vcs0-smem: SUCCESS (1.366s)
[652.853223] Starting dynamic subtest: vcs0-lmem0
[653.705002] Dynamic subtest vcs0-lmem0: SUCCESS (0.861s)
[653.719960] Starting dynamic subtest: vcs1-smem
[655.069735] Dynamic subtest vcs1-smem: SUCCESS (1.366s)
[655.084721] Starting dynamic subtest: vcs1-lmem0
[656.049214] Dynamic subtest vcs1-lmem0: SUCCESS (0.975s)
[656.063963] Starting dynamic subtest: vecs0-smem
[656.975553] Dynamic subtest vecs0-smem: FAIL (0.891s)
[656.989852] Starting dynamic subtest: vecs0-lmem0
[657.979964] Dynamic subtest vecs0-lmem0: FAIL (0.985s)
[657.981803] Subtest capture: FAIL (9.991s)
[658.123820] [137/156] (386s left) gem_tiled_blits (basic)
[658.178139] Subtest basic: SKIP (0.000s)
[658.265760] [138/156] (386s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[659.769283] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[659.771900] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[659.937074] [139/156] (385s left) gem_mmap_offset (partial-remap)
[659.988062] Starting subtest: partial-remap
[659.989473] Starting dynamic subtest: lmem0
[659.994439] Dynamic subtest lmem0: SUCCESS (0.006s)
[660.009724] Starting dynamic subtest: smem0
[660.012804] Dynamic subtest smem0: SUCCESS (0.005s)
[660.014491] Subtest partial-remap: SUCCESS (0.015s)
[660.190531] [140/156] (385s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[661.686914] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[661.689786] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[661.843858] [141/156] (383s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[663.343157] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[663.345825] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[663.531889] [142/156] (381s left) i915_power (sanity)
[663.578699] Starting subtest: sanity
[667.619577] Subtest sanity: SUCCESS (4.035s)
[667.768174] [143/156] (377s left) gem_exec_schedule (preempt-queue)
[667.842980] Subtest preempt-queue: SKIP (0.000s)
[668.252893] [144/156] (377s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[669.745892] Starting subtest: cursorB-vs-flipA-legacy
[669.748685] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[669.887582] [145/156] (375s left) i915_pm_rps (reset)
[669.955227] Starting subtest: reset
[674.683357] Subtest reset: SUCCESS (4.728s)
[674.854161] [146/156] (370s left) kms_psr (fbc-psr-cursor-plane-onoff)
[676.345939] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[676.429291] [147/156] (369s left) kms_setmode (clone-exclusive-crtc)
[676.478270] Starting subtest: clone-exclusive-crtc
[676.485134] Subtest clone-exclusive-crtc: SKIP (0.000s)
[676.610450] [148/156] (369s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[678.109020] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[678.112369] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[678.328047] [149/156] (367s left) kms_flip (flip-vs-rmfb)
[679.819279] Starting subtest: flip-vs-rmfb
[679.826327] Starting dynamic subtest: A-HDMI-A4
[687.630742] Dynamic subtest A-HDMI-A4: SUCCESS (7.803s)
[687.684453] Starting dynamic subtest: B-HDMI-A4
[695.342200] Dynamic subtest B-HDMI-A4: SUCCESS (7.701s)
[695.391882] Starting dynamic subtest: C-HDMI-A4
[703.036614] Dynamic subtest C-HDMI-A4: SUCCESS (7.688s)
[703.082158] Starting dynamic subtest: D-HDMI-A4
[710.731170] Dynamic subtest D-HDMI-A4: SUCCESS (7.688s)
[710.733285] Subtest flip-vs-rmfb: SUCCESS (30.911s)
[710.987108] [150/156] (335s left) gem_exec_whisper (basic-contexts-all)
[711.050998] Starting subtest: basic-contexts-all
[718.111048] Subtest basic-contexts-all: SUCCESS (7.054s)
[718.382604] [151/156] (327s left) dmabuf_sync_file (export-basic)
[718.440634] Starting subtest: export-basic
[718.442292] Subtest export-basic: SUCCESS (0.001s)
[718.553558] [152/156] (327s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[720.051630] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[720.055038] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[720.225323] [153/156] (326s left) gem_sync (basic-each)
[720.296810] Starting subtest: basic-each
[722.683009] Subtest basic-each: SUCCESS (2.388s)
[723.037261] [154/156] (323s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[724.545040] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[724.546641] Starting dynamic subtest: pipe-A-HDMI-A-4
[724.564149] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[724.579845] Starting dynamic subtest: pipe-B-HDMI-A-4
[724.599764] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[724.614982] Starting dynamic subtest: pipe-C-HDMI-A-4
[724.633658] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[724.648448] Starting dynamic subtest: pipe-D-HDMI-A-4
[724.667512] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[724.681015] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[724.825256] [155/156] (321s left) syncobj_wait (multi-wait-for-submit-submitted)
[724.876117] Starting subtest: multi-wait-for-submit-submitted
[724.983456] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[725.214581] [156/156] (321s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[726.710540] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[726.713249] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[726.871596] 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.