[59.955967] Initializing watchdogs
[59.956166]   /dev/watchdog0
[59.970704] [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]
[59.974994] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.977249] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.980408] [001/133] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[60.106570] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[60.110844] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[60.187344] [002/133] (960s left) perf_pmu (semaphore-wait)
[60.231290] Starting subtest: semaphore-wait
[60.233482] Starting dynamic subtest: rcs0
[60.744066] Dynamic subtest rcs0: SUCCESS (0.515s)
[60.754125] Starting dynamic subtest: bcs0
[61.257215] Dynamic subtest bcs0: SUCCESS (0.513s)
[61.267867] Starting dynamic subtest: vcs0
[61.770546] Dynamic subtest vcs0: SUCCESS (0.513s)
[61.781268] Starting dynamic subtest: vcs1
[62.283476] Dynamic subtest vcs1: SUCCESS (0.513s)
[62.294514] Starting dynamic subtest: vecs0
[62.797992] Dynamic subtest vecs0: SUCCESS (0.514s)
[62.807702] Starting dynamic subtest: ccs0
[63.312729] Dynamic subtest ccs0: SUCCESS (0.514s)
[63.315713] Subtest semaphore-wait: SUCCESS (3.090s)
[63.424434] [003/133] (957s left) kms_atomic_transition (plane-all-modeset-transition)
[63.526678] Starting subtest: plane-all-modeset-transition
[63.531055] Subtest plane-all-modeset-transition: SKIP (0.000s)
[63.609187] [004/133] (956s left) i915_pm_rps (basic-api)
[63.680814] Starting subtest: basic-api
[63.685028] Subtest basic-api: SKIP (0.001s)
[63.804889] [005/133] (956s left) kms_async_flips (test-time-stamp-atomic)
[63.901398] Starting subtest: test-time-stamp-atomic
[64.290855] Starting dynamic subtest: pipe-A-eDP-1
[65.053944] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.762s)
[65.430255] Starting dynamic subtest: pipe-B-eDP-1
[66.190426] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[66.564686] Starting dynamic subtest: pipe-C-eDP-1
[67.317989] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.758s)
[67.697945] Starting dynamic subtest: pipe-D-eDP-1
[68.452967] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.762s)
[68.455563] Subtest test-time-stamp-atomic: SUCCESS (4.558s)
[69.611772] [006/133] (951s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[69.703595] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[76.619073] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.922s)
[76.737121] [007/133] (943s left) kms_cursor_crc (cursor-random-32x10)
[76.912211] Starting subtest: cursor-random-32x10
[76.960993] Subtest cursor-random-32x10: SKIP (0.044s)
[77.257380] [008/133] (943s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[77.350856] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[77.354980] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[77.418549] [009/133] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[77.513107] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[77.517236] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[77.597773] [010/133] (943s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[77.708504] Starting subtest: flip-vs-cursor-crc-atomic
[78.428399] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.717s)
[79.297355] [011/133] (941s left) gem_exec_alignment (forked)
[79.359070] Starting subtest: forked
[118.075702] Subtest forked: SUCCESS (38.713s)
[118.149305] [012/133] (902s left) gem_exec_balancer (parallel-balancer)
[118.451237] Starting subtest: parallel-balancer
[119.368238] Subtest parallel-balancer: SUCCESS (0.919s)
[119.470464] [013/133] (901s left) kms_psr (psr2-primary-mmap-cpu)
[119.566037] Starting subtest: psr2-primary-mmap-cpu
[119.568425] Starting dynamic subtest: eDP-1
[121.264634] Dynamic subtest eDP-1: SUCCESS (1.698s)
[121.266624] Subtest psr2-primary-mmap-cpu: SUCCESS (1.699s)
[122.144646] [014/133] (898s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[122.219472] Starting subtest: invalid-signal-one-illegal-handle
[122.221875] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[122.320793] [015/133] (898s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[122.418464] Starting subtest: x-tiled-64bpp-rotate-90
[122.438297] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[122.540506] [016/133] (898s left) syncobj_timeline (etime-multi-wait-submitted)
[122.593122] Starting subtest: etime-multi-wait-submitted
[122.699486] Subtest etime-multi-wait-submitted: SUCCESS (0.106s)
[122.786685] [017/133] (898s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[122.883856] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[122.888251] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[122.983817] [018/133] (898s left) gem_set_tiling_vs_pwrite
[123.114887] [019/133] (898s left) syncobj_timeline (wait-delayed-signal)
[123.172412] Starting subtest: wait-delayed-signal
[123.274807] Subtest wait-delayed-signal: SUCCESS (0.103s)
[123.364619] [020/133] (897s left) kms_psr (psr-sprite-plane-move)
[123.460639] Starting subtest: psr-sprite-plane-move
[123.463129] Starting dynamic subtest: eDP-1
[125.205841] Dynamic subtest eDP-1: SUCCESS (1.745s)
[125.207788] Subtest psr-sprite-plane-move: SUCCESS (1.746s)
[126.021148] [021/133] (895s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[126.141173] Starting subtest: cursor-vs-flip-legacy
[155.560563] Subtest cursor-vs-flip-legacy: SUCCESS (29.417s)
[156.364757] [022/133] (864s left) kms_big_fb (linear-8bpp-rotate-0)
[156.465700] Starting subtest: linear-8bpp-rotate-0
[157.716290] Subtest linear-8bpp-rotate-0: SUCCESS (1.250s)
[157.830704] [023/133] (863s left) kms_psr (pr-basic)
[157.924472] Starting subtest: pr-basic
[157.928117] Subtest pr-basic: SKIP (0.000s)
[157.994921] [024/133] (863s left) gem_exec_whisper (basic-queues-priority-all)
[158.054536] Starting subtest: basic-queues-priority-all
[178.212906] Subtest basic-queues-priority-all: SUCCESS (20.155s)
[178.395565] [025/133] (843s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[178.497605] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[179.532296] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.036s)
[179.664238] [026/133] (841s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[179.765205] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[179.777941] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[179.848360] [027/133] (841s left) i915_hangman (engine-engine-error)
[179.905552] Starting subtest: engine-engine-error
[179.907482] Starting dynamic subtest: rcs0
[181.878714] Dynamic subtest rcs0: SUCCESS (1.970s)
[181.890819] Starting dynamic subtest: bcs0
[183.860616] Dynamic subtest bcs0: SUCCESS (1.981s)
[183.875940] Starting dynamic subtest: vcs0
[185.845384] Dynamic subtest vcs0: SUCCESS (1.983s)
[185.857218] Starting dynamic subtest: vcs1
[187.892282] Dynamic subtest vcs1: SUCCESS (2.046s)
[187.904863] Starting dynamic subtest: vecs0
[189.877730] Dynamic subtest vecs0: SUCCESS (1.984s)
[189.890211] Starting dynamic subtest: ccs0
[191.860373] Dynamic subtest ccs0: SUCCESS (1.981s)
[191.863355] Subtest engine-engine-error: SUCCESS (11.954s)
[191.991200] [028/133] (829s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[192.140362] Starting subtest: cursorA-vs-flipA-toggle
[203.269506] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.129s)
[204.098848] [029/133] (817s left) gem_userptr_blits (readonly-pwrite-unsync)
[204.173662] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[204.302792] [030/133] (817s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[204.362486] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[204.364733] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[204.462059] [031/133] (817s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[204.553967] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[208.931905] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.378s)
[210.108597] [032/133] (811s left) dmabuf_sync_file (export-before-signal)
[210.228435] Starting subtest: export-before-signal
[210.230327] Subtest export-before-signal: SUCCESS (0.001s)
[210.285051] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[210.288298] [033/133] (811s left) gem_ctx_persistence (engines-persistence)
[210.337120] Starting subtest: engines-persistence
[210.339367] Starting dynamic subtest: rcs0
[211.160043] Dynamic subtest rcs0: SUCCESS (0.822s)
[211.172125] Starting dynamic subtest: bcs0
[211.921871] Dynamic subtest bcs0: SUCCESS (0.760s)
[211.933712] Starting dynamic subtest: vcs0
[212.668367] Dynamic subtest vcs0: SUCCESS (0.745s)
[212.678769] Starting dynamic subtest: vcs1
[213.471757] Dynamic subtest vcs1: SUCCESS (0.802s)
[213.483096] Starting dynamic subtest: vecs0
[214.204819] Dynamic subtest vecs0: SUCCESS (0.731s)
[214.215587] Starting dynamic subtest: ccs0
[214.972234] Dynamic subtest ccs0: SUCCESS (0.765s)
[214.974786] Subtest engines-persistence: SUCCESS (4.636s)
[215.103018] [034/133] (806s left) kms_dp_link_training (non-uhbr-sst)
[215.224073] Starting subtest: non-uhbr-sst
[215.233080] Subtest non-uhbr-sst: SKIP (0.000s)
[215.296607] [035/133] (806s left) kms_flip (dpms-vs-vblank-race)
[215.391125] Starting subtest: dpms-vs-vblank-race
[215.393500] Starting dynamic subtest: A-eDP1
[220.384973] Dynamic subtest A-eDP1: SUCCESS (4.992s)
[220.394710] Starting dynamic subtest: B-eDP1
[223.899911] Dynamic subtest B-eDP1: SUCCESS (3.513s)
[223.910777] Starting dynamic subtest: C-eDP1
[227.631580] Dynamic subtest C-eDP1: SUCCESS (3.728s)
[227.641386] Starting dynamic subtest: D-eDP1
[232.253160] Dynamic subtest D-eDP1: SUCCESS (4.620s)
[232.257951] Subtest dpms-vs-vblank-race: SUCCESS (16.866s)
[233.130358] [036/133] (788s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[233.195342] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[233.199736] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[233.377286] [037/133] (788s left) kms_invalid_mode (zero-hdisplay)
[233.476156] Starting subtest: zero-hdisplay
[233.478427] Starting dynamic subtest: pipe-A-eDP-1
[233.759891] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[233.769082] Starting dynamic subtest: pipe-B-eDP-1
[233.771207] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[233.779263] Starting dynamic subtest: pipe-C-eDP-1
[233.781470] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[233.789499] Starting dynamic subtest: pipe-D-eDP-1
[233.791420] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[233.793266] Subtest zero-hdisplay: SUCCESS (0.291s)
[234.566327] [038/133] (787s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[234.666351] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[234.668686] Starting dynamic subtest: pipe-A
[235.005802] Dynamic subtest pipe-A: SUCCESS (0.341s)
[235.015542] Starting dynamic subtest: pipe-B
[236.468493] Dynamic subtest pipe-B: SUCCESS (1.462s)
[236.481590] Starting dynamic subtest: pipe-C
[237.887345] Dynamic subtest pipe-C: SUCCESS (1.418s)
[237.896717] Starting dynamic subtest: pipe-D
[239.320542] Dynamic subtest pipe-D: SUCCESS (1.432s)
[239.322774] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.657s)
[240.483610] [039/133] (781s left) gem_workarounds (suspend-resume-context)
[240.560181] Starting subtest: suspend-resume-context
[256.979351] Subtest suspend-resume-context: SUCCESS (2.082s)
[257.123974] [040/133] (764s left) kms_3d (basic)
[257.192888] Starting subtest: basic
[259.227892] Subtest basic: SUCCESS (2.058s)
[259.706565] [041/133] (762s left) kms_vblank (crtc-id)
[259.802997] Starting subtest: crtc-id
[259.805178] Starting dynamic subtest: pipe-A-eDP-1
[260.255484] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[260.270493] Starting dynamic subtest: pipe-D-eDP-1
[261.342998] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.095s)
[261.345192] Subtest crtc-id: SUCCESS (1.555s)
[262.170594] [042/133] (759s left) gem_pwrite (basic-random)
[262.231680] Subtest basic-random: SKIP (0.000s)
[262.294383] [043/133] (759s left) i915_query (hwconfig_table)
[262.345305] Starting subtest: hwconfig_table
[262.643456] Subtest hwconfig_table: SUCCESS (0.001s)
[262.683597] [044/133] (759s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[262.778649] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[263.398421] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.626s)
[263.559963] [045/133] (758s left) kms_chamelium_edid (dp-mode-timings)
[263.947346] Subtest dp-mode-timings: SKIP (0.000s)
[264.803415] [046/133] (757s left) kms_plane_scaling (planes-upscale-20x20)
[264.888260] Starting subtest: planes-upscale-20x20
[264.890531] Starting dynamic subtest: pipe-A
[264.947755] Dynamic subtest pipe-A: SUCCESS (0.062s)
[264.957831] Starting dynamic subtest: pipe-B
[266.102664] Dynamic subtest pipe-B: SUCCESS (1.159s)
[266.113148] Starting dynamic subtest: pipe-C
[267.262587] Dynamic subtest pipe-C: SUCCESS (1.163s)
[267.272732] Starting dynamic subtest: pipe-D
[268.369309] Dynamic subtest pipe-D: SUCCESS (1.110s)
[268.371609] Subtest planes-upscale-20x20: SUCCESS (3.499s)
[269.583397] [047/133] (752s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[269.957637] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[270.764857] [048/133] (751s left) perf (enable-disable)
[270.922584] Starting subtest: enable-disable
[270.924764] Starting dynamic subtest: 0-rcs0
[273.185094] Dynamic subtest 0-rcs0: SUCCESS (2.266s)
[273.195835] Starting dynamic subtest: 1-vcs0
[277.658244] Dynamic subtest 1-vcs0: SUCCESS (4.480s)
[277.660729] Subtest enable-disable: SUCCESS (6.748s)
[277.708495] [049/133] (744s left) gem_lmem_swapping (random)
[277.747366] Subtest random: SKIP (0.000s)
[277.802212] [050/133] (744s left) kms_psr (fbc-pr-primary-mmap-cpu)
[277.908725] Starting subtest: fbc-pr-primary-mmap-cpu
[277.913241] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[277.981644] [051/133] (744s left) kms_draw_crc (draw-method-pwrite)
[278.084163] Starting subtest: draw-method-pwrite
[278.086052] Starting dynamic subtest: xrgb8888-untiled
[278.647590] Dynamic subtest xrgb8888-untiled: SUCCESS (0.567s)
[278.657622] Starting dynamic subtest: rgb565-untiled
[279.102704] Dynamic subtest rgb565-untiled: SUCCESS (0.455s)
[279.112487] Starting dynamic subtest: xrgb2101010-untiled
[279.668373] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.564s)
[279.678766] Starting dynamic subtest: xbgr16161616f-untiled
[280.467867] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.799s)
[280.477918] Starting dynamic subtest: xrgb8888-xtiled
[282.332453] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.864s)
[282.344779] Starting dynamic subtest: rgb565-xtiled
[283.464651] Dynamic subtest rgb565-xtiled: SUCCESS (1.132s)
[283.474947] Starting dynamic subtest: xrgb2101010-xtiled
[285.307550] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.844s)
[285.317809] Starting dynamic subtest: xbgr16161616f-xtiled
[288.660086] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.355s)
[288.670406] Starting dynamic subtest: xrgb8888-4tiled
[289.791989] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.131s)
[289.802260] Starting dynamic subtest: rgb565-4tiled
[290.391096] Dynamic subtest rgb565-4tiled: SUCCESS (0.599s)
[290.400975] Starting dynamic subtest: xrgb2101010-4tiled
[291.522949] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.132s)
[291.532798] Starting dynamic subtest: xbgr16161616f-4tiled
[294.586575] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.066s)
[294.589133] Subtest draw-method-pwrite: SUCCESS (16.523s)
[294.693904] [052/133] (727s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[294.785515] Starting subtest: fbc-psr2-sprite-plane-onoff
[294.787903] Starting dynamic subtest: eDP-1
[294.792475] Dynamic subtest eDP-1: SKIP (0.000s)
[294.797025] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[294.865390] [053/133] (727s left) gem_render_copy_redux (interruptible)
[294.924454] Starting subtest: interruptible
[295.204476] Subtest interruptible: SUCCESS (0.283s)
[295.316517] [054/133] (727s left) gem_wait (await)
[295.383231] Starting subtest: await
[295.385541] Starting dynamic subtest: all-engines
[296.493129] Dynamic subtest all-engines: SUCCESS (1.110s)
[296.511679] Starting dynamic subtest: rcs0
[297.553494] Dynamic subtest rcs0: SUCCESS (1.059s)
[297.571085] Starting dynamic subtest: bcs0
[298.613689] Dynamic subtest bcs0: SUCCESS (1.059s)
[298.631337] Starting dynamic subtest: vcs0
[299.668103] Dynamic subtest vcs0: SUCCESS (1.054s)
[299.684626] Starting dynamic subtest: vcs1
[300.722081] Dynamic subtest vcs1: SUCCESS (1.053s)
[300.739069] Starting dynamic subtest: vecs0
[301.775404] Dynamic subtest vecs0: SUCCESS (1.052s)
[301.792096] Starting dynamic subtest: ccs0
[302.831332] Dynamic subtest ccs0: SUCCESS (1.054s)
[302.833829] Subtest await: SUCCESS (7.456s)
[303.032882] [055/133] (719s left) gem_spin_batch (spin-all-new)
[303.097610] Starting subtest: spin-all-new
[303.117921] Subtest spin-all-new: SUCCESS (0.020s)
[304.257242] [056/133] (718s left) gem_exec_capture (capture)
[304.308419] Starting subtest: capture
[304.310409] Starting dynamic subtest: rcs0-smem
[305.141107] Dynamic subtest rcs0-smem: SUCCESS (0.833s)
[305.152765] Starting dynamic subtest: bcs0-smem
[305.959926] Dynamic subtest bcs0-smem: SUCCESS (0.817s)
[305.971810] Starting dynamic subtest: vcs0-smem
[306.773502] Dynamic subtest vcs0-smem: SUCCESS (0.814s)
[306.784069] Starting dynamic subtest: vcs1-smem
[308.082502] Dynamic subtest vcs1-smem: SUCCESS (1.310s)
[308.093581] Starting dynamic subtest: vecs0-smem
[308.973154] Dynamic subtest vecs0-smem: FAIL (0.830s)
[308.985693] Starting dynamic subtest: ccs0-smem
[310.230505] Dynamic subtest ccs0-smem: SUCCESS (1.319s)
[310.232735] Subtest capture: FAIL (5.928s)
[310.356349] [057/133] (712s left) gem_gtt_cpu_tlb
[310.488549] [058/133] (712s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[310.595333] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[310.599665] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[310.699972] [059/133] (712s left) kms_panel_fitting (atomic-fastset)
[310.791571] Starting subtest: atomic-fastset
[310.793894] Starting dynamic subtest: pipe-A-eDP-1
[310.932706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.143s)
[310.945078] Starting dynamic subtest: pipe-B-eDP-1
[312.162766] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.230s)
[312.173677] Starting dynamic subtest: pipe-C-eDP-1
[313.318603] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.156s)
[313.328264] Starting dynamic subtest: pipe-D-eDP-1
[314.526091] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.206s)
[314.528429] Subtest atomic-fastset: SUCCESS (3.739s)
[315.729629] [060/133] (707s left) syncobj_timeline (transfer-timeline-point)
[315.802716] Starting subtest: transfer-timeline-point
[315.805117] Subtest transfer-timeline-point: SUCCESS (0.000s)
[315.938717] [061/133] (707s left) kms_vblank (wait-forked)
[316.026317] Starting subtest: wait-forked
[316.028281] Starting dynamic subtest: pipe-A-eDP-1
[317.863072] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.837s)
[317.878002] Starting dynamic subtest: pipe-D-eDP-1
[320.406130] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[320.408329] Subtest wait-forked: SUCCESS (4.382s)
[321.225364] [062/133] (701s left) gem_mmap_offset (open-flood)
[321.276541] Starting subtest: open-flood
[342.749767] Subtest open-flood: SUCCESS (21.489s)
[342.861374] [063/133] (680s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[342.949069] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[342.950982] Starting dynamic subtest: pipe-A-eDP-1
[342.998738] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[343.009318] Starting dynamic subtest: pipe-B-eDP-1
[343.057499] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[343.067827] Starting dynamic subtest: pipe-C-eDP-1
[343.114850] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[343.124968] Starting dynamic subtest: pipe-D-eDP-1
[343.172336] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[343.175867] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[345.220548] [064/133] (677s left) kms_psr (fbc-psr2-sprite-blt)
[345.317031] Starting subtest: fbc-psr2-sprite-blt
[345.319329] Starting dynamic subtest: eDP-1
[345.323677] Dynamic subtest eDP-1: SKIP (0.000s)
[345.327984] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[345.401274] [065/133] (677s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[345.497169] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[345.499399] Starting dynamic subtest: pipe-A-eDP-1
[345.521439] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[345.532562] Starting dynamic subtest: pipe-B-eDP-1
[345.553264] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[345.562786] Starting dynamic subtest: pipe-C-eDP-1
[345.583315] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[345.592428] Starting dynamic subtest: pipe-D-eDP-1
[345.613140] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[345.617120] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[347.710386] [066/133] (675s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[347.798335] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[347.802609] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[347.888937] [067/133] (675s left) syncobj_timeline (signal)
[347.942806] Starting subtest: signal
[347.945156] Subtest signal: SUCCESS (0.000s)
[348.038403] [068/133] (675s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[348.136436] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[348.138802] Starting dynamic subtest: A-eDP1
[351.739037] Dynamic subtest A-eDP1: SUCCESS (3.601s)
[351.748888] Starting dynamic subtest: B-eDP1
[355.037436] Dynamic subtest B-eDP1: SUCCESS (3.290s)
[355.047239] Starting dynamic subtest: C-eDP1
[358.344480] Dynamic subtest C-eDP1: SUCCESS (3.297s)
[358.355497] Starting dynamic subtest: D-eDP1
[361.671797] Dynamic subtest D-eDP1: SUCCESS (3.320s)
[361.674002] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.516s)
[362.533428] [069/133] (660s left) gem_ctx_engines (execute-allforone)
[362.612445] Starting subtest: execute-allforone
[362.629230] Subtest execute-allforone: SUCCESS (0.017s)
[364.338341] [070/133] (659s left) gem_exec_schedule (u-semaphore-resolve)
[364.428152] Starting subtest: u-semaphore-resolve
[364.462108] Subtest u-semaphore-resolve: SUCCESS (0.041s)
[364.802445] [071/133] (658s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[364.901801] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[365.003484] [072/133] (658s left) gem_exec_whisper (basic-contexts)
[365.061831] Starting subtest: basic-contexts
[372.213520] Subtest basic-contexts: SUCCESS (7.142s)
[372.399591] [073/133] (651s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[372.506071] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[372.508384] Starting dynamic subtest: pipe-A-default-mode
[373.666101] Dynamic subtest pipe-A-default-mode: SKIP (1.158s)
[373.670475] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.159s)
[373.841900] [074/133] (649s left) syncobj_basic (create-signaled)
[373.908804] Starting subtest: create-signaled
[373.910968] Subtest create-signaled: SUCCESS (0.000s)
[374.027254] [075/133] (649s left) fbdev (nullptr)
[374.089059] Starting subtest: nullptr
[374.091471] Subtest nullptr: SUCCESS (0.000s)
[374.149771] [076/133] (649s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[374.727744] Starting subtest: syncobj-backward-timeline-chain-engines
[374.838380] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.111s)
[376.294156] [077/133] (647s left) gem_exec_params (mmapped)
[376.344375] Starting subtest: mmapped
[376.349393] Subtest mmapped: SUCCESS (0.004s)
[376.464574] [078/133] (647s left) syncobj_wait (wait-any-snapshot)
[376.511193] Starting subtest: wait-any-snapshot
[376.592996] Subtest wait-any-snapshot: SUCCESS (0.083s)
[376.687031] [079/133] (647s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[376.785516] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[376.789613] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[376.884965] [080/133] (646s left) gem_exec_schedule (preempt-hang)
[377.053840] Starting subtest: preempt-hang
[377.056370] Starting dynamic subtest: rcs0
[377.291532] Dynamic subtest rcs0: SUCCESS (0.235s)
[377.303181] Starting dynamic subtest: bcs0
[377.516787] Dynamic subtest bcs0: SUCCESS (0.225s)
[377.527744] Starting dynamic subtest: vcs0
[377.742726] Dynamic subtest vcs0: SUCCESS (0.224s)
[377.753679] Starting dynamic subtest: vcs1
[377.968456] Dynamic subtest vcs1: SUCCESS (0.226s)
[377.980892] Starting dynamic subtest: vecs0
[378.201206] Dynamic subtest vecs0: SUCCESS (0.229s)
[378.212941] Starting dynamic subtest: ccs0
[378.428340] Dynamic subtest ccs0: SUCCESS (0.226s)
[378.430947] Subtest preempt-hang: SUCCESS (1.372s)
[379.326414] [081/133] (644s left) gem_exec_fence (basic-wait-all)
[379.389185] Starting subtest: basic-wait-all
[379.484780] Subtest basic-wait-all: SUCCESS (0.095s)
[381.342434] [082/133] (642s left) gem_exec_flush (basic-uc-set-default)
[381.408367] Starting subtest: basic-uc-set-default
[386.876760] Subtest basic-uc-set-default: SUCCESS (5.466s)
[387.071164] [083/133] (636s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[387.165808] Starting subtest: compare-crc-sanitycheck-nv12
[387.167775] Starting dynamic subtest: pipe-A-eDP-1
[387.872875] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[387.883955] Starting dynamic subtest: pipe-B-eDP-1
[389.199348] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.324s)
[389.209237] Starting dynamic subtest: pipe-C-eDP-1
[390.463806] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.261s)
[390.475352] Starting dynamic subtest: pipe-D-eDP-1
[391.664710] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.200s)
[391.667101] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.498s)
[392.544557] [084/133] (631s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[392.656458] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[392.658821] Starting dynamic subtest: pipe-A-eDP-1
[392.730316] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[392.741210] Starting dynamic subtest: pipe-B-eDP-1
[393.886312] Dynamic subtest pipe-B-eDP-1: SKIP (1.153s)
[393.890533] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.234s)
[395.146295] [085/133] (628s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[395.239785] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[402.486832] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.250s)
[402.612544] [086/133] (621s left) kms_addfb_basic (bad-pitch-256)
[402.680438] Starting subtest: bad-pitch-256
[402.682334] Subtest bad-pitch-256: SUCCESS (0.000s)
[402.816563] [087/133] (621s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[402.924813] Starting subtest: y-tiled-16bpp-rotate-270
[402.929335] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[403.017520] [088/133] (621s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[403.121574] Starting subtest: psr-rgb565-draw-mmap-wc
[409.906184] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.787s)
[410.032401] [089/133] (614s left) gem_exec_reloc (basic-cpu-wc)
[410.096323] Subtest basic-cpu-wc: SKIP (0.000s)
[410.177500] [090/133] (614s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[410.235638] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[410.340009] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.103s)
[410.419222] [091/133] (613s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[410.543371] Starting subtest: cursorA-vs-flipA-legacy
[421.680052] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.136s)
[422.556005] [092/133] (601s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[422.654067] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[422.656371] Starting dynamic subtest: pipe-A-eDP-1
[422.679076] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[422.690945] Starting dynamic subtest: pipe-B-eDP-1
[422.712319] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[422.721526] Starting dynamic subtest: pipe-C-eDP-1
[422.742846] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[422.751771] Starting dynamic subtest: pipe-D-eDP-1
[422.774879] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[422.779451] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[424.952077] [093/133] (599s left) kms_plane (pixel-format-4-tiled-modifier)
[425.049191] Starting subtest: pixel-format-4-tiled-modifier
[425.075910] Starting dynamic subtest: pipe-A-plane-0
[425.741936] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[425.751819] Starting dynamic subtest: pipe-A-plane-3
[426.186387] Dynamic subtest pipe-A-plane-3: SUCCESS (0.444s)
[426.197866] Starting dynamic subtest: pipe-A-plane-5
[426.201730] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[427.308095] Starting dynamic subtest: pipe-B-plane-0
[427.932692] Dynamic subtest pipe-B-plane-0: SUCCESS (0.633s)
[427.942761] Starting dynamic subtest: pipe-B-plane-3
[428.365750] Dynamic subtest pipe-B-plane-3: SUCCESS (0.432s)
[428.375490] Starting dynamic subtest: pipe-B-plane-5
[428.379002] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[428.693325] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.643s)
[429.562378] [094/133] (594s left) gem_fence_thrash (bo-write-verify-threaded-x)
[429.600875] Starting subtest: bo-write-verify-threaded-x
[429.625475] Subtest bo-write-verify-threaded-x: SKIP (0.022s)
[429.697798] [095/133] (594s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[429.794165] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[429.798122] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[429.888836] [096/133] (594s left) kms_plane_lowres (tiling-none)
[429.992894] Starting subtest: tiling-none
[429.995194] Starting dynamic subtest: pipe-A-eDP-1
[429.999754] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[430.298643] Starting dynamic subtest: pipe-B-eDP-1
[430.303086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[430.312757] Starting dynamic subtest: pipe-C-eDP-1
[430.317750] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[430.329248] Starting dynamic subtest: pipe-D-eDP-1
[430.333144] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[430.337132] Subtest tiling-none: SKIP (0.303s)
[431.141928] [097/133] (593s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[433.366836] Starting subtest: dpms-mode-unset-non-lpsp
[434.527657] Subtest dpms-mode-unset-non-lpsp: SKIP (1.159s)
[434.931142] [098/133] (589s left) syncobj_wait (reset-unsignaled)
[434.989323] Starting subtest: reset-unsignaled
[434.991531] Subtest reset-unsignaled: SUCCESS (0.000s)
[435.101617] [099/133] (589s left) gem_exec_reloc (basic-gtt-active)
[435.159084] Subtest basic-gtt-active: SKIP (0.000s)
[435.226097] [100/133] (589s left) i915_drm_fdinfo (busy)
[435.269763] Starting subtest: busy
[435.271971] Starting dynamic subtest: rcs0
[435.296576] Dynamic subtest rcs0: SKIP (0.025s)
[435.307089] Starting dynamic subtest: bcs0
[435.311148] Dynamic subtest bcs0: SKIP (0.000s)
[435.320620] Starting dynamic subtest: vcs0
[435.325084] Dynamic subtest vcs0: SKIP (0.000s)
[435.334289] Starting dynamic subtest: vcs1
[435.338284] Dynamic subtest vcs1: SKIP (0.000s)
[435.347407] Starting dynamic subtest: vecs0
[435.351509] Dynamic subtest vecs0: SKIP (0.000s)
[435.360751] Starting dynamic subtest: ccs0
[435.364775] Dynamic subtest ccs0: SKIP (0.000s)
[435.368808] Subtest busy: SKIP (0.030s)
[435.406450] [101/133] (589s left) prime_mmap (test_userptr)
[435.473505] Starting subtest: test_userptr
[435.475837] Starting dynamic subtest: test_userptr-smem
[435.478098] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[435.480350] Subtest test_userptr: SUCCESS (0.001s)
[435.612671] [102/133] (589s left) api_intel_allocator (standalone)
[435.667562] Starting subtest: standalone
[435.695938] Subtest standalone: SUCCESS (0.029s)
[435.819149] [103/133] (588s left) gem_ctx_persistence (legacy-engines-cleanup)
[435.870736] Starting subtest: legacy-engines-cleanup
[435.872641] Starting dynamic subtest: render
[435.911108] Dynamic subtest render: SUCCESS (0.040s)
[435.920274] Starting dynamic subtest: bsd1
[435.975248] Dynamic subtest bsd1: SUCCESS (0.063s)
[435.988535] Starting dynamic subtest: bsd2
[436.029583] Dynamic subtest bsd2: SUCCESS (0.051s)
[436.043412] Starting dynamic subtest: blt
[436.054966] Dynamic subtest blt: SUCCESS (0.023s)
[436.063991] Starting dynamic subtest: vebox
[436.120281] Dynamic subtest vebox: SUCCESS (0.064s)
[436.122333] Subtest legacy-engines-cleanup: SUCCESS (0.251s)
[436.204966] [104/133] (588s left) kms_cursor_crc (cursor-sliding-256x85)
[436.446965] Starting subtest: cursor-sliding-256x85
[436.495059] Subtest cursor-sliding-256x85: SKIP (0.043s)
[436.680768] [105/133] (588s left) kms_content_protection (uevent)
[436.808331] Starting subtest: uevent
[436.812725] Subtest uevent: SKIP (0.000s)
[436.921655] [106/133] (587s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[437.015313] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[446.328610] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.313s)
[446.448287] [107/133] (578s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[446.545466] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[446.549825] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[446.640572] [108/133] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[446.730584] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[446.734642] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[446.817317] [109/133] (578s left) kms_flip (basic-flip-vs-modeset)
[446.905653] Starting subtest: basic-flip-vs-modeset
[446.907633] Starting dynamic subtest: A-eDP1
[449.464242] Dynamic subtest A-eDP1: SUCCESS (2.559s)
[449.474062] Starting dynamic subtest: B-eDP1
[451.682251] Dynamic subtest B-eDP1: SUCCESS (2.217s)
[451.693097] Starting dynamic subtest: C-eDP1
[453.862505] Dynamic subtest C-eDP1: SUCCESS (2.180s)
[453.873782] Starting dynamic subtest: D-eDP1
[456.079100] Dynamic subtest D-eDP1: SUCCESS (2.214s)
[456.081362] Subtest basic-flip-vs-modeset: SUCCESS (9.177s)
[456.948838] [110/133] (568s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[457.048307] Starting subtest: psr2-cursor-plane-update-sf
[457.050398] Starting dynamic subtest: pipe-A-eDP-1
[457.609741] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[457.620871] Starting dynamic subtest: pipe-B-eDP-1
[458.908334] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.298s)
[458.910211] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.864s)
[459.775403] [111/133] (565s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[459.869513] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[459.873596] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[459.970509] [112/133] (565s left) kms_lease (lease-uevent)
[460.068323] Starting subtest: lease-uevent
[461.077959] Subtest lease-uevent: SUCCESS (1.010s)
[461.199096] [113/133] (564s left) kms_addfb_basic (small-bo)
[461.293830] Starting subtest: small-bo
[461.296027] Subtest small-bo: SUCCESS (0.000s)
[461.393905] [114/133] (563s left) kms_pm_rpm (modeset-non-lpsp)
[463.420555] Starting subtest: modeset-non-lpsp
[464.567468] Subtest modeset-non-lpsp: SKIP (1.144s)
[464.971497] [115/133] (560s left) gem_render_copy (yf-tiled)
[465.039522] Starting subtest: yf-tiled
[465.043877] Subtest yf-tiled: SKIP (0.000s)
[465.281815] [116/133] (560s left) kms_chamelium_color (ctm-0-50)
[465.392214] Subtest ctm-0-50: SKIP (0.000s)
[465.438861] [117/133] (559s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[465.553448] Starting subtest: basic-flip-after-cursor-legacy
[466.467447] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.914s)
[467.333840] [118/133] (558s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[467.422219] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[467.426174] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[467.521132] [119/133] (557s left) kms_cursor_crc (cursor-offscreen-64x64)
[467.692907] Starting subtest: cursor-offscreen-64x64
[467.698935] Starting dynamic subtest: pipe-A-eDP-1
[469.627505] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.931s)
[469.658629] Starting dynamic subtest: pipe-D-eDP-1
[472.207691] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.556s)
[472.210000] Subtest cursor-offscreen-64x64: SUCCESS (4.516s)
[473.039882] [120/133] (552s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[473.133207] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[473.137157] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[473.244385] [121/133] (552s left) gen9_exec_parse (allowed-all)
[473.299792] Subtest allowed-all: SKIP (0.000s)
[473.368182] [122/133] (552s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[473.419077] Starting subtest: single-wait-all-for-submit-unsubmitted
[473.523770] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[473.601397] [123/133] (552s left) perf (gen12-mi-rpc)
[473.701728] Starting subtest: gen12-mi-rpc
[473.704126] Starting dynamic subtest: rcs0
[473.721145] Dynamic subtest rcs0: SUCCESS (0.018s)
[473.723465] Subtest gen12-mi-rpc: SUCCESS (0.019s)
[473.767464] [124/133] (551s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[473.884164] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[473.886499] Starting dynamic subtest: pipe-A-eDP-1
[473.909343] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[473.921660] Starting dynamic subtest: pipe-B-eDP-1
[473.939604] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[473.948864] Starting dynamic subtest: pipe-C-eDP-1
[473.969250] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[473.980133] Starting dynamic subtest: pipe-D-eDP-1
[474.001222] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[474.005592] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[476.145488] [125/133] (549s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[476.262916] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[476.267353] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[476.409966] [126/133] (549s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[476.504605] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[476.509086] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[476.604512] [127/133] (549s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[476.706402] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[476.710470] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[476.791618] [128/133] (549s left) api_intel_bb (render-ccs)
[476.852555] Starting subtest: render-ccs
[477.205883] Subtest render-ccs: SUCCESS (0.353s)
[477.293928] [129/133] (548s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[477.381306] Starting subtest: plane-all-modeset-transition-internal-panels
[477.383451] Starting dynamic subtest: pipe-A-eDP-1
[478.747270] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.365s)
[479.027843] Starting dynamic subtest: pipe-B-eDP-1
[480.050361] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.029s)
[480.324819] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.945s)
[481.140201] [130/133] (544s left) syncobj_wait (invalid-signal-one-illegal-handle)
[481.190198] Starting subtest: invalid-signal-one-illegal-handle
[481.192104] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[481.293054] [131/133] (544s left) kms_sequence (queue-idle)
[481.382120] Starting subtest: queue-idle
[481.384418] Starting dynamic subtest: pipe-A-eDP-1
[483.200572] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.820s)
[483.214089] Starting dynamic subtest: pipe-B-eDP-1
[485.688121] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.484s)
[485.699080] Starting dynamic subtest: pipe-C-eDP-1
[488.170102] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.480s)
[488.188829] Starting dynamic subtest: pipe-D-eDP-1
[490.652611] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.479s)
[490.655086] Subtest queue-idle: SUCCESS (9.272s)
[491.535849] [132/133] (534s left) perf_pmu (invalid-open)
[491.577691] Starting subtest: invalid-open
[491.580066] Subtest invalid-open: SUCCESS (0.000s)
[491.677861] [133/133] (534s left) kms_color (ctm-red-to-blue)
[491.769261] Starting subtest: ctm-red-to-blue
[491.771116] Starting dynamic subtest: pipe-A-eDP-1
[492.475587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[492.486324] Starting dynamic subtest: pipe-B-eDP-1
[493.914126] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.438s)
[493.927801] Starting dynamic subtest: pipe-C-eDP-1
[495.268733] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.351s)
[495.278961] Starting dynamic subtest: pipe-D-eDP-1
[496.666528] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.399s)
[496.668961] Subtest ctm-red-to-blue: SUCCESS (4.900s)
[497.539569] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: kms_atomic_transition
results: parsing output: 3/ for test: i915_pm_rps
results: parsing output: 4/ for test: kms_async_flips
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_cursor_legacy
results: parsing output: 10/ for test: gem_exec_alignment
results: parsing output: 11/ for test: gem_exec_balancer
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: gem_exec_whisper
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: i915_hangman
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: dmabuf_sync_file
results: parsing output: 32/ for test: gem_ctx_persistence
results: parsing output: 33/ for test: kms_dp_link_training
results: parsing output: 34/ for test: kms_flip
results: parsing output: 35/ for test: gem_render_copy
results: parsing output: 36/ for test: kms_invalid_mode
results: parsing output: 37/ for test: kms_plane_scaling
results: parsing output: 38/ for test: gem_workarounds
results: parsing output: 39/ for test: kms_3d
results: parsing output: 40/ for test: kms_vblank
results: parsing output: 41/ for test: gem_pwrite
results: parsing output: 42/ for test: i915_query
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: kms_chamelium_edid
results: parsing output: 45/ for test: kms_plane_scaling
results: parsing output: 46/ for test: kms_chamelium_hpd
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: gem_lmem_swapping
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_draw_crc
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: gem_render_copy_redux
results: parsing output: 53/ for test: gem_wait
results: parsing output: 54/ for test: gem_spin_batch
results: parsing output: 55/ for test: gem_exec_capture
results: parsing output: 56/ for test: gem_gtt_cpu_tlb
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_panel_fitting
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_vblank
results: parsing output: 61/ for test: gem_mmap_offset
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: gem_ctx_engines
results: parsing output: 69/ for test: gem_exec_schedule
results: parsing output: 70/ for test: kms_addfb_basic
results: parsing output: 71/ for test: gem_exec_whisper
results: parsing output: 72/ for test: kms_flip_scaled_crc
results: parsing output: 73/ for test: syncobj_basic
results: parsing output: 74/ for test: fbdev
results: parsing output: 75/ for test: gem_exec_fence
results: parsing output: 76/ for test: gem_exec_params
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_schedule
results: parsing output: 80/ for test: gem_exec_fence
results: parsing output: 81/ for test: gem_exec_flush
results: parsing output: 82/ for test: kms_pipe_crc_basic
results: parsing output: 83/ for test: kms_psr2_sf
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: syncobj_timeline
results: parsing output: 90/ for test: kms_cursor_legacy
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_plane
results: parsing output: 93/ for test: gem_fence_thrash
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_plane_lowres
results: parsing output: 96/ for test: kms_pm_rpm
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: i915_drm_fdinfo
results: parsing output: 100/ for test: prime_mmap
results: parsing output: 101/ for test: api_intel_allocator
results: parsing output: 102/ for test: gem_ctx_persistence
results: parsing output: 103/ for test: kms_cursor_crc
results: parsing output: 104/ for test: kms_content_protection
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: kms_psr2_sf
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_lease
results: parsing output: 112/ for test: kms_addfb_basic
results: parsing output: 113/ for test: kms_pm_rpm
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: kms_chamelium_color
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_cursor_crc
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gen9_exec_parse
results: parsing output: 121/ for test: syncobj_wait
results: parsing output: 122/ for test: perf
results: parsing output: 123/ for test: kms_ccs
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: api_intel_bb
results: parsing output: 128/ for test: kms_atomic_transition
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: kms_sequence
results: parsing output: 131/ for test: perf_pmu
results: parsing output: 132/ for test: kms_color
Done.