[57.332100] Initializing watchdogs
[57.332156]   /dev/watchdog0
[57.347520] [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]
[57.351917] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.354147] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.356154] [001/133] (960s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[57.417756] Starting subtest: invalid-multi-wait-unsubmitted
[57.418540] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[57.535719] [002/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[57.640247] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[57.642334] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[57.753976] [003/133] (960s left) kms_ccs (bad-aux-stride-y-tiled-gen12-mc-ccs)
[57.836320] Starting subtest: bad-aux-stride-y-tiled-gen12-mc-ccs
[57.837294] Starting dynamic subtest: pipe-A-eDP-1
[57.846273] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.853600] Starting dynamic subtest: pipe-B-eDP-1
[57.861717] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.867894] Starting dynamic subtest: pipe-C-eDP-1
[57.875577] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.881538] Starting dynamic subtest: pipe-D-eDP-1
[57.889153] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.890647] Subtest bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[60.347757] [004/133] (957s left) gem_mmap_gtt (basic-small-copy-odd)
[60.399084] Subtest basic-small-copy-odd: SKIP (0.000s)
[60.444453] [005/133] (957s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[60.522242] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[60.523968] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[60.585617] [006/133] (957s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[60.689269] Starting subtest: yf-tiled-32bpp-rotate-180
[60.691126] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[60.797305] [007/133] (957s left) syncobj_timeline (single-wait-available-signaled)
[60.855168] Starting subtest: single-wait-available-signaled
[60.856222] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[60.959193] [008/133] (957s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[61.075981] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[70.647994] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.629s)
[71.508490] [009/133] (946s left) gem_softpin (softpin)
[71.564083] Starting subtest: softpin
[103.936377] Subtest softpin: SUCCESS (32.375s)
[104.027107] [010/133] (914s left) gem_mmap_wc (read)
[104.075121] Subtest read: SKIP (0.000s)
[104.131560] [011/133] (914s left) gem_flink_basic (basic)
[104.178115] Starting subtest: basic
[104.179051] Subtest basic: SUCCESS (0.000s)
[104.269709] [012/133] (913s left) gem_exec_params (invalid-bsd1-flag-on-render)
[104.319062] Starting subtest: invalid-bsd1-flag-on-render
[104.320183] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[104.390974] [013/133] (913s left) gem_mmap_gtt (cpuset-big-copy)
[104.435130] Subtest cpuset-big-copy: SKIP (0.000s)
[104.492874] [014/133] (913s left) perf_pmu (busy-idle-check-all)
[104.526826] Starting subtest: busy-idle-check-all
[104.527877] Starting dynamic subtest: rcs0
[105.186808] Dynamic subtest rcs0: SUCCESS (0.659s)
[105.198787] Starting dynamic subtest: bcs0
[105.842922] Dynamic subtest bcs0: SUCCESS (0.655s)
[105.853403] Starting dynamic subtest: vcs0
[107.368073] Dynamic subtest vcs0: SUCCESS (1.514s)
[107.383852] Starting dynamic subtest: vcs1
[109.352180] Dynamic subtest vcs1: SUCCESS (1.983s)
[109.361832] Starting dynamic subtest: vecs0
[111.336093] Dynamic subtest vecs0: SUCCESS (1.983s)
[111.343581] Starting dynamic subtest: ccs0
[111.988254] Dynamic subtest ccs0: SUCCESS (0.660s)
[111.989597] Subtest busy-idle-check-all: SUCCESS (7.462s)
[112.083807] [015/133] (906s left) gem_render_copy_redux (flink)
[112.133277] Starting subtest: flink
[113.196484] Subtest flink: SUCCESS (1.064s)
[113.320910] [016/133] (904s left) kms_psr (fbc-psr-primary-blt)
[113.415931] Starting subtest: fbc-psr-primary-blt
[113.416926] Starting dynamic subtest: eDP-1
[113.418603] Dynamic subtest eDP-1: SKIP (0.000s)
[113.420200] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[113.481469] [017/133] (904s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[113.573408] Starting subtest: fbc-rgb565-draw-mmap-cpu
[117.707965] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.134s)
[118.900174] [018/133] (899s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[118.995937] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[118.997744] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[119.093281] [019/133] (899s left) gem_ctx_param (invalid-size-get)
[119.141499] Starting subtest: invalid-size-get
[119.142895] Subtest invalid-size-get: SUCCESS (0.001s)
[119.250264] [020/133] (899s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[119.337557] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[126.176129] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.840s)
[126.303864] [021/133] (892s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[126.429470] Starting subtest: flip-vs-cursor-atomic
[133.987923] Subtest flip-vs-cursor-atomic: SUCCESS (7.558s)
[134.857142] [022/133] (883s left) kms_vblank (wait-busy-hang)
[134.943191] Starting subtest: wait-busy-hang
[134.944091] Starting dynamic subtest: pipe-A-eDP-1
[161.072735] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.134s)
[161.083826] Starting dynamic subtest: pipe-D-eDP-1
[186.083325] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.008s)
[186.084103] Subtest wait-busy-hang: SUCCESS (51.146s)
[186.923385] [023/133] (831s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[187.013684] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[187.015459] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[187.110636] [024/133] (831s left) i915_query (test-query-geometry-subslices)
[187.163159] Starting subtest: test-query-geometry-subslices
[187.164134] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[187.250816] [025/133] (831s left) kms_chamelium_frames (hdmi-frame-dump)
[187.621496] Subtest hdmi-frame-dump: SKIP (0.000s)
[188.443119] [026/133] (830s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[188.555560] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[189.954142] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.398s)
[190.827859] [027/133] (827s left) kms_psr (psr-dpms)
[190.931146] Starting subtest: psr-dpms
[190.932157] Starting dynamic subtest: eDP-1
[193.880589] Dynamic subtest eDP-1: SUCCESS (2.950s)
[193.881560] Subtest psr-dpms: SUCCESS (2.950s)
[194.728951] [028/133] (823s left) perf (buffer-fill)
[194.857510] Starting subtest: buffer-fill
[194.858341] Starting dynamic subtest: 0-rcs0
[197.124275] Dynamic subtest 0-rcs0: SUCCESS (2.258s)
[197.133974] Starting dynamic subtest: 1-vcs0
[201.577037] Dynamic subtest 1-vcs0: SUCCESS (4.452s)
[201.578066] Subtest buffer-fill: SUCCESS (6.711s)
[201.613238] [029/133] (817s left) kms_feature_discovery (display)
[201.703186] Starting subtest: display
[201.704045] Subtest display: SUCCESS (0.000s)
[201.768673] [030/133] (816s left) syncobj_wait (invalid-single-wait-unsubmitted)
[201.818235] Starting subtest: invalid-single-wait-unsubmitted
[201.819278] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[201.905206] [031/133] (816s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[201.947075] Starting subtest: multi-wait-for-submit-submitted-signaled
[201.947913] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[202.013746] [032/133] (816s left) gem_exec_reloc (basic-concurrent0)
[202.059838] Subtest basic-concurrent0: SKIP (0.000s)
[202.140699] [033/133] (816s left) core_getversion (all-cards)
[202.170715] Starting subtest: all-cards
[202.177851] Subtest all-cards: SUCCESS (0.009s)
[202.217413] [034/133] (816s left) gem_request_retire (retire-vma-not-inactive)
[202.268512] Starting subtest: retire-vma-not-inactive
[202.329475] Subtest retire-vma-not-inactive: SUCCESS (0.060s)
[202.437601] [035/133] (816s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[202.518677] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[202.520443] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[202.617095] [036/133] (816s left) gem_exec_schedule (timeslicing)
[202.670711] Starting subtest: timeslicing
[202.672327] Starting dynamic subtest: rcs0
[202.690764] Dynamic subtest rcs0: SUCCESS (0.020s)
[202.702047] Starting dynamic subtest: bcs0
[202.710510] Dynamic subtest bcs0: SUCCESS (0.019s)
[202.717931] Starting dynamic subtest: vcs0
[202.730806] Dynamic subtest vcs0: SUCCESS (0.020s)
[202.738102] Starting dynamic subtest: vcs1
[202.750131] Dynamic subtest vcs1: SUCCESS (0.019s)
[202.758066] Starting dynamic subtest: vecs0
[202.773876] Dynamic subtest vecs0: SUCCESS (0.023s)
[202.783491] Starting dynamic subtest: ccs0
[202.796055] Dynamic subtest ccs0: SUCCESS (0.021s)
[202.797059] Subtest timeslicing: SUCCESS (0.126s)
[204.349704] [037/133] (814s left) kms_psr (pr-sprite-render)
[204.435336] Starting subtest: pr-sprite-render
[204.437147] Subtest pr-sprite-render: SKIP (0.000s)
[204.516732] [038/133] (814s left) kms_frontbuffer_tracking (basic)
[204.600263] Starting subtest: basic
[208.102834] Subtest basic: SUCCESS (3.505s)
[208.226525] [039/133] (810s left) prime_vgem (sync)
[208.355381] Starting subtest: sync
[208.356334] Starting dynamic subtest: rcs0
[208.399701] Dynamic subtest rcs0: SUCCESS (0.044s)
[208.407084] Starting dynamic subtest: bcs0
[208.446709] Dynamic subtest bcs0: SUCCESS (0.046s)
[208.453979] Starting dynamic subtest: vcs0
[208.493692] Dynamic subtest vcs0: SUCCESS (0.046s)
[208.501074] Starting dynamic subtest: vcs1
[208.539101] Dynamic subtest vcs1: SUCCESS (0.045s)
[208.546650] Starting dynamic subtest: vecs0
[208.585366] Dynamic subtest vecs0: SUCCESS (0.046s)
[208.593037] Starting dynamic subtest: ccs0
[208.630509] Dynamic subtest ccs0: SUCCESS (0.044s)
[208.631957] Subtest sync: SUCCESS (0.275s)
[208.759307] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[208.761721] [040/133] (810s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[208.868508] Starting subtest: cursorA-vs-flipA-atomic
[220.002396] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.133s)
[220.864559] [041/133] (798s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[220.958411] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[228.174751] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.217s)
[228.306224] [042/133] (790s left) prime_vgem (basic-gtt)
[228.377021] Starting subtest: basic-gtt
[228.379040] Subtest basic-gtt: SKIP (0.000s)
[228.470222] [043/133] (790s left) gem_ctx_persistence (hostile)
[228.521789] Starting subtest: hostile
[238.675422] Subtest hostile: SUCCESS (10.143s)
[238.787879] [044/133] (780s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[239.171355] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[239.988437] [045/133] (779s left) kms_frontbuffer_tracking (psr-1p-rte)
[240.085325] Starting subtest: psr-1p-rte
[245.568933] Subtest psr-1p-rte: SUCCESS (5.486s)
[245.712465] [046/133] (773s left) kms_psr (fbc-pr-primary-mmap-gtt)
[245.799255] Starting subtest: fbc-pr-primary-mmap-gtt
[245.801076] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[245.865511] [047/133] (773s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[245.985899] Starting subtest: cursorB-vs-flipB-atomic
[245.987784] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[246.125013] [048/133] (773s left) gem_eio (in-flight-internal-10ms)
[246.320989] Starting subtest: in-flight-internal-10ms
[247.394270] Subtest in-flight-internal-10ms: SUCCESS (1.073s)
[247.544525] [049/133] (771s left) kms_cursor_crc (cursor-offscreen-64x64)
[247.710514] Starting subtest: cursor-offscreen-64x64
[247.713435] Starting dynamic subtest: pipe-A-eDP-1
[249.679515] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.966s)
[249.710732] Starting dynamic subtest: pipe-D-eDP-1
[252.295737] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.584s)
[252.296568] Subtest cursor-offscreen-64x64: SUCCESS (4.577s)
[253.118788] [050/133] (766s left) gen7_exec_parse (oacontrol-tracking)
[253.179181] Subtest oacontrol-tracking: SKIP (0.000s)
[253.244671] [051/133] (766s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[253.337453] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[253.338412] Starting dynamic subtest: pipe-A-default-mode
[254.484952] Dynamic subtest pipe-A-default-mode: SKIP (1.146s)
[254.487058] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.147s)
[254.613782] [052/133] (764s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[254.702488] Starting subtest: disable-crc-after-crtc
[254.703505] Starting dynamic subtest: pipe-A-eDP-1
[256.414395] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.711s)
[256.423235] Starting dynamic subtest: pipe-B-eDP-1
[258.702857] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.287s)
[258.710927] Starting dynamic subtest: pipe-C-eDP-1
[260.941531] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.238s)
[260.951221] Starting dynamic subtest: pipe-D-eDP-1
[263.216168] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.272s)
[263.216938] Subtest disable-crc-after-crtc: SUCCESS (8.514s)
[264.029794] [053/133] (755s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[264.116138] Starting subtest: primary-yf-tiled-reflect-x-0
[264.175043] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.060s)
[264.273041] [054/133] (755s left) gem_ctx_persistence (engines-hang)
[264.331569] Starting subtest: engines-hang
[264.332495] Starting dynamic subtest: rcs0
[264.445552] Dynamic subtest rcs0: SUCCESS (0.113s)
[264.456995] Starting dynamic subtest: bcs0
[264.537704] Dynamic subtest bcs0: SUCCESS (0.090s)
[264.549721] Starting dynamic subtest: vcs0
[264.611603] Dynamic subtest vcs0: SUCCESS (0.072s)
[264.624380] Starting dynamic subtest: vcs1
[264.735738] Dynamic subtest vcs1: SUCCESS (0.122s)
[264.747964] Starting dynamic subtest: vecs0
[264.828587] Dynamic subtest vecs0: SUCCESS (0.090s)
[264.839716] Starting dynamic subtest: ccs0
[264.921931] Dynamic subtest ccs0: SUCCESS (0.091s)
[264.925506] Subtest engines-hang: SUCCESS (0.592s)
[264.998077] [055/133] (754s left) gem_eio (in-flight-1us)
[265.206424] Starting subtest: in-flight-1us
[274.039094] Subtest in-flight-1us: SUCCESS (8.833s)
[274.208084] [056/133] (745s left) gem_mmap_wc (pf-nonblock)
[274.261584] Subtest pf-nonblock: SKIP (0.000s)
[274.314871] [057/133] (745s left) kms_flip (plain-flip-fb-recreate-interruptible)
[274.397447] Starting subtest: plain-flip-fb-recreate-interruptible
[274.399955] Starting dynamic subtest: A-eDP1
[283.225631] Dynamic subtest A-eDP1: SUCCESS (8.825s)
[283.237741] Starting dynamic subtest: B-eDP1
[292.059397] Dynamic subtest B-eDP1: SUCCESS (8.831s)
[292.069063] Starting dynamic subtest: C-eDP1
[300.880657] Dynamic subtest C-eDP1: SUCCESS (8.818s)
[300.893827] Starting dynamic subtest: D-eDP1
[309.708605] Dynamic subtest D-eDP1: SUCCESS (8.826s)
[309.712093] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.313s)
[310.880599] [058/133] (708s left) gem_exec_reloc (basic-wc-gtt-active)
[310.935840] Subtest basic-wc-gtt-active: SKIP (0.000s)
[311.005165] [059/133] (708s left) kms_async_flips (async-flip-hang)
[311.093100] Starting subtest: async-flip-hang
[311.479728] Starting dynamic subtest: pipe-A-eDP-1
[337.851562] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.370s)
[338.255300] Starting dynamic subtest: pipe-B-eDP-1
[353.852108] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.607s)
[354.237462] Starting dynamic subtest: pipe-C-eDP-1
[369.785850] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.553s)
[370.163852] Starting dynamic subtest: pipe-D-eDP-1
[385.788264] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.627s)
[385.789491] Subtest async-flip-hang: SUCCESS (74.689s)
[386.952449] [060/133] (632s left) gem_exec_flush (basic-wb-set-default)
[387.009359] Starting subtest: basic-wb-set-default
[392.469855] Subtest basic-wb-set-default: SUCCESS (5.454s)
[392.648948] [061/133] (627s left) kms_cursor_edge_walk (128x128-top-edge)
[392.753844] Starting subtest: 128x128-top-edge
[392.754824] Starting dynamic subtest: pipe-A-eDP-1
[396.221460] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.467s)
[396.231206] Starting dynamic subtest: pipe-D-eDP-1
[399.351556] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.128s)
[399.352484] Subtest 128x128-top-edge: SUCCESS (6.597s)
[400.148146] [062/133] (619s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[400.251120] Starting subtest: x-tiled-8bpp-rotate-180
[401.499133] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.248s)
[401.619810] [063/133] (618s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[401.712705] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[401.714566] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[401.802354] [064/133] (618s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[401.886268] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[401.887184] Starting dynamic subtest: pipe-A-eDP-1
[401.896097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[401.904389] Starting dynamic subtest: pipe-B-eDP-1
[401.912909] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[401.921425] Starting dynamic subtest: pipe-C-eDP-1
[401.929589] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[401.937653] Starting dynamic subtest: pipe-D-eDP-1
[401.946242] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[401.947867] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[404.225063] [065/133] (615s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[404.320517] Starting subtest: 4-tiled-32bpp-rotate-180
[405.854713] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.535s)
[405.985543] [066/133] (614s left) gem_exec_fence (syncobj-channel)
[406.407043] Starting subtest: syncobj-channel
[407.431245] Subtest syncobj-channel: SUCCESS (1.015s)
[408.426194] [067/133] (611s left) kms_mmap_write_crc (main)
[408.516037] Starting subtest: main
[408.517052] Starting dynamic subtest: pipe-A-eDP-1
[412.149804] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.633s)
[412.150731] Subtest main: SUCCESS (3.634s)
[412.971509] [068/133] (607s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[413.070887] Starting subtest: y-tiled-32bpp-rotate-180
[413.072723] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[413.152032] [069/133] (606s left) kms_pm_rpm (fences-dpms)
[414.673466] Starting subtest: fences-dpms
[414.676289] Subtest fences-dpms: SKIP (0.000s)
[415.401936] [070/133] (604s left) gem_ctx_param (set-priority-range)
[415.451605] Starting subtest: set-priority-range
[415.466568] Subtest set-priority-range: SUCCESS (0.015s)
[415.577972] [071/133] (604s left) api_intel_allocator (alloc-simple)
[415.621683] Starting subtest: alloc-simple
[415.623794] Subtest alloc-simple: SUCCESS (0.004s)
[415.746379] [072/133] (604s left) gem_exec_schedule (preempt-other-chain)
[415.809866] Starting subtest: preempt-other-chain
[415.811050] Starting dynamic subtest: rcs0
[415.896254] Dynamic subtest rcs0: SUCCESS (0.086s)
[415.914051] Starting dynamic subtest: bcs0
[415.978271] Dynamic subtest bcs0: SUCCESS (0.081s)
[415.990163] Starting dynamic subtest: vcs0
[416.063411] Dynamic subtest vcs0: SUCCESS (0.084s)
[416.076776] Starting dynamic subtest: vcs1
[416.145288] Dynamic subtest vcs1: SUCCESS (0.081s)
[416.156243] Starting dynamic subtest: vecs0
[416.232405] Dynamic subtest vecs0: SUCCESS (0.086s)
[416.253752] Starting dynamic subtest: ccs0
[416.316806] Dynamic subtest ccs0: SUCCESS (0.084s)
[416.318286] Subtest preempt-other-chain: SUCCESS (0.507s)
[417.432450] [073/133] (602s left) kms_addfb_basic (too-wide)
[417.521365] Starting subtest: too-wide
[417.522311] Subtest too-wide: SUCCESS (0.000s)
[417.623304] [074/133] (602s left) gem_userptr_blits (set-cache-level)
[417.683845] Starting subtest: set-cache-level
[417.685483] Subtest set-cache-level: SKIP (0.000s)
[417.812082] [075/133] (602s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[417.923935] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[417.925708] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[418.025545] [076/133] (602s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[418.111236] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[418.112096] Starting dynamic subtest: pipe-A-eDP-1
[418.115641] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[418.124035] Starting dynamic subtest: pipe-B-eDP-1
[418.127926] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[418.136272] Starting dynamic subtest: pipe-C-eDP-1
[418.140273] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[418.148505] Starting dynamic subtest: pipe-D-eDP-1
[418.151934] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[418.153606] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[420.362345] [077/133] (600s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[420.447066] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[420.456814] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[420.531735] [078/133] (599s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[420.609423] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[420.636249] Starting dynamic subtest: pipe-A-plane-0
[421.302752] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[421.311471] Starting dynamic subtest: pipe-A-plane-3
[421.791853] Dynamic subtest pipe-A-plane-3: SUCCESS (0.488s)
[422.863639] Starting dynamic subtest: pipe-B-plane-0
[423.519581] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[423.528274] Starting dynamic subtest: pipe-B-plane-3
[423.930577] Dynamic subtest pipe-B-plane-3: SUCCESS (0.410s)
[424.248224] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.639s)
[425.044497] [079/133] (595s left) gem_exec_params (rsvd2-dirt)
[425.103248] Starting subtest: rsvd2-dirt
[425.105314] Subtest rsvd2-dirt: SKIP (0.000s)
[425.218750] [080/133] (595s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[425.303556] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[425.304522] Starting dynamic subtest: pipe-A-eDP-1
[425.818436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.505s)
[425.827198] Starting dynamic subtest: pipe-B-eDP-1
[427.352473] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.533s)
[427.361127] Starting dynamic subtest: pipe-C-eDP-1
[428.813270] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.459s)
[428.822952] Starting dynamic subtest: pipe-D-eDP-1
[430.203870] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.389s)
[430.204769] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.891s)
[431.383055] [081/133] (589s left) kms_big_fb (linear-16bpp-rotate-90)
[431.481109] Starting subtest: linear-16bpp-rotate-90
[431.500796] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[431.612711] [082/133] (588s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[431.710853] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[439.143161] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.433s)
[439.273868] [083/133] (581s left) kms_chamelium_audio (dp-audio)
[439.656222] Subtest dp-audio: SKIP (0.000s)
[440.418586] [084/133] (580s left) gem_bad_reloc (negative-reloc-lut)
[440.481446] Subtest negative-reloc-lut: SKIP (0.000s)
[440.552405] [085/133] (580s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[440.645063] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[440.646911] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[440.712564] [086/133] (580s left) i915_hangman (detector)
[440.762456] Starting subtest: detector
[440.763443] Starting dynamic subtest: rcs0
[452.292995] Dynamic subtest rcs0: SUCCESS (11.521s)
[452.304609] Starting dynamic subtest: bcs0
[463.301914] Dynamic subtest bcs0: SUCCESS (11.006s)
[463.311941] Starting dynamic subtest: vcs0
[475.269119] Dynamic subtest vcs0: SUCCESS (11.964s)
[475.280721] Starting dynamic subtest: vcs1
[486.277642] Dynamic subtest vcs1: SUCCESS (11.005s)
[486.286497] Starting dynamic subtest: vecs0
[497.284802] Dynamic subtest vecs0: SUCCESS (11.004s)
[497.296785] Starting dynamic subtest: ccs0
[509.317340] Dynamic subtest ccs0: SUCCESS (12.029s)
[509.318972] Subtest detector: SUCCESS (68.545s)
[509.434682] [087/133] (511s left) sysfs_heartbeat_interval (mixed)
[509.478876] Starting subtest: mixed
[509.479762] Starting dynamic subtest: vecs0
[514.727938] Dynamic subtest vecs0: SUCCESS (5.248s)
[514.738812] Starting dynamic subtest: ccs0
[519.940627] Dynamic subtest ccs0: SUCCESS (5.210s)
[519.951889] Starting dynamic subtest: vcs0
[525.122739] Dynamic subtest vcs0: SUCCESS (5.180s)
[525.134254] Starting dynamic subtest: bcs0
[530.330464] Dynamic subtest bcs0: SUCCESS (5.205s)
[530.346554] Starting dynamic subtest: rcs0
[535.551584] Dynamic subtest rcs0: SUCCESS (5.219s)
[535.564541] Starting dynamic subtest: vcs1
[540.728836] Dynamic subtest vcs1: SUCCESS (5.175s)
[540.734318] Subtest mixed: SUCCESS (31.250s)
[540.806833] [088/133] (479s left) kms_universal_plane (universal-plane-pageflip-windowed)
[540.888942] Starting subtest: universal-plane-pageflip-windowed
[540.889840] Starting dynamic subtest: pipe-A-eDP-1
[541.484129] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.594s)
[541.492854] Starting dynamic subtest: pipe-B-eDP-1
[542.680797] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.195s)
[542.689746] Starting dynamic subtest: pipe-C-eDP-1
[543.902124] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.220s)
[543.910780] Starting dynamic subtest: pipe-D-eDP-1
[545.124056] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s)
[545.125233] Subtest universal-plane-pageflip-windowed: SUCCESS (4.234s)
[545.978083] [089/133] (474s left) kms_dp_link_training (uhbr-sst)
[546.066249] Starting subtest: uhbr-sst
[546.069404] Subtest uhbr-sst: SKIP (0.000s)
[546.134553] [090/133] (474s left) gem_exec_balancer (bonded-pair)
[546.187176] Starting subtest: bonded-pair
[546.188859] Subtest bonded-pair: SKIP (0.000s)
[546.486329] [091/133] (474s left) gem_exec_fence (invalid-fence-array)
[546.929211] Starting subtest: invalid-fence-array
[546.932328] Subtest invalid-fence-array: SUCCESS (0.002s)
[548.366367] [092/133] (472s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[548.457935] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[548.459842] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[548.550920] [093/133] (472s left) kms_psr (psr-cursor-plane-onoff)
[548.628572] Starting subtest: psr-cursor-plane-onoff
[548.629475] Starting dynamic subtest: eDP-1
[550.367537] Dynamic subtest eDP-1: SUCCESS (1.738s)
[550.368459] Subtest psr-cursor-plane-onoff: SUCCESS (1.740s)
[551.239185] [094/133] (469s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[551.337420] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[551.338991] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[551.431172] [095/133] (469s left) kms_busy (basic)
[551.522440] Starting subtest: basic
[551.523322] Starting dynamic subtest: flip
[552.112275] Dynamic subtest flip: SUCCESS (0.589s)
[552.120855] Starting dynamic subtest: modeset
[555.578102] Dynamic subtest modeset: SUCCESS (3.465s)
[555.578935] Subtest basic: SUCCESS (4.057s)
[556.384852] [096/133] (464s left) gem_exec_async (forked-writes)
[556.454400] Starting subtest: forked-writes
[556.455590] Starting dynamic subtest: rcs0
[556.480456] Dynamic subtest rcs0: SUCCESS (0.026s)
[556.491450] Starting dynamic subtest: bcs0
[556.505918] Dynamic subtest bcs0: SUCCESS (0.024s)
[556.514957] Starting dynamic subtest: vcs0
[556.527205] Dynamic subtest vcs0: SUCCESS (0.021s)
[556.536838] Starting dynamic subtest: vcs1
[556.551792] Dynamic subtest vcs1: SUCCESS (0.024s)
[556.561774] Starting dynamic subtest: vecs0
[556.576779] Dynamic subtest vecs0: SUCCESS (0.024s)
[556.586330] Starting dynamic subtest: ccs0
[556.598627] Dynamic subtest ccs0: SUCCESS (0.021s)
[556.599650] Subtest forked-writes: SUCCESS (0.145s)
[556.822867] [097/133] (464s left) prime_vgem (coherency-gtt)
[556.895886] Starting subtest: coherency-gtt
[556.897693] Subtest coherency-gtt: SKIP (0.000s)
[557.011732] [098/133] (464s left) kms_properties (colorop-properties-legacy)
[557.099240] Starting subtest: colorop-properties-legacy
[557.100165] Starting dynamic subtest: pipe-A-eDP-1
[557.459343] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[557.468196] Starting dynamic subtest: pipe-B-eDP-1
[558.548344] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.089s)
[558.557505] Starting dynamic subtest: pipe-C-eDP-1
[559.579046] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.028s)
[559.587850] Starting dynamic subtest: pipe-D-eDP-1
[560.619595] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.039s)
[560.620743] Subtest colorop-properties-legacy: SUCCESS (3.520s)
[561.406976] [099/133] (459s left) gem_mmap_wc (set-cache-level)
[561.463358] Subtest set-cache-level: SKIP (0.000s)
[561.526229] [100/133] (459s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[561.615830] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[568.655768] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (7.041s)
[568.766187] [101/133] (452s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[568.854192] Starting subtest: fbc-psr2-primary-mmap-cpu
[568.855046] Starting dynamic subtest: eDP-1
[568.856742] Dynamic subtest eDP-1: SKIP (0.000s)
[568.858575] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[568.927537] [102/133] (452s left) syncobj_timeline (32bits-limit)
[568.972764] Starting subtest: 32bits-limit
[568.973893] Subtest 32bits-limit: SUCCESS (0.001s)
[569.086474] [103/133] (452s left) perf_pmu (busy-accuracy-98)
[569.122570] Starting subtest: busy-accuracy-98
[569.123571] Starting dynamic subtest: rcs0
[572.166888] Dynamic subtest rcs0: SUCCESS (3.039s)
[572.178105] Starting dynamic subtest: bcs0
[575.224465] Dynamic subtest bcs0: SUCCESS (3.054s)
[575.236607] Starting dynamic subtest: vcs0
[578.270367] Dynamic subtest vcs0: SUCCESS (3.043s)
[578.283020] Starting dynamic subtest: vcs1
[581.314253] Dynamic subtest vcs1: SUCCESS (3.041s)
[581.323920] Starting dynamic subtest: vecs0
[584.353110] Dynamic subtest vecs0: SUCCESS (3.037s)
[584.362250] Starting dynamic subtest: ccs0
[587.411085] Dynamic subtest ccs0: SUCCESS (3.055s)
[587.411881] Subtest busy-accuracy-98: SUCCESS (18.284s)
[587.527011] [104/133] (433s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[587.612956] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[587.614288] Starting dynamic subtest: pipe-A
[587.634140] Dynamic subtest pipe-A: SKIP (0.019s)
[587.642432] Starting dynamic subtest: pipe-B
[587.667630] Dynamic subtest pipe-B: SKIP (0.034s)
[587.676290] Starting dynamic subtest: pipe-C
[587.709701] Dynamic subtest pipe-C: SKIP (0.033s)
[587.718089] Starting dynamic subtest: pipe-D
[587.735194] Dynamic subtest pipe-D: SKIP (0.032s)
[587.736816] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.122s)
[587.834260] [105/133] (433s left) gem_exec_whisper (basic-normal-all)
[587.900657] Starting subtest: basic-normal-all
[590.192990] Subtest basic-normal-all: SUCCESS (2.291s)
[590.388630] [106/133] (431s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[590.498831] Starting subtest: verify-pxp-execution-after-suspend-resume
[598.414703] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.918s)
[606.571593] [107/133] (422s left) gem_exec_reloc (basic-range-active)
[606.622524] Subtest basic-range-active: SKIP (0.000s)
[606.702983] [108/133] (422s left) kms_content_protection (dp-mst-type-1)
[606.839884] Starting subtest: dp-mst-type-1
[606.842094] Subtest dp-mst-type-1: SKIP (0.000s)
[606.978742] [109/133] (422s left) kms_invalid_mode (bad-htotal)
[607.062760] Starting subtest: bad-htotal
[607.063617] Starting dynamic subtest: pipe-A-eDP-1
[607.349862] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[607.359193] Starting dynamic subtest: pipe-B-eDP-1
[607.360104] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[607.369388] Starting dynamic subtest: pipe-C-eDP-1
[607.370279] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[607.379368] Starting dynamic subtest: pipe-D-eDP-1
[607.380252] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[607.381079] Subtest bad-htotal: SUCCESS (0.292s)
[608.188963] [110/133] (421s left) gem_userptr_blits (unsync-unmap-after-close)
[608.242230] Subtest unsync-unmap-after-close: SKIP (0.000s)
[608.379389] [111/133] (421s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[608.471734] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[608.473403] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[608.572429] [112/133] (421s left) gem_exec_whisper (basic-queues-priority)
[608.626669] Starting subtest: basic-queues-priority
[631.568326] Subtest basic-queues-priority: SUCCESS (22.929s)
[631.745194] [113/133] (397s left) kms_flip (2x-flip-vs-suspend)
[631.825735] Starting subtest: 2x-flip-vs-suspend
[631.829269] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[631.907523] [114/133] (397s left) gem_ctx_engines (execute-one)
[631.959730] Starting subtest: execute-one
[634.224559] Subtest execute-one: SUCCESS (2.264s)
[634.416781] [115/133] (395s left) kms_color_pipeline (plane-ctm3x4)
[634.524569] Starting subtest: plane-ctm3x4
[634.682574] Starting dynamic subtest: pipe-A-plane-0
[634.838071] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[634.848366] Starting dynamic subtest: pipe-A-plane-1
[634.971268] Dynamic subtest pipe-A-plane-1: SUCCESS (0.132s)
[634.982486] Starting dynamic subtest: pipe-A-plane-2
[635.104748] Dynamic subtest pipe-A-plane-2: SUCCESS (0.132s)
[636.341061] Starting dynamic subtest: pipe-B-plane-0
[636.476565] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[636.486693] Starting dynamic subtest: pipe-B-plane-1
[636.609896] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[636.619715] Starting dynamic subtest: pipe-B-plane-2
[636.743374] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[637.938828] Starting dynamic subtest: pipe-C-plane-0
[638.061876] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[638.073996] Starting dynamic subtest: pipe-C-plane-1
[638.183929] Dynamic subtest pipe-C-plane-1: SUCCESS (0.121s)
[638.193435] Starting dynamic subtest: pipe-C-plane-2
[638.317607] Dynamic subtest pipe-C-plane-2: SUCCESS (0.133s)
[639.470496] Starting dynamic subtest: pipe-D-plane-0
[639.594783] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[639.604285] Starting dynamic subtest: pipe-D-plane-1
[639.705829] Dynamic subtest pipe-D-plane-1: SUCCESS (0.110s)
[639.715640] Starting dynamic subtest: pipe-D-plane-2
[639.839147] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[640.124858] Subtest plane-ctm3x4: SUCCESS (5.600s)
[640.927708] [116/133] (388s left) kms_plane (plane-position-hole-dpms)
[641.014196] Starting subtest: plane-position-hole-dpms
[641.550547] Starting dynamic subtest: pipe-A-plane-1
[643.927410] Dynamic subtest pipe-A-plane-1: SUCCESS (2.377s)
[643.937369] Starting dynamic subtest: pipe-A-plane-2
[646.278634] Dynamic subtest pipe-A-plane-2: SUCCESS (2.349s)
[646.289447] Starting dynamic subtest: pipe-A-plane-3
[648.644285] Dynamic subtest pipe-A-plane-3: SUCCESS (2.366s)
[648.654332] Starting dynamic subtest: pipe-A-plane-4
[651.028956] Dynamic subtest pipe-A-plane-4: SUCCESS (2.385s)
[651.039925] Starting dynamic subtest: pipe-A-plane-5
[651.059398] Dynamic subtest pipe-A-plane-5: SUCCESS (0.028s)
[652.286598] Starting dynamic subtest: pipe-B-plane-1
[654.707552] Dynamic subtest pipe-B-plane-1: SUCCESS (2.431s)
[654.719453] Starting dynamic subtest: pipe-B-plane-2
[657.080644] Dynamic subtest pipe-B-plane-2: SUCCESS (2.371s)
[657.090233] Starting dynamic subtest: pipe-B-plane-3
[659.534614] Dynamic subtest pipe-B-plane-3: SUCCESS (2.453s)
[659.546045] Starting dynamic subtest: pipe-B-plane-4
[661.902090] Dynamic subtest pipe-B-plane-4: SUCCESS (2.367s)
[661.911707] Starting dynamic subtest: pipe-B-plane-5
[661.927043] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[661.927914] Subtest plane-position-hole-dpms: SUCCESS (20.913s)
[662.717924] [117/133] (367s left) kms_plane_cursor (primary)
[662.805400] Starting subtest: primary
[662.807232] Starting dynamic subtest: pipe-A-eDP-1-size-64
[665.434302] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.629s)
[665.461613] Starting dynamic subtest: pipe-A-eDP-1-size-128
[668.045912] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.594s)
[668.073898] Starting dynamic subtest: pipe-A-eDP-1-size-256
[670.647559] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.583s)
[670.970252] Starting dynamic subtest: pipe-B-eDP-1-size-64
[674.300245] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.340s)
[674.324538] Starting dynamic subtest: pipe-B-eDP-1-size-128
[676.898885] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.583s)
[676.926479] Starting dynamic subtest: pipe-B-eDP-1-size-256
[679.521927] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.605s)
[679.836661] Starting dynamic subtest: pipe-C-eDP-1-size-64
[682.849175] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.022s)
[682.878048] Starting dynamic subtest: pipe-C-eDP-1-size-128
[685.171643] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.303s)
[685.200413] Starting dynamic subtest: pipe-C-eDP-1-size-256
[687.482829] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.292s)
[687.800417] Starting dynamic subtest: pipe-D-eDP-1-size-64
[690.746106] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.957s)
[690.779673] Starting dynamic subtest: pipe-D-eDP-1-size-128
[693.055994] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.292s)
[693.084379] Starting dynamic subtest: pipe-D-eDP-1-size-256
[695.367997] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.292s)
[695.674060] Subtest primary: SUCCESS (32.867s)
[696.515131] [118/133] (333s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[696.608586] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[696.610616] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[696.717560] [119/133] (333s left) gen9_exec_parse (unaligned-access)
[696.774205] Subtest unaligned-access: SKIP (0.000s)
[696.838777] [120/133] (333s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[696.888729] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[696.890705] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[697.069862] [121/133] (332s left) kms_cursor_crc (cursor-random-64x64)
[697.230262] Starting subtest: cursor-random-64x64
[697.234468] Starting dynamic subtest: pipe-A-eDP-1
[700.600023] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.365s)
[700.631083] Starting dynamic subtest: pipe-D-eDP-1
[704.654034] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.032s)
[704.655363] Subtest cursor-random-64x64: SUCCESS (7.424s)
[705.497073] [122/133] (324s left) dumb_buffer (create-valid-dumb)
[705.541067] Starting subtest: create-valid-dumb
[705.541977] Subtest create-valid-dumb: SUCCESS (0.000s)
[705.625983] [123/133] (324s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[705.678530] Starting subtest: etime-multi-wait-all-submitted-signaled
[705.786667] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[705.859932] [124/133] (324s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[705.955237] Starting subtest: 4-tiled-16bpp-rotate-180
[707.235911] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.281s)
[707.367952] [125/133] (322s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[707.464080] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[707.465884] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[707.550809] [126/133] (322s left) i915_pm_rc6_residency (rc6-idle)
[707.595069] Starting subtest: rc6-idle
[707.625147] Starting dynamic subtest: gt0-rcs0
[731.905850] Dynamic subtest gt0-rcs0: SUCCESS (24.280s)
[731.918073] Starting dynamic subtest: gt0-bcs0
[756.197801] Dynamic subtest gt0-bcs0: SUCCESS (24.289s)
[756.207828] Starting dynamic subtest: gt0-ccs0
[780.570854] Dynamic subtest gt0-ccs0: SUCCESS (24.371s)
[780.584902] Starting dynamic subtest: gt1-vcs0
[786.789612] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[786.802580] Starting dynamic subtest: gt1-vecs0
[792.961768] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[792.963064] Subtest rc6-idle: SUCCESS (85.321s)
[793.003521] [127/133] (237s left) kms_pm_rpm (universal-planes-dpms)
[794.525667] Starting subtest: universal-planes-dpms
[794.527222] Starting dynamic subtest: plane-35
[802.825850] Dynamic subtest plane-35: SUCCESS (8.301s)
[802.841704] Starting dynamic subtest: plane-65
[811.124842] Dynamic subtest plane-65: SUCCESS (8.296s)
[811.138991] Starting dynamic subtest: plane-95
[819.730389] Dynamic subtest plane-95: SUCCESS (8.602s)
[819.748641] Starting dynamic subtest: plane-125
[828.046443] Dynamic subtest plane-125: SUCCESS (8.312s)
[828.061476] Starting dynamic subtest: plane-135
[836.647992] Dynamic subtest plane-135: SUCCESS (8.598s)
[836.662221] Starting dynamic subtest: plane-145
[845.288793] Dynamic subtest plane-145: SUCCESS (8.638s)
[845.291945] Subtest universal-planes-dpms: SUCCESS (50.765s)
[845.651167] [128/133] (184s left) gem_mmap_offset (bad-object)
[845.707141] Starting subtest: bad-object
[845.708227] Subtest bad-object: SUCCESS (0.001s)
[845.800930] [129/133] (184s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[845.886777] Starting subtest: fbc-modesetfrombusy
[849.601646] Subtest fbc-modesetfrombusy: SUCCESS (3.715s)
[850.778277] [130/133] (179s left) kms_properties (invalid-properties-legacy)
[850.871440] Starting subtest: invalid-properties-legacy
[850.980834] Subtest invalid-properties-legacy: SUCCESS (0.109s)
[851.064136] [131/133] (179s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[851.108278] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[851.109393] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[851.200397] [132/133] (179s left) gem_softpin (evict-single-offset)
[851.249317] Starting subtest: evict-single-offset
[880.359060] Subtest evict-single-offset: SUCCESS (29.099s)
[880.453831] [133/133] (149s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[880.592499] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[880.594215] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[880.692014] 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.