[58.086578] Initializing watchdogs
[58.086650]   /dev/watchdog0
[58.102420] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[58.106286] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.108254] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.111498] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[58.228308] Starting subtest: fbc-tiling-linear
[61.952236] Subtest fbc-tiling-linear: SUCCESS (3.794s)
[63.102575] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[63.161071] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[63.208787] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[63.284654] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[63.288810] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[63.395598] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[63.485073] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[63.489094] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[63.585855] [005/156] (955s left) kms_force_connector_basic (prune-stale-modes)
[63.650313] Subtest prune-stale-modes: SKIP (0.000s)
[63.713586] [006/156] (955s left) perf_pmu (most-busy-check-all)
[63.752683] Starting subtest: most-busy-check-all
[63.754991] Starting dynamic subtest: rcs0
[65.217030] Dynamic subtest rcs0: SUCCESS (1.476s)
[65.227308] Starting dynamic subtest: bcs0
[67.266953] Dynamic subtest bcs0: SUCCESS (2.064s)
[67.278639] Starting dynamic subtest: vcs0
[69.247687] Dynamic subtest vcs0: SUCCESS (1.991s)
[69.257076] Starting dynamic subtest: vcs1
[71.231749] Dynamic subtest vcs1: SUCCESS (1.991s)
[71.242743] Starting dynamic subtest: vecs0
[73.216634] Dynamic subtest vecs0: SUCCESS (1.990s)
[73.228651] Starting dynamic subtest: ccs0
[75.200725] Dynamic subtest ccs0: SUCCESS (1.986s)
[75.203304] Subtest most-busy-check-all: SUCCESS (11.507s)
[75.326080] [007/156] (943s left) gem_ccs (large-ctrl-surf-copy)
[75.394757] Starting subtest: large-ctrl-surf-copy
[75.399533] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[75.514722] [008/156] (943s left) kms_plane_alpha_blend (coverage-7efc)
[75.589138] Starting subtest: coverage-7efc
[75.874164] Starting dynamic subtest: pipe-A-eDP-1
[76.460457] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.587s)
[76.490411] Starting dynamic subtest: pipe-D-eDP-1
[77.836904] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.354s)
[77.839283] Subtest coverage-7efc: SUCCESS (2.251s)
[78.674422] [009/156] (940s left) gem_mmap_gtt (fault-concurrent)
[78.730423] Subtest fault-concurrent: SKIP (0.000s)
[78.795313] [010/156] (940s left) api_intel_allocator (gem-pool)
[78.845777] Starting subtest: gem-pool
[78.856463] Subtest gem-pool: SUCCESS (0.012s)
[78.965370] [011/156] (940s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[79.042680] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[79.046702] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[79.129959] [012/156] (939s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[79.204730] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[79.208587] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[79.298225] [013/156] (939s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[79.375592] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[79.377812] Starting dynamic subtest: pipe-A-eDP-1
[79.400040] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[79.411287] Starting dynamic subtest: pipe-B-eDP-1
[79.432530] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[79.440451] Starting dynamic subtest: pipe-C-eDP-1
[79.462611] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[79.470500] Starting dynamic subtest: pipe-D-eDP-1
[79.492801] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[79.497346] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[81.642207] [014/156] (937s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[81.723070] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[81.725451] Starting dynamic subtest: pipe-A-eDP-1
[81.748887] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[81.761390] Starting dynamic subtest: pipe-B-eDP-1
[81.783281] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[81.792059] Starting dynamic subtest: pipe-C-eDP-1
[81.814132] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[81.822123] Starting dynamic subtest: pipe-D-eDP-1
[81.844535] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[81.848967] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[83.994354] [015/156] (935s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[84.072197] Starting subtest: hdr-rgb565-draw-blt
[84.076265] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[84.175784] [016/156] (935s left) kms_pm_rpm (legacy-planes-dpms)
[86.401095] Starting subtest: legacy-planes-dpms
[86.403311] Starting dynamic subtest: plane-65
[94.699707] Dynamic subtest plane-65: SUCCESS (8.300s)
[94.711559] Starting dynamic subtest: plane-95
[102.999297] Dynamic subtest plane-95: SUCCESS (8.297s)
[103.010660] Starting dynamic subtest: plane-125
[111.313613] Dynamic subtest plane-125: SUCCESS (8.312s)
[111.325232] Starting dynamic subtest: plane-135
[119.645777] Dynamic subtest plane-135: SUCCESS (8.330s)
[119.648762] Subtest legacy-planes-dpms: SUCCESS (33.243s)
[120.013196] [017/156] (899s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[120.071329] Starting subtest: x-tiled-to-vebox-y-tiled
[120.075530] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[120.265095] [018/156] (898s left) sysfs_heartbeat_interval (invalid)
[120.325534] Starting subtest: invalid
[120.327686] Starting dynamic subtest: vecs0
[120.329952] Dynamic subtest vecs0: SUCCESS (0.000s)
[120.337987] Starting dynamic subtest: ccs0
[120.339888] Dynamic subtest ccs0: SUCCESS (0.000s)
[120.347745] Starting dynamic subtest: vcs0
[120.349649] Dynamic subtest vcs0: SUCCESS (0.000s)
[120.357302] Starting dynamic subtest: bcs0
[120.359179] Dynamic subtest bcs0: SUCCESS (0.000s)
[120.366785] Starting dynamic subtest: rcs0
[120.368695] Dynamic subtest rcs0: SUCCESS (0.000s)
[120.376327] Starting dynamic subtest: vcs1
[120.378650] Dynamic subtest vcs1: SUCCESS (0.000s)
[120.380872] Subtest invalid: SUCCESS (0.003s)
[120.434652] [019/156] (898s left) kms_frontbuffer_tracking (fbc-stridechange)
[120.528236] Starting subtest: fbc-stridechange
[123.990833] Subtest fbc-stridechange: SUCCESS (3.470s)
[125.144042] [020/156] (894s left) gem_ctx_param (basic-default)
[125.190804] Starting subtest: basic-default
[125.193285] Subtest basic-default: SUCCESS (0.000s)
[125.255211] [021/156] (894s left) kms_psr (fbc-pr-primary-mmap-gtt)
[125.337738] Starting subtest: fbc-pr-primary-mmap-gtt
[125.341660] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[125.419330] [022/156] (893s left) i915_pm_freq_mult (media-freq)
[125.473122] Starting subtest: media-freq
[125.475507] Starting dynamic subtest: gt0
[125.479763] Dynamic subtest gt0: SKIP (0.000s)
[125.490212] Starting dynamic subtest: gt1
[125.494235] Dynamic subtest gt1: SKIP (0.000s)
[125.498283] Subtest media-freq: SKIP (0.002s)
[125.564399] [023/156] (893s left) prime_busy (before-wait)
[125.620066] Starting subtest: before-wait
[125.624708] Starting dynamic subtest: rcs0
[125.663677] Dynamic subtest rcs0: SUCCESS (0.039s)
[125.672917] Starting dynamic subtest: bcs0
[125.711700] Dynamic subtest bcs0: SUCCESS (0.048s)
[125.720469] Starting dynamic subtest: vcs0
[125.751084] Dynamic subtest vcs0: SUCCESS (0.040s)
[125.760049] Starting dynamic subtest: vcs1
[125.792384] Dynamic subtest vcs1: SUCCESS (0.041s)
[125.801601] Starting dynamic subtest: vecs0
[125.832494] Dynamic subtest vecs0: SUCCESS (0.040s)
[125.841484] Starting dynamic subtest: ccs0
[125.873070] Dynamic subtest ccs0: SUCCESS (0.040s)
[125.981241] Subtest before-wait: SUCCESS (0.359s)
[126.050633] [024/156] (893s left) gem_exec_gttfill (engines)
[126.111775] Starting subtest: engines
[126.114122] Starting dynamic subtest: rcs0
[148.873550] Dynamic subtest rcs0: SUCCESS (22.760s)
[148.885084] Starting dynamic subtest: bcs0
[172.130683] Dynamic subtest bcs0: SUCCESS (23.254s)
[172.139749] Starting dynamic subtest: vcs0
[195.328177] Dynamic subtest vcs0: SUCCESS (23.195s)
[195.339513] Starting dynamic subtest: vcs1
[218.562786] Dynamic subtest vcs1: SUCCESS (23.233s)
[218.572098] Starting dynamic subtest: vecs0
[241.837804] Dynamic subtest vecs0: SUCCESS (23.273s)
[241.847485] Starting dynamic subtest: ccs0
[265.138889] Dynamic subtest ccs0: SUCCESS (23.299s)
[265.141257] Subtest engines: SUCCESS (139.020s)
[265.338281] [025/156] (754s left) syncobj_timeline (etime-multi-wait-all-submitted)
[265.431936] Starting subtest: etime-multi-wait-all-submitted
[265.539371] Subtest etime-multi-wait-all-submitted: SUCCESS (0.108s)
[265.613395] [026/156] (753s left) gem_ctx_param (get-priority-new-ctx)
[265.666986] Starting subtest: get-priority-new-ctx
[265.669451] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[265.763352] [027/156] (753s left) kms_big_fb (linear-32bpp-rotate-90)
[265.848145] Starting subtest: linear-32bpp-rotate-90
[265.869076] Subtest linear-32bpp-rotate-90: SKIP (0.021s)
[265.968536] [028/156] (753s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[266.007527] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[266.046131] [029/156] (753s left) gem_ctx_shared (disjoint-timelines)
[266.101251] Starting subtest: disjoint-timelines
[266.183167] Subtest disjoint-timelines: SUCCESS (0.082s)
[266.409315] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[266.412666] [030/156] (753s left) kms_psr (fbc-psr2-cursor-plane-move)
[266.484064] Starting subtest: fbc-psr2-cursor-plane-move
[266.486263] Starting dynamic subtest: eDP-1
[266.490218] Dynamic subtest eDP-1: SKIP (0.000s)
[266.494291] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[266.558048] [031/156] (753s left) gem_bad_reloc (negative-reloc-bltcopy)
[266.631244] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[266.688126] [032/156] (753s left) kms_chamelium_hpd (hdmi-hpd)
[267.045162] Subtest hdmi-hpd: SKIP (0.000s)
[267.835150] [033/156] (752s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[267.916933] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[267.921663] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[267.988300] [034/156] (751s left) gem_eio (wait-10ms)
[268.204543] Starting subtest: wait-10ms
[268.543583] Subtest wait-10ms: SUCCESS (0.341s)
[268.672104] [035/156] (751s left) kms_content_protection (legacy-hdcp14)
[268.797406] Starting subtest: legacy-hdcp14
[268.801273] Subtest legacy-hdcp14: SKIP (0.000s)
[268.908213] [036/156] (751s left) syncobj_wait (reset-signaled)
[268.960906] Starting subtest: reset-signaled
[268.962481] Subtest reset-signaled: SUCCESS (0.000s)
[269.056913] [037/156] (750s left) gem_ctx_isolation (dirty-switch)
[269.107943] Starting subtest: dirty-switch
[269.110098] Starting dynamic subtest: rcs0
[269.318497] Dynamic subtest rcs0: SUCCESS (0.210s)
[269.328385] Starting dynamic subtest: bcs0
[269.520253] Dynamic subtest bcs0: SUCCESS (0.201s)
[269.533022] Starting dynamic subtest: vcs0
[269.719054] Dynamic subtest vcs0: SUCCESS (0.198s)
[269.729487] Starting dynamic subtest: vcs1
[271.452453] Dynamic subtest vcs1: SUCCESS (1.732s)
[271.461964] Starting dynamic subtest: vecs0
[273.419479] Dynamic subtest vecs0: SUCCESS (1.967s)
[273.430831] Starting dynamic subtest: ccs0
[275.472083] Dynamic subtest ccs0: SUCCESS (2.051s)
[275.474434] Subtest dirty-switch: SUCCESS (6.363s)
[275.699433] [038/156] (744s left) gem_flink_basic (bad-open)
[275.749646] Starting subtest: bad-open
[275.751571] Subtest bad-open: SUCCESS (0.000s)
[275.852965] [039/156] (744s left) gem_caching (writes)
[275.912739] Subtest writes: SKIP (0.000s)
[275.985600] [040/156] (744s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[276.065180] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[276.067162] Starting dynamic subtest: pipe-A
[276.401335] Dynamic subtest pipe-A: SUCCESS (0.339s)
[276.410305] Starting dynamic subtest: pipe-B
[277.833918] Dynamic subtest pipe-B: SUCCESS (1.431s)
[277.845201] Starting dynamic subtest: pipe-C
[279.252726] Dynamic subtest pipe-C: SUCCESS (1.419s)
[279.264810] Starting dynamic subtest: pipe-D
[280.682268] Dynamic subtest pipe-D: SUCCESS (1.429s)
[280.684677] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.620s)
[281.866286] [041/156] (738s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[281.918100] Starting subtest: single-wait-all-for-submit-available-submitted
[281.920470] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[282.012835] [042/156] (738s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[282.100375] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[282.104485] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[282.202594] [043/156] (738s left) kms_pm_rpm (system-suspend-idle)
[284.328492] Starting subtest: system-suspend-idle
[304.136724] Subtest system-suspend-idle: SUCCESS (4.485s)
[304.531013] [044/156] (715s left) kms_atomic_transition (modeset-transition)
[304.616680] Starting subtest: modeset-transition
[304.618880] Starting dynamic subtest: 1x-outputs
[315.463498] Dynamic subtest 1x-outputs: SUCCESS (11.095s)
[315.465994] Subtest modeset-transition: SUCCESS (11.096s)
[316.308346] [045/156] (704s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[316.389868] Starting subtest: pipe-fbc-rte
[316.397740] Starting dynamic subtest: pipe-B-eDP-1
[321.856252] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.509s)
[321.865028] Subtest pipe-fbc-rte: SUCCESS (5.511s)
[324.105451] [046/156] (696s left) gem_spin_batch (resubmit-all)
[324.166918] Starting subtest: resubmit-all
[324.169599] Starting dynamic subtest: rcs0
[324.180898] Dynamic subtest rcs0: SUCCESS (0.014s)
[324.196462] Starting dynamic subtest: bcs0
[324.198855] Dynamic subtest bcs0: SUCCESS (0.002s)
[324.209020] Starting dynamic subtest: vcs0
[324.211429] Dynamic subtest vcs0: SUCCESS (0.002s)
[324.221031] Starting dynamic subtest: vcs1
[324.223266] Dynamic subtest vcs1: SUCCESS (0.002s)
[324.233038] Starting dynamic subtest: vecs0
[324.235399] Dynamic subtest vecs0: SUCCESS (0.003s)
[324.245029] Starting dynamic subtest: ccs0
[324.247498] Dynamic subtest ccs0: SUCCESS (0.003s)
[324.249946] Subtest resubmit-all: SUCCESS (0.030s)
[325.606140] [047/156] (694s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[325.686108] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[325.691680] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[325.784838] [048/156] (694s left) syncobj_basic (illegal-fd-to-handle)
[325.844316] Starting subtest: illegal-fd-to-handle
[325.846890] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[325.926886] [049/156] (694s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[326.004165] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[326.009505] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[326.098177] [050/156] (694s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[326.175381] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[327.361967] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.190s)
[327.477936] [051/156] (693s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[327.524462] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[327.629161] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[327.704870] [052/156] (692s left) api_intel_bb (misplaced-blitter)
[327.756413] Starting subtest: misplaced-blitter
[327.761185] Subtest misplaced-blitter: SUCCESS (0.009s)
[327.873282] [053/156] (692s left) kms_atomic_interruptible (universal-setplane-cursor)
[327.959615] Starting subtest: universal-setplane-cursor
[327.962186] Starting dynamic subtest: pipe-A-eDP-1
[335.407206] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.457s)
[335.409964] Subtest universal-setplane-cursor: SUCCESS (7.459s)
[336.255123] [054/156] (684s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[336.338798] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[336.343964] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[336.431310] [055/156] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[336.511000] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[336.516004] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[336.610192] [056/156] (684s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[336.690771] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[336.695901] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[336.761310] [057/156] (684s left) gem_pxp (create-protected-buffer)
[336.878733] Starting subtest: create-protected-buffer
[336.882049] Subtest create-protected-buffer: SUCCESS (0.000s)
[336.990303] [058/156] (683s left) gem_fence_thrash (bo-write-verify-threaded-x)
[337.029787] Starting subtest: bo-write-verify-threaded-x
[337.055773] Subtest bo-write-verify-threaded-x: SKIP (0.020s)
[337.116534] [059/156] (683s left) gen9_exec_parse (allowed-single)
[337.174078] Subtest allowed-single: SKIP (0.000s)
[337.238248] [060/156] (683s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[337.324685] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[337.328824] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[337.421629] [061/156] (683s left) gem_exec_balancer (bonded-semaphore)
[337.478847] Starting subtest: bonded-semaphore
[337.483366] Subtest bonded-semaphore: SKIP (0.000s)
[337.768611] [062/156] (683s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[337.831739] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[337.836599] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[337.902653] [063/156] (683s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[337.977137] Starting subtest: fbc-rgb565-draw-mmap-wc
[341.942255] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (3.969s)
[343.155592] [064/156] (678s left) kms_pwrite_crc (basic)
[343.226133] Starting subtest: basic
[343.910889] Subtest basic: SUCCESS (0.685s)
[344.780815] [065/156] (676s left) gem_flink_basic (bad-flink)
[344.830161] Starting subtest: bad-flink
[344.833012] Subtest bad-flink: SUCCESS (0.000s)
[344.921424] [066/156] (676s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[344.995766] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[345.000502] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[345.107854] [067/156] (676s left) gem_ctx_persistence (legacy-engines-persistence)
[345.159999] Starting subtest: legacy-engines-persistence
[345.162803] Starting dynamic subtest: render
[345.981256] Dynamic subtest render: SUCCESS (0.820s)
[345.994012] Starting dynamic subtest: bsd1
[346.753014] Dynamic subtest bsd1: SUCCESS (0.769s)
[346.765787] Starting dynamic subtest: bsd2
[347.514702] Dynamic subtest bsd2: SUCCESS (0.759s)
[347.527604] Starting dynamic subtest: blt
[348.269389] Dynamic subtest blt: SUCCESS (0.753s)
[348.282945] Starting dynamic subtest: vebox
[349.051304] Dynamic subtest vebox: SUCCESS (0.780s)
[349.054269] Subtest legacy-engines-persistence: SUCCESS (3.892s)
[349.145160] [068/156] (672s left) gen7_exec_parse (basic-allowed)
[349.206329] Subtest basic-allowed: SKIP (0.000s)
[349.272400] [069/156] (672s left) gem_exec_schedule (reorder-wide)
[349.448908] Subtest reorder-wide: SKIP (0.000s)
[349.636738] [070/156] (671s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[349.733876] Starting subtest: psrhdr-slowdraw
[349.739550] Subtest psrhdr-slowdraw: SKIP (0.000s)
[349.854913] [071/156] (671s left) perf (non-zero-reason)
[350.000164] Starting subtest: non-zero-reason
[350.003617] Starting dynamic subtest: 0-rcs0
[355.351316] Dynamic subtest 0-rcs0: SUCCESS (5.351s)
[355.366249] Starting dynamic subtest: 1-vcs0
[359.241018] Dynamic subtest 1-vcs0: SUCCESS (3.889s)
[359.244012] Subtest non-zero-reason: SUCCESS (9.241s)
[359.292721] [072/156] (662s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[359.383946] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[359.389201] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[359.488155] [073/156] (662s left) i915_drm_fdinfo (all-busy-check-all)
[359.527606] Starting subtest: all-busy-check-all
[359.532602] Subtest all-busy-check-all: SKIP (0.003s)
[359.596334] [074/156] (661s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[359.690508] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[359.695789] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[359.791468] [075/156] (661s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[359.871045] Starting subtest: compare-crc-sanitycheck-nv12
[359.873362] Starting dynamic subtest: pipe-A-eDP-1
[360.520624] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.646s)
[360.532134] Starting dynamic subtest: pipe-B-eDP-1
[361.862485] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.343s)
[361.873613] Starting dynamic subtest: pipe-C-eDP-1
[363.132967] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.269s)
[363.145505] Starting dynamic subtest: pipe-D-eDP-1
[364.346737] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[364.349265] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.476s)
[365.201558] [076/156] (656s left) gem_exec_reloc (basic-write-gtt)
[365.258333] Subtest basic-write-gtt: SKIP (0.000s)
[365.332240] [077/156] (656s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[365.615372] Starting subtest: cursor-rapid-movement-max-size
[365.624010] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[365.727046] [078/156] (656s left) kms_color_pipeline (plane-lut1d)
[365.801678] Starting subtest: plane-lut1d
[365.924430] Starting dynamic subtest: pipe-A-plane-0
[366.080544] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[366.091438] Starting dynamic subtest: pipe-A-plane-1
[366.213458] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[366.223700] Starting dynamic subtest: pipe-A-plane-2
[366.369555] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[367.596830] Starting dynamic subtest: pipe-B-plane-0
[367.776448] Dynamic subtest pipe-B-plane-0: SUCCESS (0.189s)
[367.787787] Starting dynamic subtest: pipe-B-plane-1
[367.943553] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[367.954918] Starting dynamic subtest: pipe-B-plane-2
[368.088043] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[369.334922] Starting dynamic subtest: pipe-C-plane-0
[369.481542] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[369.492056] Starting dynamic subtest: pipe-C-plane-1
[369.636929] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[369.648018] Starting dynamic subtest: pipe-C-plane-2
[369.792671] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[370.984761] Starting dynamic subtest: pipe-D-plane-0
[371.120825] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[371.132961] Starting dynamic subtest: pipe-D-plane-1
[371.264738] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[371.276267] Starting dynamic subtest: pipe-D-plane-2
[371.409066] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[371.694323] Subtest plane-lut1d: SUCCESS (5.896s)
[372.527165] [079/156] (649s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[372.619181] Starting subtest: yf-tiled-16bpp-rotate-180
[372.624596] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[372.710501] [080/156] (649s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[372.793235] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[373.792865] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.001s)
[373.904269] [081/156] (647s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[373.985808] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[373.990777] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[374.082779] [082/156] (647s left) gem_exec_reloc (basic-wc-read)
[374.137631] Subtest basic-wc-read: SKIP (0.000s)
[374.205816] [083/156] (647s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[374.301780] Starting subtest: short-busy-flip-before-cursor-toggle
[374.306730] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[374.448698] [084/156] (647s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[374.520007] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[374.528773] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.007s)
[374.604464] [085/156] (647s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[374.686716] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[374.692156] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[374.789492] [086/156] (647s left) kms_draw_crc (draw-method-render)
[374.872316] Starting subtest: draw-method-render
[374.874030] Starting dynamic subtest: xrgb8888-untiled
[375.126657] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[375.136864] Starting dynamic subtest: rgb565-untiled
[375.349313] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[375.359753] Starting dynamic subtest: xrgb2101010-untiled
[375.571565] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[375.582463] Starting dynamic subtest: xbgr16161616f-untiled
[375.782546] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.209s)
[375.793620] Starting dynamic subtest: xrgb8888-xtiled
[375.904788] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[375.917716] Starting dynamic subtest: rgb565-xtiled
[376.015377] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[376.028278] Starting dynamic subtest: xrgb2101010-xtiled
[376.137652] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[376.148412] Starting dynamic subtest: xbgr16161616f-xtiled
[376.271263] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[376.282226] Starting dynamic subtest: xrgb8888-4tiled
[376.393256] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[376.406746] Starting dynamic subtest: rgb565-4tiled
[376.526605] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[376.537842] Starting dynamic subtest: xrgb2101010-4tiled
[376.648771] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[376.660293] Starting dynamic subtest: xbgr16161616f-4tiled
[376.781589] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[376.783786] Subtest draw-method-render: SUCCESS (1.912s)
[376.889832] [087/156] (645s left) kms_cursor_crc (cursor-sliding-256x85)
[377.113748] Starting subtest: cursor-sliding-256x85
[377.159622] Subtest cursor-sliding-256x85: SKIP (0.042s)
[377.313494] [088/156] (644s left) kms_cursor_crc (cursor-onscreen-256x256)
[377.523571] Starting subtest: cursor-onscreen-256x256
[377.525631] Starting dynamic subtest: pipe-A-eDP-1
[379.629910] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.105s)
[379.659970] Starting dynamic subtest: pipe-D-eDP-1
[382.380651] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.729s)
[382.382519] Subtest cursor-onscreen-256x256: SUCCESS (4.859s)
[383.192666] [089/156] (639s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[383.268811] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[383.274175] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[383.385638] [090/156] (638s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[383.469733] Starting subtest: fbcpsr-slowdraw
[408.715505] Subtest fbcpsr-slowdraw: SUCCESS (25.252s)
[408.842732] [091/156] (613s left) kms_lease (cursor-implicit-plane)
[408.913646] Starting subtest: cursor-implicit-plane
[408.915912] Starting dynamic subtest: pipe-A-eDP-1
[409.369044] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.453s)
[409.379886] Starting dynamic subtest: pipe-B-eDP-1
[410.530868] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.159s)
[410.543242] Starting dynamic subtest: pipe-C-eDP-1
[411.654496] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.122s)
[411.666827] Starting dynamic subtest: pipe-D-eDP-1
[412.786211] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[412.788729] Subtest cursor-implicit-plane: SUCCESS (3.870s)
[413.638276] [092/156] (608s left) syncobj_wait (invalid-reset-zero-handles)
[413.697992] Starting subtest: invalid-reset-zero-handles
[413.700830] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[413.793396] [093/156] (608s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[413.874237] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[413.878856] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[413.972372] [094/156] (608s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[414.057856] Starting subtest: yf-tiled-64bpp-rotate-90
[414.063822] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[414.157906] [095/156] (608s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[414.238263] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[414.243773] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[414.337931] [096/156] (608s left) gem_exec_fence (long-history)
[414.764589] Starting subtest: long-history
[422.282921] Subtest long-history: SUCCESS (7.518s)
[423.668922] [097/156] (598s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[423.752432] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[431.073617] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.328s)
[431.199372] [098/156] (591s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[431.271790] Starting subtest: bad-rotation-90-yf-tiled-ccs
[431.274489] Starting dynamic subtest: pipe-A-eDP-1
[431.299048] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[431.310817] Starting dynamic subtest: pipe-B-eDP-1
[431.335656] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[431.345835] Starting dynamic subtest: pipe-C-eDP-1
[431.371468] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[431.381936] Starting dynamic subtest: pipe-D-eDP-1
[431.407870] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[431.413023] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[433.533061] [099/156] (589s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[433.641731] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[451.726070] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.082s)
[452.611393] [100/156] (570s left) kms_chamelium_audio (dp-audio-edid)
[452.974303] Subtest dp-audio-edid: SKIP (0.000s)
[453.825233] [101/156] (568s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[453.907173] Starting subtest: addfb25-yf-tiled-legacy
[453.909531] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[453.997996] [102/156] (568s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[454.084951] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[454.090620] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[454.188154] [103/156] (568s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[454.279303] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[454.283991] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[454.375523] [104/156] (568s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[454.468820] Starting subtest: fbc-2p-pri-indfb-multidraw
[454.474032] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[454.554618] [105/156] (568s left) kms_sequence (queue-busy)
[454.638849] Starting subtest: queue-busy
[454.641698] Starting dynamic subtest: pipe-A-eDP-1
[456.563011] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.926s)
[456.574802] Starting dynamic subtest: pipe-B-eDP-1
[459.138361] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.574s)
[459.149890] Starting dynamic subtest: pipe-C-eDP-1
[461.754896] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.616s)
[461.766547] Starting dynamic subtest: pipe-D-eDP-1
[464.388265] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.629s)
[464.390649] Subtest queue-busy: SUCCESS (9.751s)
[465.274024] [106/156] (557s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[465.344506] Starting subtest: plane-upscale-20x20-with-rotation
[465.347029] Starting dynamic subtest: pipe-A
[465.568750] Dynamic subtest pipe-A: SUCCESS (0.227s)
[465.579407] Starting dynamic subtest: pipe-B
[466.852633] Dynamic subtest pipe-B: SUCCESS (1.284s)
[466.864003] Starting dynamic subtest: pipe-C
[468.180166] Dynamic subtest pipe-C: SUCCESS (1.327s)
[468.191742] Starting dynamic subtest: pipe-D
[469.505342] Dynamic subtest pipe-D: SUCCESS (1.324s)
[469.507955] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.165s)
[470.693885] [107/156] (552s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[470.777625] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[470.782737] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[470.868719] [108/156] (552s left) gem_exec_reloc (basic-write-wc)
[470.930024] Subtest basic-write-wc: SKIP (0.000s)
[471.004776] [109/156] (552s left) syncobj_timeline (reset-signaled)
[471.073392] Starting subtest: reset-signaled
[471.076066] Subtest reset-signaled: SUCCESS (0.001s)
[471.152601] [110/156] (552s left) kms_psr (pr-cursor-plane-move)
[471.228930] Starting subtest: pr-cursor-plane-move
[471.233845] Subtest pr-cursor-plane-move: SKIP (0.000s)
[471.304456] [111/156] (551s left) syncobj_wait (invalid-wait-illegal-handle)
[471.348777] Starting subtest: invalid-wait-illegal-handle
[471.351449] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[471.444232] [112/156] (551s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[471.519815] Starting subtest: extended-pageflip-modeset-hang-oldfb
[471.522571] Starting dynamic subtest: pipe-A
[479.419510] Dynamic subtest pipe-A: SUCCESS (7.900s)
[479.430839] Starting dynamic subtest: pipe-D
[488.019703] Dynamic subtest pipe-D: SUCCESS (8.599s)
[488.022323] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.503s)
[488.857739] [113/156] (534s left) kms_psr (psr2-primary-blt)
[488.926409] Starting subtest: psr2-primary-blt
[488.928804] Starting dynamic subtest: eDP-1
[490.648023] Dynamic subtest eDP-1: SUCCESS (1.720s)
[490.650934] Subtest psr2-primary-blt: SUCCESS (1.721s)
[491.513949] [114/156] (531s left) gem_softpin (evict-snoop-interruptible)
[491.566669] Starting subtest: evict-snoop-interruptible
[491.572241] Subtest evict-snoop-interruptible: SKIP (0.000s)
[491.682778] [115/156] (531s left) kms_force_connector_basic (force-load-detect)
[491.738556] Subtest force-load-detect: SKIP (0.000s)
[491.802344] [116/156] (531s left) kms_chamelium_edid (hdmi-mode-timings)
[492.171945] Subtest hdmi-mode-timings: SKIP (0.000s)
[493.028607] [117/156] (530s left) syncobj_timeline (invalid-signal-zero-handles)
[493.087011] Starting subtest: invalid-signal-zero-handles
[493.089927] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[493.190507] [118/156] (530s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[493.273458] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[493.278006] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[493.362469] [119/156] (530s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[493.450412] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[493.455241] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[493.558520] [120/156] (530s left) gem_mmap_gtt (fault-concurrent-x)
[493.609817] Subtest fault-concurrent-X: SKIP (0.000s)
[493.665917] [121/156] (529s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[493.727925] Starting subtest: y-tiled-to-vebox-y-tiled
[493.734040] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[493.938241] [122/156] (529s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[494.013909] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[494.018908] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[494.109982] [123/156] (529s left) vgem_basic (debugfs)
[494.154828] Starting subtest: debugfs
[494.158817] Subtest debugfs: SUCCESS (0.000s)
[494.214169] [124/156] (529s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[494.287828] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[494.290317] Starting dynamic subtest: pipe-A-default-mode
[495.430516] Dynamic subtest pipe-A-default-mode: SKIP (1.140s)
[495.436025] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.141s)
[495.581508] [125/156] (528s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[495.663552] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[495.668607] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[495.763331] [126/156] (528s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[495.842012] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[495.847236] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[495.946214] [127/156] (527s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[496.025160] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[496.030611] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[496.120646] [128/156] (527s left) kms_vblank (wait-forked-hang)
[496.194268] Starting subtest: wait-forked-hang
[496.196922] Starting dynamic subtest: pipe-A-eDP-1
[522.381300] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.193s)
[522.401268] Starting dynamic subtest: pipe-D-eDP-1
[548.356706] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.965s)
[548.359606] Subtest wait-forked-hang: SUCCESS (52.162s)
[549.156187] [129/156] (474s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[549.232789] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[549.235495] Starting dynamic subtest: pipe-A-default-mode
[550.427182] Dynamic subtest pipe-A-default-mode: SKIP (1.190s)
[550.432048] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.191s)
[550.571575] [130/156] (473s left) gem_ctx_exec (basic-invalid-context)
[550.623015] Starting subtest: basic-invalid-context
[550.627856] Subtest basic-invalid-context: SUCCESS (0.005s)
[550.744122] [131/156] (473s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[550.824779] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[550.830046] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[550.925279] [132/156] (473s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[551.011742] Starting subtest: fbc-psr2-cursor-plane-update-sf
[551.014307] Starting dynamic subtest: pipe-A-eDP-1
[551.087878] Dynamic subtest pipe-A-eDP-1: SKIP (0.080s)
[551.100045] Starting dynamic subtest: pipe-B-eDP-1
[552.275610] Dynamic subtest pipe-B-eDP-1: SKIP (1.184s)
[552.280945] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.266s)
[553.472693] [133/156] (470s left) gem_ppgtt (blt-vs-render-ctxn)
[553.535599] Starting subtest: blt-vs-render-ctxN
[586.322789] Subtest blt-vs-render-ctxN: SUCCESS (32.781s)
[586.448942] [134/156] (437s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[586.539011] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[586.607141] [135/156] (437s left) gem_softpin (invalid)
[586.665616] Starting subtest: invalid
[586.673828] Subtest invalid: SUCCESS (0.011s)
[586.791288] [136/156] (437s left) gem_exec_capture (capture)
[586.844606] Starting subtest: capture
[586.847269] Starting dynamic subtest: rcs0-smem
[587.683679] Dynamic subtest rcs0-smem: SUCCESS (0.836s)
[587.695403] Starting dynamic subtest: bcs0-smem
[588.507910] Dynamic subtest bcs0-smem: SUCCESS (0.823s)
[588.519713] Starting dynamic subtest: vcs0-smem
[589.332130] Dynamic subtest vcs0-smem: SUCCESS (0.824s)
[589.343512] Starting dynamic subtest: vcs1-smem
[590.645882] Dynamic subtest vcs1-smem: SUCCESS (1.312s)
[590.657299] Starting dynamic subtest: vecs0-smem
[591.549343] Dynamic subtest vecs0-smem: FAIL (0.830s)
[591.561672] Starting dynamic subtest: ccs0-smem
[592.791992] Dynamic subtest ccs0-smem: SUCCESS (1.315s)
[592.794923] Subtest capture: FAIL (5.945s)
[592.903287] [137/156] (431s left) gem_tiled_blits (basic)
[592.966955] Subtest basic: SKIP (0.000s)
[593.042847] [138/156] (431s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[593.126815] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[600.456292] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.334s)
[600.583645] [139/156] (423s left) gem_mmap_offset (partial-remap)
[600.646904] Starting subtest: partial-remap
[600.650002] Starting dynamic subtest: smem0
[600.660785] Dynamic subtest smem0: SUCCESS (0.003s)
[600.663775] Subtest partial-remap: SUCCESS (0.004s)
[600.752402] [140/156] (423s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[600.829448] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[600.834690] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[600.918675] [141/156] (423s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[601.004834] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[601.009794] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[601.086527] [142/156] (423s left) i915_power (sanity)
[601.143823] Starting subtest: sanity
[605.193916] Subtest sanity: SUCCESS (4.043s)
[606.665483] [143/156] (417s left) gem_exec_schedule (preempt-queue)
[606.752785] Subtest preempt-queue: SKIP (0.000s)
[607.041125] [144/156] (417s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[607.159528] Starting subtest: cursorB-vs-flipA-legacy
[607.164311] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[607.303445] [145/156] (417s left) i915_pm_rps (reset)
[607.363246] Starting subtest: reset
[615.785195] Subtest reset: SUCCESS (8.421s)
[615.897020] [146/156] (408s left) kms_psr (fbc-psr-cursor-plane-onoff)
[615.973247] Starting subtest: fbc-psr-cursor-plane-onoff
[615.975991] Starting dynamic subtest: eDP-1
[615.980752] Dynamic subtest eDP-1: SKIP (0.000s)
[615.985573] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[616.053122] [147/156] (408s left) kms_setmode (clone-exclusive-crtc)
[616.110664] Starting subtest: clone-exclusive-crtc
[616.118529] Subtest clone-exclusive-crtc: SKIP (0.000s)
[616.211426] [148/156] (408s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[616.294922] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[623.002324] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.712s)
[623.144786] [149/156] (401s left) kms_flip (flip-vs-rmfb)
[623.223651] Starting subtest: flip-vs-rmfb
[623.226251] Starting dynamic subtest: A-eDP1
[631.900050] Dynamic subtest A-eDP1: SUCCESS (8.678s)
[631.911147] Starting dynamic subtest: B-eDP1
[640.539011] Dynamic subtest B-eDP1: SUCCESS (8.638s)
[640.550119] Starting dynamic subtest: C-eDP1
[649.200637] Dynamic subtest C-eDP1: SUCCESS (8.660s)
[649.212719] Starting dynamic subtest: D-eDP1
[657.935497] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[657.937797] Subtest flip-vs-rmfb: SUCCESS (34.720s)
[659.175039] [150/156] (365s left) gem_exec_whisper (basic-contexts-all)
[659.239842] Starting subtest: basic-contexts-all
[669.587014] Subtest basic-contexts-all: SUCCESS (10.345s)
[669.769915] [151/156] (355s left) dmabuf_sync_file (export-basic)
[669.812948] Starting subtest: export-basic
[669.815766] Subtest export-basic: SUCCESS (0.001s)
[669.870992] [152/156] (355s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[669.952149] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[669.956781] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[670.032520] [153/156] (354s left) gem_sync (basic-each)
[670.097423] Starting subtest: basic-each
[672.449805] Subtest basic-each: SUCCESS (2.366s)
[672.633716] [154/156] (352s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[672.709587] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[672.712083] Starting dynamic subtest: pipe-A-eDP-1
[672.739813] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[672.751040] Starting dynamic subtest: pipe-B-eDP-1
[672.778703] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[672.789786] Starting dynamic subtest: pipe-C-eDP-1
[672.814687] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[672.824631] Starting dynamic subtest: pipe-D-eDP-1
[672.849701] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[672.854205] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[675.050104] [155/156] (350s left) syncobj_wait (multi-wait-for-submit-submitted)
[675.098574] Starting subtest: multi-wait-for-submit-submitted
[675.204716] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[675.282534] [156/156] (349s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[675.354936] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[675.359894] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[675.453543] 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.