[72.634225] Initializing watchdogs
[72.634314]   /dev/watchdog0
[72.670754] [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]
[72.683739] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[72.689341] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[72.692414] [001/133] (960s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[72.783300] Starting subtest: invalid-multi-wait-unsubmitted
[72.784448] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[72.984395] [002/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[73.113248] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[73.115183] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[73.347521] [003/133] (959s left) kms_ccs (bad-aux-stride-y-tiled-gen12-mc-ccs)
[73.448593] Starting subtest: bad-aux-stride-y-tiled-gen12-mc-ccs
[73.449510] Starting dynamic subtest: pipe-A-eDP-1
[73.458817] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[73.465442] Starting dynamic subtest: pipe-B-eDP-1
[73.473889] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[73.480378] Starting dynamic subtest: pipe-C-eDP-1
[73.488840] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[73.494769] Starting dynamic subtest: pipe-D-eDP-1
[73.502646] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[73.504219] Subtest bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[75.772085] [004/133] (957s left) gem_mmap_gtt (basic-small-copy-odd)
[75.822258] Subtest basic-small-copy-odd: SKIP (0.000s)
[75.867754] [005/133] (957s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[75.951478] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[75.953445] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[76.015299] [006/133] (957s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[76.111343] Starting subtest: yf-tiled-32bpp-rotate-180
[76.113033] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[76.182946] [007/133] (957s left) syncobj_timeline (single-wait-available-signaled)
[76.229389] Starting subtest: single-wait-available-signaled
[76.230512] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[76.317399] [008/133] (957s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[76.423067] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[86.050081] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.634s)
[86.884199] [009/133] (946s left) gem_softpin (softpin)
[86.949018] Starting subtest: softpin
[119.240717] Subtest softpin: SUCCESS (32.270s)
[119.334805] [010/133] (914s left) gem_mmap_wc (read)
[119.387274] Subtest read: SKIP (0.000s)
[119.440259] [011/133] (914s left) gem_flink_basic (basic)
[119.491067] Starting subtest: basic
[119.491866] Subtest basic: SUCCESS (0.000s)
[119.574495] [012/133] (914s left) gem_exec_params (invalid-bsd1-flag-on-render)
[119.633089] Starting subtest: invalid-bsd1-flag-on-render
[119.634039] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[119.726335] [013/133] (913s left) gem_mmap_gtt (cpuset-big-copy)
[119.766059] Subtest cpuset-big-copy: SKIP (0.000s)
[119.825164] [014/133] (913s left) perf_pmu (busy-idle-check-all)
[119.857695] Starting subtest: busy-idle-check-all
[119.858689] Starting dynamic subtest: rcs0
[120.514898] Dynamic subtest rcs0: SUCCESS (0.657s)
[120.527803] Starting dynamic subtest: bcs0
[121.170873] Dynamic subtest bcs0: SUCCESS (0.655s)
[121.181233] Starting dynamic subtest: vcs0
[122.343425] Dynamic subtest vcs0: SUCCESS (1.162s)
[122.352384] Starting dynamic subtest: vcs1
[124.326483] Dynamic subtest vcs1: SUCCESS (1.982s)
[124.334325] Starting dynamic subtest: vecs0
[126.312545] Dynamic subtest vecs0: SUCCESS (1.984s)
[126.322372] Starting dynamic subtest: ccs0
[126.959840] Dynamic subtest ccs0: SUCCESS (0.656s)
[126.960994] Subtest busy-idle-check-all: SUCCESS (7.102s)
[127.080130] [015/133] (906s left) gem_render_copy_redux (flink)
[127.140235] Starting subtest: flink
[128.223840] Subtest flink: SUCCESS (1.084s)
[128.335961] [016/133] (905s left) kms_psr (fbc-psr-primary-blt)
[128.429937] Starting subtest: fbc-psr-primary-blt
[128.430938] Starting dynamic subtest: eDP-1
[128.432693] Dynamic subtest eDP-1: SKIP (0.000s)
[128.434317] Subtest fbc-psr-primary-blt: SKIP (0.000s)
[128.510473] [017/133] (905s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[128.616820] Starting subtest: fbc-rgb565-draw-mmap-cpu
[132.633015] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.016s)
[133.797047] [018/133] (899s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[133.882976] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[133.884887] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[133.974852] [019/133] (899s left) gem_ctx_param (invalid-size-get)
[134.022381] Starting subtest: invalid-size-get
[134.024326] Subtest invalid-size-get: SUCCESS (0.002s)
[134.114073] [020/133] (899s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[134.199618] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[141.047603] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.849s)
[141.185756] [021/133] (892s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[141.315417] Starting subtest: flip-vs-cursor-atomic
[148.874675] Subtest flip-vs-cursor-atomic: SUCCESS (7.558s)
[149.737410] [022/133] (884s left) kms_vblank (wait-busy-hang)
[149.828358] Starting subtest: wait-busy-hang
[149.829325] Starting dynamic subtest: pipe-A-eDP-1
[176.155845] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.326s)
[176.164410] Starting dynamic subtest: pipe-D-eDP-1
[191.148672] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.988s)
[191.149708] Subtest wait-busy-hang: SUCCESS (41.318s)
[192.008193] [023/133] (841s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[192.108830] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[192.110780] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[192.193035] [024/133] (841s left) i915_query (test-query-geometry-subslices)
[192.243063] Starting subtest: test-query-geometry-subslices
[192.244082] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[192.351925] [025/133] (841s left) kms_chamelium_frames (hdmi-frame-dump)
[192.737845] Subtest hdmi-frame-dump: SKIP (0.000s)
[193.519101] [026/133] (840s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[193.638147] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[195.035885] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.397s)
[195.833754] [027/133] (838s left) kms_psr (psr-dpms)
[195.934683] Starting subtest: psr-dpms
[195.935823] Starting dynamic subtest: eDP-1
[198.880202] Dynamic subtest eDP-1: SUCCESS (2.945s)
[198.881273] Subtest psr-dpms: SUCCESS (2.946s)
[199.736339] [028/133] (834s left) perf (buffer-fill)
[199.859895] Starting subtest: buffer-fill
[199.860853] Starting dynamic subtest: 0-rcs0
[202.122506] Dynamic subtest 0-rcs0: SUCCESS (2.254s)
[202.134686] Starting dynamic subtest: 1-vcs0
[206.567440] Dynamic subtest 1-vcs0: SUCCESS (4.443s)
[206.568558] Subtest buffer-fill: SUCCESS (6.698s)
[206.600899] [029/133] (827s left) kms_feature_discovery (display)
[206.693295] Starting subtest: display
[206.694235] Subtest display: SUCCESS (0.000s)
[206.759090] [030/133] (827s left) syncobj_wait (invalid-single-wait-unsubmitted)
[206.818539] Starting subtest: invalid-single-wait-unsubmitted
[206.819688] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[206.918984] [031/133] (827s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[206.965681] Starting subtest: multi-wait-for-submit-submitted-signaled
[206.966666] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[207.064938] [032/133] (827s left) gem_exec_reloc (basic-concurrent0)
[207.110979] Subtest basic-concurrent0: SKIP (0.000s)
[207.168282] [033/133] (827s left) core_getversion (all-cards)
[207.199769] Starting subtest: all-cards
[207.207532] Subtest all-cards: SUCCESS (0.009s)
[207.245198] [034/133] (827s left) gem_request_retire (retire-vma-not-inactive)
[207.293990] Starting subtest: retire-vma-not-inactive
[207.352091] Subtest retire-vma-not-inactive: SUCCESS (0.057s)
[207.473980] [035/133] (826s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[207.554167] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[207.556051] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[207.651987] [036/133] (826s left) gem_exec_schedule (timeslicing)
[207.702859] Starting subtest: timeslicing
[207.703718] Starting dynamic subtest: rcs0
[207.725351] Dynamic subtest rcs0: SUCCESS (0.023s)
[207.734914] Starting dynamic subtest: bcs0
[207.745902] Dynamic subtest bcs0: SUCCESS (0.020s)
[207.753423] Starting dynamic subtest: vcs0
[207.766459] Dynamic subtest vcs0: SUCCESS (0.020s)
[207.773872] Starting dynamic subtest: vcs1
[207.787035] Dynamic subtest vcs1: SUCCESS (0.020s)
[207.794309] Starting dynamic subtest: vecs0
[207.806530] Dynamic subtest vecs0: SUCCESS (0.019s)
[207.813787] Starting dynamic subtest: ccs0
[207.826239] Dynamic subtest ccs0: SUCCESS (0.019s)
[207.827193] Subtest timeslicing: SUCCESS (0.124s)
[209.344497] [037/133] (825s left) kms_psr (pr-sprite-render)
[209.438544] Starting subtest: pr-sprite-render
[209.440605] Subtest pr-sprite-render: SKIP (0.000s)
[209.504048] [038/133] (824s left) kms_frontbuffer_tracking (basic)
[209.598264] Starting subtest: basic
[213.054873] Subtest basic: SUCCESS (3.458s)
[213.161442] [039/133] (821s left) prime_vgem (sync)
[213.306554] Starting subtest: sync
[213.309812] Starting dynamic subtest: rcs0
[213.345902] Dynamic subtest rcs0: SUCCESS (0.047s)
[213.353712] Starting dynamic subtest: bcs0
[213.391329] Dynamic subtest bcs0: SUCCESS (0.045s)
[213.398861] Starting dynamic subtest: vcs0
[213.437172] Dynamic subtest vcs0: SUCCESS (0.045s)
[213.444470] Starting dynamic subtest: vcs1
[213.483977] Dynamic subtest vcs1: SUCCESS (0.046s)
[213.491029] Starting dynamic subtest: vecs0
[213.529033] Dynamic subtest vecs0: SUCCESS (0.045s)
[213.536170] Starting dynamic subtest: ccs0
[213.576602] Dynamic subtest ccs0: SUCCESS (0.047s)
[213.577781] Subtest sync: SUCCESS (0.278s)
[213.712021] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[213.713802] [040/133] (820s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[213.838297] Starting subtest: cursorA-vs-flipA-atomic
[224.964340] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.125s)
[225.751716] [041/133] (808s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[225.837473] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[233.063604] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.227s)
[233.183242] [042/133] (801s left) prime_vgem (basic-gtt)
[233.240416] Starting subtest: basic-gtt
[233.242532] Subtest basic-gtt: SKIP (0.000s)
[233.340458] [043/133] (801s left) gem_ctx_persistence (hostile)
[233.389223] Starting subtest: hostile
[242.892741] Subtest hostile: SUCCESS (9.493s)
[243.004037] [044/133] (791s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[243.383245] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[244.189264] [045/133] (790s left) kms_frontbuffer_tracking (psr-1p-rte)
[244.283813] Starting subtest: psr-1p-rte
[249.746203] Subtest psr-1p-rte: SUCCESS (5.465s)
[249.887607] [046/133] (784s left) kms_psr (fbc-pr-primary-mmap-gtt)
[249.985472] Starting subtest: fbc-pr-primary-mmap-gtt
[249.987386] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[250.053470] [047/133] (784s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[250.167420] Starting subtest: cursorB-vs-flipB-atomic
[250.169127] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[250.308769] [048/133] (784s left) gem_eio (in-flight-internal-10ms)
[250.497865] Starting subtest: in-flight-internal-10ms
[252.387976] Subtest in-flight-internal-10ms: SUCCESS (1.889s)
[252.526702] [049/133] (782s left) kms_cursor_crc (cursor-offscreen-64x64)
[252.706401] Starting subtest: cursor-offscreen-64x64
[252.709688] Starting dynamic subtest: pipe-A-eDP-1
[254.668036] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.958s)
[254.698711] Starting dynamic subtest: pipe-D-eDP-1
[257.256327] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.564s)
[257.257556] Subtest cursor-offscreen-64x64: SUCCESS (4.549s)
[258.111110] [050/133] (776s left) gen7_exec_parse (oacontrol-tracking)
[258.162076] Subtest oacontrol-tracking: SKIP (0.000s)
[258.230466] [051/133] (776s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[258.319893] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[258.321904] Starting dynamic subtest: pipe-A-default-mode
[259.479630] Dynamic subtest pipe-A-default-mode: SKIP (1.157s)
[259.481346] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.159s)
[259.646934] [052/133] (775s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[259.731528] Starting subtest: disable-crc-after-crtc
[259.732486] Starting dynamic subtest: pipe-A-eDP-1
[261.414024] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.683s)
[261.423854] Starting dynamic subtest: pipe-B-eDP-1
[263.693415] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.276s)
[263.701539] Starting dynamic subtest: pipe-C-eDP-1
[265.942871] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.248s)
[265.951563] Starting dynamic subtest: pipe-D-eDP-1
[268.212626] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.268s)
[268.213612] Subtest disable-crc-after-crtc: SUCCESS (8.481s)
[269.033508] [053/133] (765s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[269.124650] Starting subtest: primary-yf-tiled-reflect-x-0
[269.184805] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s)
[269.284469] [054/133] (765s left) gem_ctx_persistence (engines-hang)
[269.337980] Starting subtest: engines-hang
[269.338987] Starting dynamic subtest: rcs0
[269.462533] Dynamic subtest rcs0: SUCCESS (0.124s)
[269.474550] Starting dynamic subtest: bcs0
[269.554029] Dynamic subtest bcs0: SUCCESS (0.089s)
[269.566353] Starting dynamic subtest: vcs0
[269.651721] Dynamic subtest vcs0: SUCCESS (0.096s)
[269.664344] Starting dynamic subtest: vcs1
[269.759860] Dynamic subtest vcs1: SUCCESS (0.106s)
[269.770721] Starting dynamic subtest: vecs0
[269.853366] Dynamic subtest vecs0: SUCCESS (0.092s)
[269.865594] Starting dynamic subtest: ccs0
[269.944748] Dynamic subtest ccs0: SUCCESS (0.089s)
[269.946227] Subtest engines-hang: SUCCESS (0.608s)
[270.018257] [055/133] (764s left) gem_eio (in-flight-1us)
[270.223797] Starting subtest: in-flight-1us
[279.107353] Subtest in-flight-1us: SUCCESS (8.883s)
[279.239742] [056/133] (755s left) gem_mmap_wc (pf-nonblock)
[279.296760] Subtest pf-nonblock: SKIP (0.000s)
[279.349017] [057/133] (755s left) kms_flip (plain-flip-fb-recreate-interruptible)
[279.446547] Starting subtest: plain-flip-fb-recreate-interruptible
[279.447530] Starting dynamic subtest: A-eDP1
[288.325897] Dynamic subtest A-eDP1: SUCCESS (8.880s)
[288.335916] Starting dynamic subtest: B-eDP1
[297.156563] Dynamic subtest B-eDP1: SUCCESS (8.827s)
[297.167199] Starting dynamic subtest: C-eDP1
[305.975423] Dynamic subtest C-eDP1: SUCCESS (8.816s)
[305.985032] Starting dynamic subtest: D-eDP1
[314.804130] Dynamic subtest D-eDP1: SUCCESS (8.828s)
[314.806483] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.363s)
[315.983770] [058/133] (719s left) gem_exec_reloc (basic-wc-gtt-active)
[316.027689] Subtest basic-wc-gtt-active: SKIP (0.000s)
[316.098296] [059/133] (719s left) kms_async_flips (async-flip-hang)
[316.183369] Starting subtest: async-flip-hang
[316.557381] Starting dynamic subtest: pipe-A-eDP-1
[331.834559] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.275s)
[332.229162] Starting dynamic subtest: pipe-B-eDP-1
[347.833735] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.610s)
[348.207894] Starting dynamic subtest: pipe-C-eDP-1
[363.832123] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.629s)
[364.207244] Starting dynamic subtest: pipe-D-eDP-1
[379.829838] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.629s)
[379.832637] Subtest async-flip-hang: SUCCESS (63.645s)
[381.041435] [060/133] (654s left) gem_exec_flush (basic-wb-set-default)
[381.091079] Starting subtest: basic-wb-set-default
[386.547236] Subtest basic-wb-set-default: SUCCESS (5.456s)
[386.739455] [061/133] (648s left) kms_cursor_edge_walk (128x128-top-edge)
[386.827220] Starting subtest: 128x128-top-edge
[386.828067] Starting dynamic subtest: pipe-A-eDP-1
[390.315811] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.488s)
[390.324538] Starting dynamic subtest: pipe-D-eDP-1
[393.437983] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.121s)
[393.438910] Subtest 128x128-top-edge: SUCCESS (6.611s)
[394.239325] [062/133] (640s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[394.330324] Starting subtest: x-tiled-8bpp-rotate-180
[395.577679] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.248s)
[395.686983] [063/133] (639s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[395.789426] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[395.791149] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[395.910886] [064/133] (639s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[395.994244] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[395.995029] Starting dynamic subtest: pipe-A-eDP-1
[396.002526] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[396.010524] Starting dynamic subtest: pipe-B-eDP-1
[396.018484] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[396.026787] Starting dynamic subtest: pipe-C-eDP-1
[396.034258] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[396.042211] Starting dynamic subtest: pipe-D-eDP-1
[396.050163] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[396.051707] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[398.302788] [065/133] (637s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[398.397863] Starting subtest: 4-tiled-32bpp-rotate-180
[399.843933] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.446s)
[399.960760] [066/133] (635s left) gem_exec_fence (syncobj-channel)
[400.403087] Starting subtest: syncobj-channel
[401.429644] Subtest syncobj-channel: SUCCESS (1.015s)
[402.424175] [067/133] (632s left) kms_mmap_write_crc (main)
[402.512103] Starting subtest: main
[402.513248] Starting dynamic subtest: pipe-A-eDP-1
[406.082522] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.570s)
[406.083481] Subtest main: SUCCESS (3.572s)
[406.943490] [068/133] (628s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[407.038074] Starting subtest: y-tiled-32bpp-rotate-180
[407.039994] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[407.130080] [069/133] (628s left) kms_pm_rpm (fences-dpms)
[408.657423] Starting subtest: fences-dpms
[408.660137] Subtest fences-dpms: SKIP (0.000s)
[409.380743] [070/133] (626s left) gem_ctx_param (set-priority-range)
[409.429928] Starting subtest: set-priority-range
[409.443496] Subtest set-priority-range: SUCCESS (0.014s)
[409.549069] [071/133] (626s left) api_intel_allocator (alloc-simple)
[409.598692] Starting subtest: alloc-simple
[409.600972] Subtest alloc-simple: SUCCESS (0.003s)
[409.715496] [072/133] (625s left) gem_exec_schedule (preempt-other-chain)
[409.776433] Starting subtest: preempt-other-chain
[409.777412] Starting dynamic subtest: rcs0
[409.860808] Dynamic subtest rcs0: SUCCESS (0.088s)
[409.869372] Starting dynamic subtest: bcs0
[409.945625] Dynamic subtest bcs0: SUCCESS (0.083s)
[409.958587] Starting dynamic subtest: vcs0
[410.032483] Dynamic subtest vcs0: SUCCESS (0.086s)
[410.045072] Starting dynamic subtest: vcs1
[410.119069] Dynamic subtest vcs1: SUCCESS (0.086s)
[410.129092] Starting dynamic subtest: vecs0
[410.204373] Dynamic subtest vecs0: SUCCESS (0.083s)
[410.216185] Starting dynamic subtest: ccs0
[410.289696] Dynamic subtest ccs0: SUCCESS (0.084s)
[410.291281] Subtest preempt-other-chain: SUCCESS (0.517s)
[411.369223] [073/133] (624s left) kms_addfb_basic (too-wide)
[411.466206] Starting subtest: too-wide
[411.467142] Subtest too-wide: SUCCESS (0.000s)
[411.564042] [074/133] (624s left) gem_userptr_blits (set-cache-level)
[411.617035] Starting subtest: set-cache-level
[411.619448] Subtest set-cache-level: SKIP (0.000s)
[411.752312] [075/133] (623s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[411.837979] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[411.839907] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[411.927640] [076/133] (623s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[412.026112] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[412.026951] Starting dynamic subtest: pipe-A-eDP-1
[412.030352] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[412.038380] Starting dynamic subtest: pipe-B-eDP-1
[412.042060] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[412.052210] Starting dynamic subtest: pipe-C-eDP-1
[412.055951] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[412.064111] Starting dynamic subtest: pipe-D-eDP-1
[412.067693] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[412.069553] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[414.350948] [077/133] (621s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[414.450433] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[414.462365] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[414.562463] [078/133] (621s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[414.640797] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[414.669068] Starting dynamic subtest: pipe-A-plane-0
[415.333963] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[415.342802] Starting dynamic subtest: pipe-A-plane-3
[415.767374] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[416.835085] Starting dynamic subtest: pipe-B-plane-0
[417.537865] Dynamic subtest pipe-B-plane-0: SUCCESS (0.711s)
[417.546789] Starting dynamic subtest: pipe-B-plane-3
[417.948958] Dynamic subtest pipe-B-plane-3: SUCCESS (0.410s)
[418.268897] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.628s)
[419.121744] [079/133] (616s left) gem_exec_params (rsvd2-dirt)
[419.184138] Starting subtest: rsvd2-dirt
[419.185999] Subtest rsvd2-dirt: SKIP (0.000s)
[419.289517] [080/133] (616s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[419.386418] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[419.387409] Starting dynamic subtest: pipe-A-eDP-1
[419.878680] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.481s)
[419.887813] Starting dynamic subtest: pipe-B-eDP-1
[421.375463] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.496s)
[421.383990] Starting dynamic subtest: pipe-C-eDP-1
[422.776062] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.399s)
[422.785203] Starting dynamic subtest: pipe-D-eDP-1
[424.197597] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.421s)
[424.198431] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.803s)
[425.381253] [081/133] (610s left) kms_big_fb (linear-16bpp-rotate-90)
[425.472534] Starting subtest: linear-16bpp-rotate-90
[425.495253] Subtest linear-16bpp-rotate-90: SKIP (0.022s)
[425.607804] [082/133] (610s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[425.695670] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[433.089838] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.394s)
[433.231607] [083/133] (602s left) kms_chamelium_audio (dp-audio)
[433.627258] Subtest dp-audio: SKIP (0.000s)
[434.393428] [084/133] (601s left) gem_bad_reloc (negative-reloc-lut)
[434.450122] Subtest negative-reloc-lut: SKIP (0.000s)
[434.509522] [085/133] (601s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[434.595727] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[434.597651] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[434.661847] [086/133] (601s left) i915_hangman (detector)
[434.714207] Starting subtest: detector
[434.715308] Starting dynamic subtest: rcs0
[446.276806] Dynamic subtest rcs0: SUCCESS (11.553s)
[446.287062] Starting dynamic subtest: bcs0
[458.307851] Dynamic subtest bcs0: SUCCESS (12.028s)
[458.319824] Starting dynamic subtest: vcs0
[470.277556] Dynamic subtest vcs0: SUCCESS (11.965s)
[470.288869] Starting dynamic subtest: vcs1
[481.285954] Dynamic subtest vcs1: SUCCESS (11.005s)
[481.295281] Starting dynamic subtest: vecs0
[493.316887] Dynamic subtest vecs0: SUCCESS (12.028s)
[493.326834] Starting dynamic subtest: ccs0
[505.284283] Dynamic subtest ccs0: SUCCESS (11.964s)
[505.286433] Subtest detector: SUCCESS (70.559s)
[505.368441] [087/133] (530s left) sysfs_heartbeat_interval (mixed)
[505.426908] Starting subtest: mixed
[505.427772] Starting dynamic subtest: vecs0
[510.653530] Dynamic subtest vecs0: SUCCESS (5.225s)
[510.664735] Starting dynamic subtest: ccs0
[515.837704] Dynamic subtest ccs0: SUCCESS (5.182s)
[515.848567] Starting dynamic subtest: vcs0
[521.028542] Dynamic subtest vcs0: SUCCESS (5.188s)
[521.040228] Starting dynamic subtest: bcs0
[526.217584] Dynamic subtest bcs0: SUCCESS (5.186s)
[526.228246] Starting dynamic subtest: rcs0
[531.421998] Dynamic subtest rcs0: SUCCESS (5.203s)
[531.434071] Starting dynamic subtest: vcs1
[536.647894] Dynamic subtest vcs1: SUCCESS (5.224s)
[536.651499] Subtest mixed: SUCCESS (31.220s)
[536.724868] [088/133] (499s left) kms_universal_plane (universal-plane-pageflip-windowed)
[536.827072] Starting subtest: universal-plane-pageflip-windowed
[536.827973] Starting dynamic subtest: pipe-A-eDP-1
[537.416602] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.588s)
[537.425059] Starting dynamic subtest: pipe-B-eDP-1
[538.653965] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.236s)
[538.662625] Starting dynamic subtest: pipe-C-eDP-1
[539.864397] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.208s)
[539.873673] Starting dynamic subtest: pipe-D-eDP-1
[541.090816] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.225s)
[541.091762] Subtest universal-plane-pageflip-windowed: SUCCESS (4.265s)
[541.931545] [089/133] (494s left) kms_dp_link_training (uhbr-sst)
[542.009659] Starting subtest: uhbr-sst
[542.013337] Subtest uhbr-sst: SKIP (0.000s)
[542.077951] [090/133] (494s left) gem_exec_balancer (bonded-pair)
[542.130965] Starting subtest: bonded-pair
[542.132812] Subtest bonded-pair: SKIP (0.000s)
[542.427770] [091/133] (493s left) gem_exec_fence (invalid-fence-array)
[542.863941] Starting subtest: invalid-fence-array
[542.866587] Subtest invalid-fence-array: SUCCESS (0.003s)
[544.417989] [092/133] (491s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[544.504642] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[544.506441] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[544.613231] [093/133] (491s left) kms_psr (psr-cursor-plane-onoff)
[544.702502] Starting subtest: psr-cursor-plane-onoff
[544.703421] Starting dynamic subtest: eDP-1
[546.450344] Dynamic subtest eDP-1: SUCCESS (1.747s)
[546.451385] Subtest psr-cursor-plane-onoff: SUCCESS (1.748s)
[547.306320] [094/133] (489s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[547.395326] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[547.396889] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[547.499094] [095/133] (488s left) kms_busy (basic)
[547.592339] Starting subtest: basic
[547.593242] Starting dynamic subtest: flip
[548.206475] Dynamic subtest flip: SUCCESS (0.613s)
[548.215274] Starting dynamic subtest: modeset
[551.642586] Dynamic subtest modeset: SUCCESS (3.436s)
[551.643541] Subtest basic: SUCCESS (4.051s)
[552.438952] [096/133] (483s left) gem_exec_async (forked-writes)
[552.490176] Starting subtest: forked-writes
[552.491291] Starting dynamic subtest: rcs0
[552.522188] Dynamic subtest rcs0: SUCCESS (0.031s)
[552.534703] Starting dynamic subtest: bcs0
[552.547266] Dynamic subtest bcs0: SUCCESS (0.024s)
[552.556586] Starting dynamic subtest: vcs0
[552.570612] Dynamic subtest vcs0: SUCCESS (0.023s)
[552.581392] Starting dynamic subtest: vcs1
[552.593989] Dynamic subtest vcs1: SUCCESS (0.022s)
[552.603351] Starting dynamic subtest: vecs0
[552.617614] Dynamic subtest vecs0: SUCCESS (0.023s)
[552.626795] Starting dynamic subtest: ccs0
[552.643848] Dynamic subtest ccs0: SUCCESS (0.025s)
[552.644740] Subtest forked-writes: SUCCESS (0.154s)
[552.866019] [097/133] (483s left) prime_vgem (coherency-gtt)
[552.949589] Starting subtest: coherency-gtt
[552.951412] Subtest coherency-gtt: SKIP (0.000s)
[553.043584] [098/133] (483s left) kms_properties (colorop-properties-legacy)
[553.129622] Starting subtest: colorop-properties-legacy
[553.130882] Starting dynamic subtest: pipe-A-eDP-1
[553.486614] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.357s)
[553.495272] Starting dynamic subtest: pipe-B-eDP-1
[554.614453] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.126s)
[554.623254] Starting dynamic subtest: pipe-C-eDP-1
[555.651386] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.037s)
[555.661291] Starting dynamic subtest: pipe-D-eDP-1
[556.683762] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.029s)
[556.684793] Subtest colorop-properties-legacy: SUCCESS (3.555s)
[557.474628] [099/133] (479s left) gem_mmap_wc (set-cache-level)
[557.531938] Subtest set-cache-level: SKIP (0.000s)
[557.583671] [100/133] (478s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[557.670031] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[564.616438] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.947s)
[564.773836] [101/133] (471s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[564.862358] Starting subtest: fbc-psr2-primary-mmap-cpu
[564.863280] Starting dynamic subtest: eDP-1
[564.865192] Dynamic subtest eDP-1: SKIP (0.000s)
[564.866825] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[564.934292] [102/133] (471s left) syncobj_timeline (32bits-limit)
[564.988658] Starting subtest: 32bits-limit
[564.989659] Subtest 32bits-limit: SUCCESS (0.001s)
[565.081247] [103/133] (471s left) perf_pmu (busy-accuracy-98)
[565.119582] Starting subtest: busy-accuracy-98
[565.120458] Starting dynamic subtest: rcs0
[568.165626] Dynamic subtest rcs0: SUCCESS (3.040s)
[568.177413] Starting dynamic subtest: bcs0
[571.218333] Dynamic subtest bcs0: SUCCESS (3.051s)
[571.229073] Starting dynamic subtest: vcs0
[574.261940] Dynamic subtest vcs0: SUCCESS (3.041s)
[574.273763] Starting dynamic subtest: vcs1
[577.306904] Dynamic subtest vcs1: SUCCESS (3.043s)
[577.317844] Starting dynamic subtest: vecs0
[580.350674] Dynamic subtest vecs0: SUCCESS (3.041s)
[580.361584] Starting dynamic subtest: ccs0
[583.405693] Dynamic subtest ccs0: SUCCESS (3.052s)
[583.406836] Subtest busy-accuracy-98: SUCCESS (18.283s)
[583.511293] [104/133] (453s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[583.599269] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[583.600244] Starting dynamic subtest: pipe-A
[583.619522] Dynamic subtest pipe-A: SKIP (0.018s)
[583.628240] Starting dynamic subtest: pipe-B
[583.651691] Dynamic subtest pipe-B: SKIP (0.032s)
[583.660184] Starting dynamic subtest: pipe-C
[583.685352] Dynamic subtest pipe-C: SKIP (0.033s)
[583.693658] Starting dynamic subtest: pipe-D
[583.719365] Dynamic subtest pipe-D: SKIP (0.033s)
[583.721054] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.118s)
[583.825344] [105/133] (452s left) gem_exec_whisper (basic-normal-all)
[583.885047] Starting subtest: basic-normal-all
[586.187916] Subtest basic-normal-all: SUCCESS (2.302s)
[586.381695] [106/133] (450s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[586.496635] Starting subtest: verify-pxp-execution-after-suspend-resume
[594.448695] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.953s)
[603.656243] [107/133] (442s left) gem_exec_reloc (basic-range-active)
[603.719676] Subtest basic-range-active: SKIP (0.000s)
[603.792791] [108/133] (442s left) kms_content_protection (dp-mst-type-1)
[603.929264] Starting subtest: dp-mst-type-1
[603.931049] Subtest dp-mst-type-1: SKIP (0.000s)
[604.041802] [109/133] (441s left) kms_invalid_mode (bad-htotal)
[604.122644] Starting subtest: bad-htotal
[604.123899] Starting dynamic subtest: pipe-A-eDP-1
[604.412238] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[604.421780] Starting dynamic subtest: pipe-B-eDP-1
[604.422592] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[604.431141] Starting dynamic subtest: pipe-C-eDP-1
[604.432022] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[604.440735] Starting dynamic subtest: pipe-D-eDP-1
[604.441662] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[604.442561] Subtest bad-htotal: SUCCESS (0.296s)
[605.219869] [110/133] (440s left) gem_userptr_blits (unsync-unmap-after-close)
[605.277304] Subtest unsync-unmap-after-close: SKIP (0.000s)
[605.402530] [111/133] (440s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[605.493448] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[605.495386] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[605.610311] [112/133] (440s left) gem_exec_whisper (basic-queues-priority)
[605.669568] Starting subtest: basic-queues-priority
[630.323290] Subtest basic-queues-priority: SUCCESS (24.603s)
[630.467056] [113/133] (415s left) kms_flip (2x-flip-vs-suspend)
[630.554873] Starting subtest: 2x-flip-vs-suspend
[630.557875] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[630.657184] [114/133] (415s left) gem_ctx_engines (execute-one)
[630.715477] Starting subtest: execute-one
[633.001252] Subtest execute-one: SUCCESS (2.286s)
[633.349543] [115/133] (412s left) kms_color_pipeline (plane-ctm3x4)
[633.441984] Starting subtest: plane-ctm3x4
[633.590809] Starting dynamic subtest: pipe-A-plane-0
[633.734469] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[633.744740] Starting dynamic subtest: pipe-A-plane-1
[633.890113] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[633.901704] Starting dynamic subtest: pipe-A-plane-2
[634.023355] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[635.242497] Starting dynamic subtest: pipe-B-plane-0
[635.377238] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[635.389527] Starting dynamic subtest: pipe-B-plane-1
[635.510179] Dynamic subtest pipe-B-plane-1: SUCCESS (0.132s)
[635.520117] Starting dynamic subtest: pipe-B-plane-2
[635.676986] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[636.866566] Starting dynamic subtest: pipe-C-plane-0
[636.990559] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[637.003496] Starting dynamic subtest: pipe-C-plane-1
[637.112772] Dynamic subtest pipe-C-plane-1: SUCCESS (0.121s)
[637.123090] Starting dynamic subtest: pipe-C-plane-2
[637.235277] Dynamic subtest pipe-C-plane-2: SUCCESS (0.122s)
[638.414737] Starting dynamic subtest: pipe-D-plane-0
[638.538919] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[638.548891] Starting dynamic subtest: pipe-D-plane-1
[638.671700] Dynamic subtest pipe-D-plane-1: SUCCESS (0.132s)
[638.681225] Starting dynamic subtest: pipe-D-plane-2
[638.794171] Dynamic subtest pipe-D-plane-2: SUCCESS (0.122s)
[639.079442] Subtest plane-ctm3x4: SUCCESS (5.637s)
[639.961712] [116/133] (406s left) kms_plane (plane-position-hole-dpms)
[640.053262] Starting subtest: plane-position-hole-dpms
[640.589249] Starting dynamic subtest: pipe-A-plane-1
[642.997852] Dynamic subtest pipe-A-plane-1: SUCCESS (2.406s)
[643.008253] Starting dynamic subtest: pipe-A-plane-2
[645.428341] Dynamic subtest pipe-A-plane-2: SUCCESS (2.430s)
[645.438101] Starting dynamic subtest: pipe-A-plane-3
[647.801330] Dynamic subtest pipe-A-plane-3: SUCCESS (2.372s)
[647.812488] Starting dynamic subtest: pipe-A-plane-4
[650.169952] Dynamic subtest pipe-A-plane-4: SUCCESS (2.368s)
[650.180111] Starting dynamic subtest: pipe-A-plane-5
[650.193894] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[651.346814] Starting dynamic subtest: pipe-B-plane-1
[653.788482] Dynamic subtest pipe-B-plane-1: SUCCESS (2.451s)
[653.799149] Starting dynamic subtest: pipe-B-plane-2
[656.268608] Dynamic subtest pipe-B-plane-2: SUCCESS (2.479s)
[656.278402] Starting dynamic subtest: pipe-B-plane-3
[658.705481] Dynamic subtest pipe-B-plane-3: SUCCESS (2.435s)
[658.715251] Starting dynamic subtest: pipe-B-plane-4
[661.067458] Dynamic subtest pipe-B-plane-4: SUCCESS (2.361s)
[661.077114] Starting dynamic subtest: pipe-B-plane-5
[661.092732] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[661.093787] Subtest plane-position-hole-dpms: SUCCESS (21.039s)
[661.880086] [117/133] (384s left) kms_plane_cursor (primary)
[661.967336] Starting subtest: primary
[661.969236] Starting dynamic subtest: pipe-A-eDP-1-size-64
[664.543234] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.576s)
[664.568532] Starting dynamic subtest: pipe-A-eDP-1-size-128
[667.144475] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.585s)
[667.169599] Starting dynamic subtest: pipe-A-eDP-1-size-256
[669.732456] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.572s)
[670.047805] Starting dynamic subtest: pipe-B-eDP-1-size-64
[673.368777] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.330s)
[673.397194] Starting dynamic subtest: pipe-B-eDP-1-size-128
[675.980776] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.594s)
[676.011888] Starting dynamic subtest: pipe-B-eDP-1-size-256
[678.559881] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.561s)
[678.885565] Starting dynamic subtest: pipe-C-eDP-1-size-64
[681.873870] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.001s)
[681.903218] Starting dynamic subtest: pipe-C-eDP-1-size-128
[684.151953] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.259s)
[684.180290] Starting dynamic subtest: pipe-C-eDP-1-size-256
[686.451971] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.281s)
[686.778026] Starting dynamic subtest: pipe-D-eDP-1-size-64
[689.725275] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.958s)
[689.753978] Starting dynamic subtest: pipe-D-eDP-1-size-128
[692.002983] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.258s)
[692.033330] Starting dynamic subtest: pipe-D-eDP-1-size-256
[694.270098] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.247s)
[694.575752] Subtest primary: SUCCESS (32.607s)
[695.413504] [118/133] (350s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[695.550278] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[695.552891] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[695.766684] [119/133] (350s left) gen9_exec_parse (unaligned-access)
[695.855475] Subtest unaligned-access: SKIP (0.000s)
[695.953565] [120/133] (350s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[696.015887] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[696.018504] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[696.209715] [121/133] (350s left) kms_cursor_crc (cursor-random-64x64)
[696.425543] Starting subtest: cursor-random-64x64
[696.429503] Starting dynamic subtest: pipe-A-eDP-1
[699.774914] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.346s)
[699.807296] Starting dynamic subtest: pipe-D-eDP-1
[703.797110] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.997s)
[703.798528] Subtest cursor-random-64x64: SUCCESS (7.370s)
[704.639791] [122/133] (341s left) dumb_buffer (create-valid-dumb)
[704.689984] Starting subtest: create-valid-dumb
[704.690914] Subtest create-valid-dumb: SUCCESS (0.000s)
[704.785492] [123/133] (341s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[704.827057] Starting subtest: etime-multi-wait-all-submitted-signaled
[704.929732] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.102s)
[705.006866] [124/133] (341s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[705.095997] Starting subtest: 4-tiled-16bpp-rotate-180
[706.442632] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.347s)
[706.563353] [125/133] (340s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[706.654666] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[706.656448] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[706.751155] [126/133] (339s left) i915_pm_rc6_residency (rc6-idle)
[706.793522] Starting subtest: rc6-idle
[706.822026] Starting dynamic subtest: gt0-rcs0
[731.030588] Dynamic subtest gt0-rcs0: SUCCESS (24.207s)
[731.041916] Starting dynamic subtest: gt0-bcs0
[755.312483] Dynamic subtest gt0-bcs0: SUCCESS (24.278s)
[755.323598] Starting dynamic subtest: gt0-ccs0
[779.593753] Dynamic subtest gt0-ccs0: SUCCESS (24.278s)
[779.605283] Starting dynamic subtest: gt1-vcs0
[785.810881] Dynamic subtest gt1-vcs0: SUCCESS (6.168s)
[785.823558] Starting dynamic subtest: gt1-vecs0
[791.982206] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[791.983225] Subtest rc6-idle: SUCCESS (85.142s)
[792.016567] [127/133] (254s left) kms_pm_rpm (universal-planes-dpms)
[793.544261] Starting subtest: universal-planes-dpms
[793.545188] Starting dynamic subtest: plane-35
[801.968479] Dynamic subtest plane-35: SUCCESS (8.425s)
[801.983861] Starting dynamic subtest: plane-65
[810.316572] Dynamic subtest plane-65: SUCCESS (8.344s)
[810.331264] Starting dynamic subtest: plane-95
[818.588823] Dynamic subtest plane-95: SUCCESS (8.270s)
[818.603073] Starting dynamic subtest: plane-125
[827.208761] Dynamic subtest plane-125: SUCCESS (8.617s)
[827.223977] Starting dynamic subtest: plane-135
[835.524996] Dynamic subtest plane-135: SUCCESS (8.313s)
[835.538963] Starting dynamic subtest: plane-145
[843.838703] Dynamic subtest plane-145: SUCCESS (8.310s)
[843.840417] Subtest universal-planes-dpms: SUCCESS (50.296s)
[844.209736] [128/133] (202s left) gem_mmap_offset (bad-object)
[844.269517] Starting subtest: bad-object
[844.276897] Subtest bad-object: SUCCESS (0.001s)
[844.370805] [129/133] (202s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[844.467344] Starting subtest: fbc-modesetfrombusy
[848.187298] Subtest fbc-modesetfrombusy: SUCCESS (3.721s)
[849.361803] [130/133] (197s left) kms_properties (invalid-properties-legacy)
[849.454285] Starting subtest: invalid-properties-legacy
[849.572369] Subtest invalid-properties-legacy: SUCCESS (0.108s)
[849.643292] [131/133] (197s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[849.688887] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[849.690307] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[849.783788] [132/133] (197s left) gem_softpin (evict-single-offset)
[849.846279] Starting subtest: evict-single-offset
[878.311239] Subtest evict-single-offset: SUCCESS (28.455s)
[878.389947] [133/133] (168s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[878.536990] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[878.538683] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[878.637158] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_ccs
results: parsing output: 3/ for test: gem_mmap_gtt
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: gem_softpin
results: parsing output: 9/ for test: gem_mmap_wc
results: parsing output: 10/ for test: gem_flink_basic
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: perf_pmu
results: parsing output: 14/ for test: gem_render_copy_redux
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: kms_vblank
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: i915_query
results: parsing output: 24/ for test: kms_chamelium_frames
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: perf
results: parsing output: 28/ for test: kms_feature_discovery
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: gem_exec_reloc
results: parsing output: 32/ for test: core_getversion
results: parsing output: 33/ for test: gem_request_retire
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_exec_schedule
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: prime_vgem
results: parsing output: 39/ for test: kms_cursor_legacy
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: prime_vgem
results: parsing output: 42/ for test: gem_ctx_persistence
results: parsing output: 43/ for test: kms_chamelium_hpd
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: gem_eio
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: gen7_exec_parse
results: parsing output: 50/ for test: kms_flip_scaled_crc
results: parsing output: 51/ for test: kms_pipe_crc_basic
results: parsing output: 52/ for test: kms_rotation_crc
results: parsing output: 53/ for test: gem_ctx_persistence
results: parsing output: 54/ for test: gem_eio
results: parsing output: 55/ for test: gem_mmap_wc
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_async_flips
results: parsing output: 59/ for test: gem_exec_flush
results: parsing output: 60/ for test: kms_cursor_edge_walk
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: gem_exec_fence
results: parsing output: 66/ for test: kms_mmap_write_crc
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: gem_ctx_param
results: parsing output: 70/ for test: api_intel_allocator
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: kms_addfb_basic
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_chamelium_audio
results: parsing output: 83/ for test: gem_bad_reloc
results: parsing output: 84/ for test: kms_psr2_sf
results: parsing output: 85/ for test: i915_hangman
results: parsing output: 86/ for test: sysfs_heartbeat_interval
results: parsing output: 87/ for test: kms_universal_plane
results: parsing output: 88/ for test: kms_dp_link_training
results: parsing output: 89/ for test: gem_exec_balancer
results: parsing output: 90/ for test: gem_exec_fence
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_busy
results: parsing output: 95/ for test: gem_exec_async
results: parsing output: 96/ for test: prime_vgem
results: parsing output: 97/ for test: kms_properties
results: parsing output: 98/ for test: gem_mmap_wc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: gem_exec_whisper
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_content_protection
results: parsing output: 108/ for test: kms_invalid_mode
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_exec_whisper
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: gem_ctx_engines
results: parsing output: 114/ for test: kms_color_pipeline
results: parsing output: 115/ for test: kms_plane
results: parsing output: 116/ for test: kms_plane_cursor
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: gem_render_copy
results: parsing output: 120/ for test: kms_cursor_crc
results: parsing output: 121/ for test: dumb_buffer
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_pm_rc6_residency
results: parsing output: 126/ for test: kms_pm_rpm
results: parsing output: 127/ for test: gem_mmap_offset
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_properties
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: gem_softpin
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.