[58.898166] Initializing watchdogs
[58.898213]   /dev/watchdog0
[58.913394] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.917993] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.920232] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.923362] [001/131] (960s left) kms_feature_discovery (display-2x)
[59.032603] Starting subtest: display-2x
[59.036794] Subtest display-2x: SKIP (0.000s)
[59.098823] [002/131] (960s left) api_intel_allocator (gem-pool)
[59.146886] Starting subtest: gem-pool
[59.154610] Subtest gem-pool: SUCCESS (0.011s)
[59.293681] [003/131] (960s left) gem_eio (throttle)
[59.430602] Starting subtest: throttle
[59.585358] Subtest throttle: SUCCESS (0.155s)
[59.723886] [004/131] (959s left) kms_flip (basic-plain-flip)
[59.822649] Starting subtest: basic-plain-flip
[59.824716] Starting dynamic subtest: A-eDP1
[61.540766] Dynamic subtest A-eDP1: SUCCESS (1.720s)
[61.550431] Starting dynamic subtest: B-eDP1
[63.259439] Dynamic subtest B-eDP1: SUCCESS (1.721s)
[63.269121] Starting dynamic subtest: C-eDP1
[64.971572] Dynamic subtest C-eDP1: SUCCESS (1.714s)
[64.980854] Starting dynamic subtest: D-eDP1
[66.698147] Dynamic subtest D-eDP1: SUCCESS (1.728s)
[66.700508] Subtest basic-plain-flip: SUCCESS (6.889s)
[67.852263] [005/131] (951s left) i915_module_load (reload-with-fault-injection)
[67.887473] Starting subtest: reload-with-fault-injection
[94.843537] Subtest reload-with-fault-injection: SUCCESS (26.957s)
[94.869611] [FACT i915_module_load (reload-with-fault-injection)] deleted: kernel.kmod_is_loaded.i915: true
[94.872656] [006/131] (924s left) sysfs_timeslice_duration (timeout)
[96.350684] Starting subtest: timeout
[96.353217] Starting dynamic subtest: vecs0
[105.878857] Dynamic subtest vecs0: SUCCESS (9.524s)
[105.887676] Starting dynamic subtest: ccs0
[106.906756] Dynamic subtest ccs0: SUCCESS (1.028s)
[106.914384] Starting dynamic subtest: vcs0
[116.900784] Dynamic subtest vcs0: SUCCESS (9.992s)
[116.909901] Starting dynamic subtest: bcs0
[117.464693] Dynamic subtest bcs0: SUCCESS (0.564s)
[117.474365] Starting dynamic subtest: rcs0
[118.647885] Dynamic subtest rcs0: SUCCESS (1.182s)
[118.656804] Starting dynamic subtest: vcs1
[127.883997] Dynamic subtest vcs1: SUCCESS (9.234s)
[127.886620] Subtest timeout: SUCCESS (31.528s)
[127.925375] [FACT sysfs_timeslice_duration (timeout)] new: kernel.kmod_is_loaded.i915: true
[127.929169] [007/131] (891s left) kms_cursor_crc (cursor-size-change)
[128.030186] Starting subtest: cursor-size-change
[128.032549] Starting dynamic subtest: pipe-A-eDP-1
[129.140296] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.106s)
[129.148151] Starting dynamic subtest: pipe-D-eDP-1
[130.594627] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.454s)
[130.596880] Subtest cursor-size-change: SUCCESS (2.562s)
[131.448742] [008/131] (888s left) core_auth (basic-auth)
[131.502498] Starting subtest: basic-auth
[131.504937] Subtest basic-auth: SUCCESS (0.001s)
[131.596969] [009/131] (888s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[131.693979] Starting subtest: random-ccs-data-yf-tiled-ccs
[131.696314] Starting dynamic subtest: pipe-A-eDP-1
[131.721761] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[131.730433] Starting dynamic subtest: pipe-B-eDP-1
[131.754566] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[131.762088] Starting dynamic subtest: pipe-C-eDP-1
[131.786688] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[131.795092] Starting dynamic subtest: pipe-D-eDP-1
[131.819588] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[131.823710] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.003s)
[133.809778] [010/131] (885s left) gem_create (busy-create)
[133.870930] Starting subtest: busy-create
[133.873147] Starting dynamic subtest: smem0
[166.901768] Dynamic subtest smem0: SUCCESS (33.029s)
[166.904778] Subtest busy-create: SUCCESS (33.030s)
[167.083769] [011/131] (852s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[167.147430] Starting subtest: etime-single-wait-for-submit-unsubmitted
[167.255984] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[167.327553] [012/131] (852s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[167.447726] Starting subtest: basic-flip-after-cursor-varying-size
[168.363663] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.916s)
[169.242346] [013/131] (850s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[169.349687] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[169.354182] Subtest fbc-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[169.461699] [014/131] (850s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[169.562830] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[169.566990] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[169.652284] [015/131] (850s left) perf (invalid-create-userspace-config)
[169.766431] Starting subtest: invalid-create-userspace-config
[169.768302] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[169.804299] [016/131] (850s left) kms_plane (plane-position-hole-dpms)
[169.893078] Starting subtest: plane-position-hole-dpms
[170.408759] Starting dynamic subtest: pipe-A-plane-1
[172.729470] Dynamic subtest pipe-A-plane-1: SUCCESS (2.323s)
[172.736618] Starting dynamic subtest: pipe-A-plane-2
[175.041504] Dynamic subtest pipe-A-plane-2: SUCCESS (2.308s)
[175.050281] Starting dynamic subtest: pipe-A-plane-3
[177.308063] Dynamic subtest pipe-A-plane-3: SUCCESS (2.268s)
[177.317267] Starting dynamic subtest: pipe-A-plane-4
[179.656878] Dynamic subtest pipe-A-plane-4: SUCCESS (2.349s)
[179.665094] Starting dynamic subtest: pipe-A-plane-5
[179.695794] Dynamic subtest pipe-A-plane-5: SUCCESS (0.038s)
[180.831763] Starting dynamic subtest: pipe-B-plane-1
[183.082916] Dynamic subtest pipe-B-plane-1: SUCCESS (2.255s)
[183.090412] Starting dynamic subtest: pipe-B-plane-2
[185.304765] Dynamic subtest pipe-B-plane-2: SUCCESS (2.220s)
[185.312936] Starting dynamic subtest: pipe-B-plane-3
[187.662058] Dynamic subtest pipe-B-plane-3: SUCCESS (2.357s)
[187.669281] Starting dynamic subtest: pipe-B-plane-4
[189.919796] Dynamic subtest pipe-B-plane-4: SUCCESS (2.258s)
[189.927687] Starting dynamic subtest: pipe-B-plane-5
[189.957306] Dynamic subtest pipe-B-plane-5: SUCCESS (0.037s)
[189.959157] Subtest plane-position-hole-dpms: SUCCESS (20.061s)
[190.733475] [017/131] (829s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[190.837266] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[190.839483] Starting dynamic subtest: pipe-A-default-mode
[193.534548] Dynamic subtest pipe-A-default-mode: SUCCESS (2.695s)
[193.537046] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.697s)
[194.400180] [018/131] (825s left) gem_exec_schedule (smoketest-all)
[194.478894] Starting subtest: smoketest-all
[226.847739] Subtest smoketest-all: SUCCESS (32.324s)
[227.983331] [019/131] (792s left) perf (per-context-mode-unprivileged)
[228.063747] Starting subtest: per-context-mode-unprivileged
[228.068580] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[228.106327] [020/131] (792s left) gem_exec_fence (syncobj-timeline-signal)
[228.702748] Starting subtest: syncobj-timeline-signal
[228.713850] Subtest syncobj-timeline-signal: SUCCESS (0.013s)
[230.015899] [FACT gem_exec_fence (syncobj-timeline-signal)] new: kernel.kmod_is_loaded.vgem: true
[230.019097] [021/131] (790s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[230.110203] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[230.112520] Starting dynamic subtest: pipe-A
[230.121664] Dynamic subtest pipe-A: SKIP (0.005s)
[230.129472] Starting dynamic subtest: pipe-B
[230.139412] Dynamic subtest pipe-B: SKIP (0.017s)
[230.148908] Starting dynamic subtest: pipe-C
[230.161393] Dynamic subtest pipe-C: SKIP (0.022s)
[230.168755] Starting dynamic subtest: pipe-D
[230.177349] Dynamic subtest pipe-D: SKIP (0.018s)
[230.181836] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.063s)
[230.278972] [022/131] (789s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[230.369078] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[230.371488] Starting dynamic subtest: pipe-A-eDP-1
[231.217245] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.838s)
[231.224773] Starting dynamic subtest: pipe-B-eDP-1
[232.707434] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.487s)
[232.716405] Starting dynamic subtest: pipe-C-eDP-1
[234.204546] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.498s)
[234.213303] Starting dynamic subtest: pipe-D-eDP-1
[235.717048] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.512s)
[235.718939] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.339s)
[236.888765] [023/131] (783s left) kms_cursor_crc (cursor-onscreen-32x32)
[237.045548] Starting subtest: cursor-onscreen-32x32
[237.087494] Subtest cursor-onscreen-32x32: SKIP (0.038s)
[237.381073] [024/131] (782s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[237.476351] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[237.480349] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[237.572615] [025/131] (782s left) kms_prop_blob (blob-prop-core)
[237.627301] Starting subtest: blob-prop-core
[237.629667] Subtest blob-prop-core: SUCCESS (0.000s)
[237.724349] [026/131] (782s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[237.823836] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[244.218797] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.399s)
[244.355364] [027/131] (776s left) gem_exec_schedule (preempt-queue-chain)
[244.419332] Subtest preempt-queue-chain: SKIP (0.000s)
[244.704034] [028/131] (775s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[244.800051] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[244.802381] Starting dynamic subtest: pipe-A-default-mode
[245.090940] Dynamic subtest pipe-A-default-mode: SKIP (0.289s)
[245.095668] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.290s)
[245.906424] [029/131] (774s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[246.016795] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[246.021240] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[246.114450] [030/131] (774s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[251.139925] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[251.149071] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[251.992344] [031/131] (768s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[252.054890] Starting subtest: yf-tiled-ccs-to-y-tiled
[252.059303] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[252.237552] [032/131] (768s left) perf_pmu (semaphore-wait)
[252.274254] Starting subtest: semaphore-wait
[252.276549] Starting dynamic subtest: rcs0
[252.790637] Dynamic subtest rcs0: SUCCESS (0.515s)
[252.799951] Starting dynamic subtest: bcs0
[253.301689] Dynamic subtest bcs0: SUCCESS (0.510s)
[253.312558] Starting dynamic subtest: vcs0
[253.815446] Dynamic subtest vcs0: SUCCESS (0.511s)
[253.825891] Starting dynamic subtest: vcs1
[254.328334] Dynamic subtest vcs1: SUCCESS (0.510s)
[254.339136] Starting dynamic subtest: vecs0
[254.841883] Dynamic subtest vecs0: SUCCESS (0.511s)
[254.853231] Starting dynamic subtest: ccs0
[255.356777] Dynamic subtest ccs0: SUCCESS (0.513s)
[255.359961] Subtest semaphore-wait: SUCCESS (3.082s)
[255.497876] [033/131] (765s left) kms_flip (2x-plain-flip-interruptible)
[255.607064] Starting subtest: 2x-plain-flip-interruptible
[255.618602] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[255.691711] [034/131] (764s left) gem_exec_flush (basic-wb-rw-before-default)
[255.749796] Starting subtest: basic-wb-rw-before-default
[261.237966] Subtest basic-wb-rw-before-default: SUCCESS (5.458s)
[261.395495] [035/131] (759s left) gen7_exec_parse (oacontrol-tracking)
[261.455641] Subtest oacontrol-tracking: SKIP (0.000s)
[261.529255] [036/131] (759s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[261.635596] Starting subtest: yf-tiled-16bpp-rotate-270
[261.640097] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[261.742361] [037/131] (759s left) kms_psr (psr-sprite-plane-move)
[261.847182] Starting subtest: psr-sprite-plane-move
[261.849388] Starting dynamic subtest: eDP-1
[263.631699] Dynamic subtest eDP-1: SUCCESS (1.784s)
[263.634180] Subtest psr-sprite-plane-move: SUCCESS (1.785s)
[264.446912] [038/131] (756s left) gem_exec_params (rsvd2-dirt)
[264.509869] Starting subtest: rsvd2-dirt
[264.514178] Subtest rsvd2-dirt: SKIP (0.000s)
[264.610426] [039/131] (756s left) kms_flip (flip-vs-wf_vblank-interruptible)
[264.708238] Starting subtest: flip-vs-wf_vblank-interruptible
[264.710562] Starting dynamic subtest: A-eDP1
[266.695216] Dynamic subtest A-eDP1: SUCCESS (1.986s)
[266.708903] Starting dynamic subtest: B-eDP1
[268.622098] Dynamic subtest B-eDP1: SUCCESS (1.925s)
[268.633065] Starting dynamic subtest: C-eDP1
[270.538459] Dynamic subtest C-eDP1: SUCCESS (1.914s)
[270.546177] Starting dynamic subtest: D-eDP1
[272.463474] Dynamic subtest D-eDP1: SUCCESS (1.924s)
[272.468708] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.760s)
[273.686532] [040/131] (747s left) kms_universal_plane (universal-plane-pageflip-windowed)
[273.781334] Starting subtest: universal-plane-pageflip-windowed
[273.783742] Starting dynamic subtest: pipe-A-eDP-1
[274.390752] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.606s)
[274.399380] Starting dynamic subtest: pipe-B-eDP-1
[275.602284] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.212s)
[275.610801] Starting dynamic subtest: pipe-C-eDP-1
[276.817333] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.214s)
[276.825341] Starting dynamic subtest: pipe-D-eDP-1
[278.050506] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s)
[278.052506] Subtest universal-plane-pageflip-windowed: SUCCESS (4.266s)
[278.894906] [041/131] (742s left) gem_exec_balancer (nohangcheck)
[279.160933] Starting subtest: nohangcheck
[280.065105] Subtest nohangcheck: SUCCESS (0.903s)
[280.206926] [042/131] (740s left) kms_big_fb (linear-16bpp-rotate-180)
[280.315473] Starting subtest: linear-16bpp-rotate-180
[281.813355] Subtest linear-16bpp-rotate-180: SUCCESS (1.500s)
[281.934403] [043/131] (739s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[282.041261] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[282.044822] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[282.134963] [044/131] (738s left) gem_exec_async (forked-writes)
[282.192238] Starting subtest: forked-writes
[282.194151] Starting dynamic subtest: rcs0
[282.220212] Dynamic subtest rcs0: SUCCESS (0.027s)
[282.231181] Starting dynamic subtest: bcs0
[282.248626] Dynamic subtest bcs0: SUCCESS (0.028s)
[282.259305] Starting dynamic subtest: vcs0
[282.271168] Dynamic subtest vcs0: SUCCESS (0.022s)
[282.280842] Starting dynamic subtest: vcs1
[282.294993] Dynamic subtest vcs1: SUCCESS (0.023s)
[282.303168] Starting dynamic subtest: vecs0
[282.319234] Dynamic subtest vecs0: SUCCESS (0.023s)
[282.327462] Starting dynamic subtest: ccs0
[282.342947] Dynamic subtest ccs0: SUCCESS (0.023s)
[282.344971] Subtest forked-writes: SUCCESS (0.151s)
[282.598462] [045/131] (738s left) gem_mmap_gtt (basic-write)
[282.665235] Subtest basic-write: SKIP (0.000s)
[282.732381] [046/131] (738s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[282.825418] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[282.935461] [047/131] (738s left) kms_chamelium_edid (hdmi-mode-timings)
[283.330847] Subtest hdmi-mode-timings: SKIP (0.000s)
[284.097953] [048/131] (737s left) kms_vrr (seamless-rr-switch-drrs)
[284.202201] Starting subtest: seamless-rr-switch-drrs
[284.208937] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[284.271075] [049/131] (736s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[284.335713] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[284.402654] [050/131] (736s left) kms_psr (pr-primary-page-flip)
[284.498157] Starting subtest: pr-primary-page-flip
[284.502527] Subtest pr-primary-page-flip: SKIP (0.000s)
[284.565904] [051/131] (736s left) kms_psr (fbc-psr-cursor-plane-move)
[284.655094] Starting subtest: fbc-psr-cursor-plane-move
[284.656948] Starting dynamic subtest: eDP-1
[284.660924] Dynamic subtest eDP-1: SKIP (0.000s)
[284.664925] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[284.725416] [052/131] (736s left) i915_selftest (perf)
[288.176426] Starting subtest: perf
[288.178970] Starting dynamic subtest: engine_cs
[292.553025] Dynamic subtest engine_cs: SUCCESS (4.376s)
[292.560844] Starting dynamic subtest: request
[299.909061] Dynamic subtest request: SUCCESS (7.354s)
[299.918034] Starting dynamic subtest: migrate
[304.369780] Dynamic subtest migrate: SUCCESS (4.459s)
[304.377353] Starting dynamic subtest: region
[308.810320] Dynamic subtest region: SUCCESS (4.438s)
[308.813211] Subtest perf: SUCCESS (20.635s)
[308.840628] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[308.841474] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[308.844850] [053/131] (712s left) kms_invalid_mode (zero-vdisplay)
[310.346508] Starting subtest: zero-vdisplay
[310.348845] Starting dynamic subtest: pipe-A-eDP-1
[310.641837] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[310.651108] Starting dynamic subtest: pipe-B-eDP-1
[310.653453] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[310.662757] Starting dynamic subtest: pipe-C-eDP-1
[310.665085] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[310.672503] Starting dynamic subtest: pipe-D-eDP-1
[310.674485] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[310.676399] Subtest zero-vdisplay: SUCCESS (0.298s)
[311.500938] [FACT kms_invalid_mode (zero-vdisplay)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[311.502259] [FACT kms_invalid_mode (zero-vdisplay)] new: kernel.kmod_is_loaded.i915: true
[311.506134] [054/131] (709s left) tools_test (sysfs_l3_parity)
[311.554127] Starting subtest: sysfs_l3_parity
[311.638310] Subtest sysfs_l3_parity: SKIP (0.094s)
[311.689932] [055/131] (709s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[316.743704] Starting subtest: fbc-pr-cursor-plane-update-sf
[316.747829] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.002s)
[317.579466] [056/131] (703s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[317.681496] Starting subtest: fbcpsr-slowdraw
[342.809505] Subtest fbcpsr-slowdraw: SUCCESS (25.129s)
[342.945719] [057/131] (678s left) syncobj_timeline (signal)
[343.001298] Starting subtest: signal
[343.003619] Subtest signal: SUCCESS (0.000s)
[343.107328] [058/131] (678s left) kms_content_protection (atomic)
[343.274683] Starting subtest: atomic
[346.601580] Subtest atomic: SKIP (3.323s)
[347.765840] [059/131] (673s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[347.869807] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[347.874028] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[347.962162] [060/131] (673s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[348.067882] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[348.072359] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[348.153404] [061/131] (673s left) gem_vm_create (execbuf)
[348.215574] Starting subtest: execbuf
[348.222189] Subtest execbuf: SUCCESS (0.007s)
[348.359890] [062/131] (673s left) gem_softpin (reverse)
[348.416020] Starting subtest: reverse
[348.425647] Subtest reverse: SUCCESS (0.010s)
[348.551744] [063/131] (673s left) core_setmaster_vs_auth
[348.732158] [064/131] (673s left) kms_lease (lessee-list)
[348.837545] Starting subtest: lessee-list
[348.839590] Starting dynamic subtest: pipe-A-eDP-1
[348.841615] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[348.849901] Starting dynamic subtest: pipe-B-eDP-1
[348.851893] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[348.864466] Starting dynamic subtest: pipe-C-eDP-1
[348.866236] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[348.873459] Starting dynamic subtest: pipe-D-eDP-1
[348.875316] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[348.877159] Subtest lessee-list: SUCCESS (0.009s)
[348.946609] [065/131] (672s left) gem_ctx_persistence (legacy-engines-cleanup)
[349.009727] Starting subtest: legacy-engines-cleanup
[349.011864] Starting dynamic subtest: render
[349.112299] Dynamic subtest render: SUCCESS (0.101s)
[349.123097] Starting dynamic subtest: bsd1
[349.160372] Dynamic subtest bsd1: SUCCESS (0.047s)
[349.169344] Starting dynamic subtest: bsd2
[349.210183] Dynamic subtest bsd2: SUCCESS (0.048s)
[349.219576] Starting dynamic subtest: blt
[349.265196] Dynamic subtest blt: SUCCESS (0.053s)
[349.277162] Starting dynamic subtest: vebox
[349.286349] Dynamic subtest vebox: SUCCESS (0.019s)
[349.288292] Subtest legacy-engines-cleanup: SUCCESS (0.277s)
[349.414418] [066/131] (672s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[349.518919] Starting subtest: fbc-pr-sprite-mmap-cpu
[349.522667] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[349.586097] [067/131] (672s left) gem_fence_thrash (bo-copy)
[349.618113] Starting subtest: bo-copy
[349.641767] Subtest bo-copy: SKIP (0.022s)
[349.704155] [068/131] (672s left) kms_atomic (plane-immutable-zpos)
[349.802717] Starting subtest: plane-immutable-zpos
[349.804728] Starting dynamic subtest: pipe-A-eDP-1
[350.528428] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[350.530533] Subtest plane-immutable-zpos: SUCCESS (0.724s)
[351.394224] [069/131] (670s left) prime_mmap_coherency (ioctl-errors)
[351.459570] Starting subtest: ioctl-errors
[366.436426] Subtest ioctl-errors: SUCCESS (14.977s)
[366.624159] [070/131] (655s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[366.725329] Starting subtest: multi-wait-for-submit-submitted-signaled
[366.727846] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[366.831581] [071/131] (655s left) syncobj_eventfd (invalid-illegal-handle)
[366.876465] Starting subtest: invalid-illegal-handle
[366.878456] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[366.969621] [072/131] (655s left) syncobj_timeline (invalid-wait-illegal-handle)
[367.025373] Starting subtest: invalid-wait-illegal-handle
[367.027865] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[367.145290] [073/131] (655s left) gem_ctx_param (invalid-ctx-set)
[367.208067] Starting subtest: invalid-ctx-set
[367.210398] Subtest invalid-ctx-set: SUCCESS (0.001s)
[367.322843] [074/131] (654s left) kms_pipe_crc_basic (read-crc)
[367.424959] Starting subtest: read-crc
[367.427259] Starting dynamic subtest: pipe-A-eDP-1
[368.160629] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.734s)
[368.169587] Starting dynamic subtest: pipe-B-eDP-1
[369.562412] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.403s)
[369.571541] Starting dynamic subtest: pipe-C-eDP-1
[370.945124] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.380s)
[370.954533] Starting dynamic subtest: pipe-D-eDP-1
[372.267869] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[372.270287] Subtest read-crc: SUCCESS (4.844s)
[373.090545] [075/131] (649s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[373.222804] Starting subtest: verify-pxp-key-change-after-suspend-resume
[380.414410] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.194s)
[380.551241] [076/131] (641s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[380.667200] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[387.101177] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.437s)
[387.229191] [077/131] (635s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[387.335120] Starting subtest: bad-rotation-90-y-tiled-ccs
[387.337413] Starting dynamic subtest: pipe-A-eDP-1
[387.360388] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[387.370364] Starting dynamic subtest: pipe-B-eDP-1
[387.391838] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[387.400535] Starting dynamic subtest: pipe-C-eDP-1
[387.421634] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[387.429596] Starting dynamic subtest: pipe-D-eDP-1
[387.451783] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[387.456164] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.004s)
[389.606338] [078/131] (632s left) kms_vrr (flipline)
[389.698064] Starting subtest: flipline
[389.704764] Subtest flipline: SKIP (0.000s)
[389.770191] [079/131] (632s left) vgem_slow (nohang)
[389.813364] Starting subtest: nohang
[399.985782] Subtest nohang: SUCCESS (10.171s)
[400.053309] [080/131] (622s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[400.149082] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[400.151220] Starting dynamic subtest: pipe-A-eDP-1
[400.208204] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[400.217249] Starting dynamic subtest: pipe-B-eDP-1
[400.272669] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[400.282757] Starting dynamic subtest: pipe-C-eDP-1
[400.336654] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[400.345296] Starting dynamic subtest: pipe-D-eDP-1
[400.403213] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[400.407671] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.008s)
[402.357802] [081/131] (620s left) i915_drm_fdinfo (most-busy-check-all)
[402.403145] Starting subtest: most-busy-check-all
[402.405479] Starting dynamic subtest: rcs0
[402.410070] Dynamic subtest rcs0: SKIP (0.003s)
[402.419848] Starting dynamic subtest: bcs0
[402.425191] Dynamic subtest bcs0: SKIP (0.000s)
[402.436107] Starting dynamic subtest: vcs0
[402.440125] Dynamic subtest vcs0: SKIP (0.000s)
[402.448523] Starting dynamic subtest: vcs1
[402.452649] Dynamic subtest vcs1: SKIP (0.000s)
[402.461092] Starting dynamic subtest: vecs0
[402.465255] Dynamic subtest vecs0: SKIP (0.000s)
[402.473517] Starting dynamic subtest: ccs0
[402.477713] Dynamic subtest ccs0: SKIP (0.000s)
[402.482046] Subtest most-busy-check-all: SKIP (0.008s)
[402.519344] [082/131] (620s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[402.666753] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[402.671124] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[402.754389] [083/131] (619s left) gem_exec_schedule (u-submit-early-slice)
[402.827616] Starting subtest: u-submit-early-slice
[402.829869] Starting dynamic subtest: rcs0
[402.862336] Dynamic subtest rcs0: SUCCESS (0.041s)
[402.873247] Starting dynamic subtest: bcs0
[402.900502] Dynamic subtest bcs0: SUCCESS (0.038s)
[402.910072] Starting dynamic subtest: vcs0
[402.944389] Dynamic subtest vcs0: SUCCESS (0.043s)
[402.954667] Starting dynamic subtest: vcs1
[402.979623] Dynamic subtest vcs1: SUCCESS (0.035s)
[402.988809] Starting dynamic subtest: vecs0
[403.014075] Dynamic subtest vecs0: SUCCESS (0.034s)
[403.023449] Starting dynamic subtest: ccs0
[403.052569] Dynamic subtest ccs0: SUCCESS (0.038s)
[403.054930] Subtest u-submit-early-slice: SUCCESS (0.232s)
[404.982916] [084/131] (617s left) kms_properties (get_properties-sanity-non-atomic)
[405.075096] Starting subtest: get_properties-sanity-non-atomic
[405.077453] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[405.151971] [085/131] (617s left) gem_exec_whisper (basic-normal-all)
[405.215689] Starting subtest: basic-normal-all
[407.489531] Subtest basic-normal-all: SUCCESS (2.273s)
[407.673410] [086/131] (615s left) gem_ctx_shared (exec-shared-gtt)
[407.740337] Starting subtest: exec-shared-gtt
[407.742823] Starting dynamic subtest: rcs0
[407.752078] Dynamic subtest rcs0: SUCCESS (0.011s)
[407.763502] Starting dynamic subtest: bcs0
[407.765679] Dynamic subtest bcs0: SUCCESS (0.009s)
[407.777828] Starting dynamic subtest: vcs0
[407.779793] Dynamic subtest vcs0: SUCCESS (0.011s)
[407.788895] Starting dynamic subtest: vcs1
[407.791122] Dynamic subtest vcs1: SUCCESS (0.008s)
[407.800212] Starting dynamic subtest: vecs0
[407.802205] Dynamic subtest vecs0: SUCCESS (0.011s)
[407.811486] Starting dynamic subtest: ccs0
[407.813860] Dynamic subtest ccs0: SUCCESS (0.015s)
[407.815939] Subtest exec-shared-gtt: SUCCESS (0.068s)
[407.999890] [087/131] (614s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[408.096299] Starting subtest: bad-aux-stride-yf-tiled-ccs
[408.098526] Starting dynamic subtest: pipe-A-eDP-1
[408.121897] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[408.133848] Starting dynamic subtest: pipe-B-eDP-1
[408.156076] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[408.165456] Starting dynamic subtest: pipe-C-eDP-1
[408.187430] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[408.196448] Starting dynamic subtest: pipe-D-eDP-1
[408.218777] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[408.222868] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[410.211914] [088/131] (612s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[410.509702] Starting subtest: cursor-rapid-movement-512x170
[410.514234] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[410.662525] [089/131] (612s left) gem_pread (exhaustion)
[410.719782] Subtest exhaustion: SKIP (0.000s)
[410.784146] [090/131] (612s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[410.845722] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[410.848265] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[410.945817] [091/131] (611s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[411.046335] Starting subtest: x-tiled-8bpp-rotate-180
[412.258517] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.214s)
[412.459842] [092/131] (610s left) gem_mmap (basic)
[412.520793] Subtest basic: SKIP (0.000s)
[412.586216] [093/131] (610s left) perf_pmu (rc6-all-gts)
[412.622783] Starting subtest: rc6-all-gts
[419.035176] Subtest rc6-all-gts: SUCCESS (6.359s)
[419.889523] [094/131] (603s left) gem_workarounds (basic-read-context)
[419.956761] Starting subtest: basic-read-context
[419.974221] Subtest basic-read-context: SUCCESS (0.018s)
[420.117979] [095/131] (602s left) gem_madvise (dontneed-after-mmap)
[420.157978] Starting subtest: dontneed-after-mmap
[420.183269] Subtest dontneed-after-mmap: SUCCESS (0.027s)
[420.292444] [096/131] (602s left) kms_color (ctm-max)
[420.392656] Starting subtest: ctm-max
[420.394583] Starting dynamic subtest: pipe-A-eDP-1
[421.124354] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.730s)
[421.134781] Starting dynamic subtest: pipe-B-eDP-1
[422.507090] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.379s)
[422.517386] Starting dynamic subtest: pipe-C-eDP-1
[423.880099] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.372s)
[423.891306] Starting dynamic subtest: pipe-D-eDP-1
[425.181843] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.303s)
[425.184297] Subtest ctm-max: SUCCESS (4.788s)
[426.015958] [097/131] (597s left) api_intel_bb (blit-reloc-purge-cache)
[426.070521] Starting subtest: blit-reloc-purge-cache
[426.075067] Subtest blit-reloc-purge-cache: SKIP (0.000s)
[426.214120] [098/131] (597s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[426.315496] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[426.319502] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[426.421362] [099/131] (596s left) kms_cursor_edge_walk (256x256-top-edge)
[426.516484] Starting subtest: 256x256-top-edge
[426.519032] Starting dynamic subtest: pipe-A-eDP-1
[429.975266] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.456s)
[429.984577] Starting dynamic subtest: pipe-D-eDP-1
[433.093987] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.115s)
[433.096345] Subtest 256x256-top-edge: SUCCESS (6.574s)
[433.886748] [100/131] (589s left) gem_userptr_blits (sync-unmap-after-close)
[433.961465] Starting subtest: sync-unmap-after-close
[433.973796] Subtest sync-unmap-after-close: SUCCESS (0.014s)
[434.091812] [101/131] (589s left) kms_psr (fbc-pr-sprite-plane-onoff)
[434.196300] Starting subtest: fbc-pr-sprite-plane-onoff
[434.200842] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[434.274587] [102/131] (589s left) i915_pm_rps (thresholds-idle)
[434.332792] Starting subtest: thresholds-idle
[434.337522] Subtest thresholds-idle: SKIP (0.001s)
[434.416192] [103/131] (589s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[434.524599] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[435.703916] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.178s)
[435.936216] [104/131] (587s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[436.046906] Starting subtest: extended-modeset-hang-newfb-with-reset
[436.049259] Starting dynamic subtest: pipe-A
[449.678813] Dynamic subtest pipe-A: SUCCESS (13.630s)
[449.689690] Starting dynamic subtest: pipe-D
[465.537070] Dynamic subtest pipe-D: SUCCESS (15.855s)
[465.539546] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.489s)
[466.332980] [105/131] (557s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[466.442267] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[473.401275] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.963s)
[473.526934] [106/131] (550s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[473.636200] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[473.640295] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[473.735866] [107/131] (549s left) gem_userptr_blits (invalid-null-pointer)
[473.806931] Starting subtest: invalid-null-pointer
[473.809186] Subtest invalid-null-pointer: SUCCESS (0.001s)
[473.953408] [108/131] (549s left) gem_exec_schedule (fifo)
[474.022760] Starting subtest: fifo
[474.024976] Starting dynamic subtest: rcs0
[474.112495] Dynamic subtest rcs0: SUCCESS (0.099s)
[474.125157] Starting dynamic subtest: vcs0
[474.190656] Dynamic subtest vcs0: SUCCESS (0.078s)
[474.204096] Starting dynamic subtest: vcs1
[474.266269] Dynamic subtest vcs1: SUCCESS (0.075s)
[474.277817] Starting dynamic subtest: vecs0
[474.339796] Dynamic subtest vecs0: SUCCESS (0.073s)
[474.352308] Starting dynamic subtest: ccs0
[474.418539] Dynamic subtest ccs0: SUCCESS (0.078s)
[474.421210] Subtest fifo: SUCCESS (0.405s)
[476.043925] [109/131] (547s left) gem_lmem_swapping (verify-ccs)
[476.083554] Subtest verify-ccs: SKIP (0.000s)
[476.130130] [110/131] (547s left) syncobj_wait (multi-wait-submitted)
[476.189511] Starting subtest: multi-wait-submitted
[476.296400] Subtest multi-wait-submitted: SUCCESS (0.106s)
[476.374061] [111/131] (547s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[476.485173] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[476.489147] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[476.600830] [112/131] (547s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[476.689223] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[476.691541] Starting dynamic subtest: pipe-A
[477.028648] Dynamic subtest pipe-A: SUCCESS (0.339s)
[477.038289] Starting dynamic subtest: pipe-B
[478.393913] Dynamic subtest pipe-B: SUCCESS (1.364s)
[478.404584] Starting dynamic subtest: pipe-C
[479.823694] Dynamic subtest pipe-C: SUCCESS (1.429s)
[479.835126] Starting dynamic subtest: pipe-D
[481.261677] Dynamic subtest pipe-D: SUCCESS (1.437s)
[481.264057] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.572s)
[482.429184] [113/131] (541s left) kms_pm_rpm (basic-rte)
[482.466799] Starting subtest: basic-rte
[485.507741] Subtest basic-rte: SUCCESS (3.041s)
[487.302795] [114/131] (536s left) core_setmaster (master-drop-set-shared-fd)
[487.378811] Starting subtest: master-drop-set-shared-fd
[487.388712] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[487.431239] [115/131] (536s left) gem_exec_create (forked)
[487.490471] Starting subtest: forked
[487.492718] Starting dynamic subtest: smem
[508.886064] Dynamic subtest smem: SUCCESS (21.392s)
[508.888917] Subtest forked: SUCCESS (21.394s)
[509.067593] [116/131] (514s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[509.178267] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[509.182182] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[509.282468] [117/131] (514s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[509.381921] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[509.386085] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[509.500683] [118/131] (514s left) gem_exec_fence (parallel)
[509.681215] Starting subtest: parallel
[509.683982] Starting dynamic subtest: rcs0
[511.828500] Dynamic subtest rcs0: SUCCESS (2.148s)
[511.839498] Starting dynamic subtest: bcs0
[513.979187] Dynamic subtest bcs0: SUCCESS (2.150s)
[513.989973] Starting dynamic subtest: vcs0
[516.130027] Dynamic subtest vcs0: SUCCESS (2.150s)
[516.140601] Starting dynamic subtest: vcs1
[518.280323] Dynamic subtest vcs1: SUCCESS (2.150s)
[518.291850] Starting dynamic subtest: vecs0
[520.428958] Dynamic subtest vecs0: SUCCESS (2.148s)
[520.440029] Starting dynamic subtest: ccs0
[522.577391] Dynamic subtest ccs0: SUCCESS (2.148s)
[522.579381] Subtest parallel: SUCCESS (12.897s)
[523.210104] [119/131] (500s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[523.257365] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[523.259711] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[523.370428] [120/131] (500s left) kms_flip (2x-flip-vs-panning-interruptible)
[523.474945] Starting subtest: 2x-flip-vs-panning-interruptible
[523.486761] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[523.566060] [121/131] (500s left) gem_ctx_isolation (dirty-create)
[523.627144] Starting subtest: dirty-create
[523.629644] Starting dynamic subtest: rcs0
[524.073880] Dynamic subtest rcs0: SUCCESS (0.446s)
[524.084548] Starting dynamic subtest: bcs0
[524.549639] Dynamic subtest bcs0: SUCCESS (0.475s)
[524.562019] Starting dynamic subtest: vcs0
[524.989729] Dynamic subtest vcs0: SUCCESS (0.439s)
[525.001614] Starting dynamic subtest: vcs1
[526.348886] Dynamic subtest vcs1: SUCCESS (1.358s)
[526.361144] Starting dynamic subtest: vecs0
[528.333328] Dynamic subtest vecs0: SUCCESS (1.983s)
[528.345973] Starting dynamic subtest: ccs0
[530.336148] Dynamic subtest ccs0: SUCCESS (2.002s)
[530.338623] Subtest dirty-create: SUCCESS (6.709s)
[530.587683] [122/131] (493s left) gem_bad_reloc (negative-reloc)
[530.641685] Subtest negative-reloc: SKIP (0.000s)
[530.707637] [123/131] (493s left) kms_psr (psr2-suspend)
[530.805250] Starting subtest: psr2-suspend
[530.807509] Starting dynamic subtest: eDP-1
[548.895591] Dynamic subtest eDP-1: SUCCESS (2.861s)
[548.897498] Subtest psr2-suspend: SUCCESS (2.862s)
[549.672425] [124/131] (474s left) kms_dsc (dsc-basic)
[549.780313] Starting subtest: dsc-basic
[549.793153] Subtest dsc-basic: SKIP (0.000s)
[549.855806] [125/131] (474s left) kms_chamelium_hpd (dp-hpd)
[550.241458] Subtest dp-hpd: SKIP (0.000s)
[551.067953] [126/131] (473s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[551.174078] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[554.945661] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.795s)
[556.135029] [127/131] (468s left) gen9_exec_parse (shadow-peek)
[556.188534] Subtest shadow-peek: SKIP (0.000s)
[556.282746] [128/131] (468s left) kms_atomic_transition (modeset-transition-nonblocking)
[556.383035] Starting subtest: modeset-transition-nonblocking
[556.385131] Starting dynamic subtest: 1x-outputs
[566.951532] Dynamic subtest 1x-outputs: SUCCESS (10.534s)
[567.173129] Subtest modeset-transition-nonblocking: SUCCESS (10.809s)
[568.026626] [129/131] (456s left) drm_read (short-buffer-block)
[568.196772] Starting subtest: short-buffer-block
[568.199284] Subtest short-buffer-block: SUCCESS (0.000s)
[568.325201] [130/131] (456s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[568.429260] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[568.433596] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[568.505673] [131/131] (456s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[568.606003] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[568.617156] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[568.717671] Closing watchdogs
results: parsing output: 0/ for test: kms_feature_discovery
results: parsing output: 1/ for test: api_intel_allocator
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_flip
results: parsing output: 4/ for test: i915_module_load
results: parsing output: 5/ for test: sysfs_timeslice_duration
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: core_auth
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: gem_create
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: perf
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: gem_exec_schedule
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_prop_blob
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_exec_schedule
results: parsing output: 27/ for test: kms_flip_scaled_crc
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_exec_flush
results: parsing output: 34/ for test: gen7_exec_parse
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: gem_exec_params
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_universal_plane
results: parsing output: 40/ for test: gem_exec_balancer
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_async
results: parsing output: 44/ for test: gem_mmap_gtt
results: parsing output: 45/ for test: kms_addfb_basic
results: parsing output: 46/ for test: kms_chamelium_edid
results: parsing output: 47/ for test: kms_vrr
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: i915_selftest
results: parsing output: 52/ for test: kms_invalid_mode
results: parsing output: 53/ for test: tools_test
results: parsing output: 54/ for test: kms_psr2_sf
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: kms_content_protection
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_vm_create
results: parsing output: 61/ for test: gem_softpin
results: parsing output: 62/ for test: core_setmaster_vs_auth
results: parsing output: 63/ for test: kms_lease
results: parsing output: 64/ for test: gem_ctx_persistence
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: gem_fence_thrash
results: parsing output: 67/ for test: kms_atomic
results: parsing output: 68/ for test: prime_mmap_coherency
results: parsing output: 69/ for test: syncobj_wait
results: parsing output: 70/ for test: syncobj_eventfd
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: gem_ctx_param
results: parsing output: 73/ for test: kms_pipe_crc_basic
results: parsing output: 74/ for test: gem_pxp
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: kms_vrr
results: parsing output: 78/ for test: vgem_slow
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: i915_drm_fdinfo
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_exec_schedule
results: parsing output: 83/ for test: kms_properties
results: parsing output: 84/ for test: gem_exec_whisper
results: parsing output: 85/ for test: gem_ctx_shared
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: gem_pread
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: gem_mmap
results: parsing output: 92/ for test: perf_pmu
results: parsing output: 93/ for test: gem_workarounds
results: parsing output: 94/ for test: gem_madvise
results: parsing output: 95/ for test: kms_color
results: parsing output: 96/ for test: api_intel_bb
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_cursor_edge_walk
results: parsing output: 99/ for test: gem_userptr_blits
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: i915_pm_rps
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_busy
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: gem_userptr_blits
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: gem_lmem_swapping
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: core_setmaster
results: parsing output: 114/ for test: gem_exec_create
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_exec_fence
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: gem_ctx_isolation
results: parsing output: 121/ for test: gem_bad_reloc
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_dsc
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gen9_exec_parse
results: parsing output: 127/ for test: kms_atomic_transition
results: parsing output: 128/ for test: drm_read
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_flip
Done.