[57.450523] Initializing watchdogs
[57.450570]   /dev/watchdog0
[57.465568] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.470215] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.472278] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.475588] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[57.595941] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[57.600494] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[57.699108] [002/133] (960s left) gem_exec_schedule (smoketest)
[57.881903] Starting subtest: smoketest
[57.884257] Starting dynamic subtest: rcs0
[63.416270] Dynamic subtest rcs0: SUCCESS (5.480s)
[63.428875] Starting dynamic subtest: bcs0
[68.971387] Dynamic subtest bcs0: SUCCESS (5.502s)
[68.979323] Starting dynamic subtest: vcs0
[74.457529] Dynamic subtest vcs0: SUCCESS (5.489s)
[74.467973] Starting dynamic subtest: vcs1
[79.946421] Dynamic subtest vcs1: SUCCESS (5.492s)
[79.957882] Starting dynamic subtest: vecs0
[85.457716] Dynamic subtest vecs0: SUCCESS (5.504s)
[85.465873] Starting dynamic subtest: ccs0
[90.899477] Dynamic subtest ccs0: SUCCESS (5.500s)
[90.901607] Subtest smoketest: SUCCESS (32.970s)
[91.090345] [003/133] (926s left) syncobj_wait (single-wait-for-submit-submitted)
[91.150690] Starting subtest: single-wait-for-submit-submitted
[91.259877] Subtest single-wait-for-submit-submitted: SUCCESS (0.107s)
[91.356175] [004/133] (926s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[91.453703] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[91.458079] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[91.542654] [005/133] (926s left) sw_sync (sync_multi_producer_single_consumer)
[91.583048] Starting subtest: sync_multi_producer_single_consumer
[92.660069] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[92.727681] [006/133] (925s left) gem_exec_schedule (preempt-engines)
[92.796194] Starting subtest: preempt-engines
[92.798199] Starting dynamic subtest: rcs0
[95.046423] Dynamic subtest rcs0: SUCCESS (2.256s)
[95.056255] Starting dynamic subtest: bcs0
[97.462820] Dynamic subtest bcs0: SUCCESS (2.362s)
[97.473955] Starting dynamic subtest: vcs0
[99.717421] Dynamic subtest vcs0: SUCCESS (2.256s)
[99.727553] Starting dynamic subtest: vcs1
[101.981255] Dynamic subtest vcs1: SUCCESS (2.264s)
[101.988863] Starting dynamic subtest: vecs0
[104.231142] Dynamic subtest vecs0: SUCCESS (2.248s)
[104.238957] Starting dynamic subtest: ccs0
[106.516265] Dynamic subtest ccs0: SUCCESS (2.284s)
[106.518563] Subtest preempt-engines: SUCCESS (13.675s)
[106.757850] [007/133] (911s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[106.848587] Starting subtest: fbc-rgb565-draw-render
[111.281605] Subtest fbc-rgb565-draw-render: SUCCESS (4.436s)
[112.447673] [008/133] (905s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[112.522860] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[112.527021] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[112.590592] [009/133] (905s left) vgem_basic (unload)
[112.627556] Starting subtest: unload
[141.740569] Subtest unload: SUCCESS (29.062s)
[141.798733] [FACT vgem_basic (unload)] new: kernel.kmod_is_loaded.vgem: true
[141.804943] [010/133] (876s left) gem_create (busy-create)
[141.861589] Starting subtest: busy-create
[141.864069] Starting dynamic subtest: smem0
[175.230601] Dynamic subtest smem0: SUCCESS (33.364s)
[175.232940] Subtest busy-create: SUCCESS (33.365s)
[175.431621] [011/133] (842s left) kms_pm_rpm (system-suspend-idle)
[177.369734] Starting subtest: system-suspend-idle
[197.194272] Subtest system-suspend-idle: SUCCESS (4.434s)
[197.564626] [012/133] (820s left) gem_exec_whisper (basic-queues)
[197.658601] Starting subtest: basic-queues
[202.683325] Subtest basic-queues: SUCCESS (5.020s)
[202.867205] [013/133] (815s left) drm_read (short-buffer-nonblock)
[202.991551] Starting subtest: short-buffer-nonblock
[202.994008] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[203.110339] [014/133] (815s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[203.185821] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[209.878778] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.696s)
[210.031649] [015/133] (808s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[210.115337] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[210.117576] Starting dynamic subtest: pipe-A-eDP-1
[210.139720] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[210.150892] Starting dynamic subtest: pipe-B-eDP-1
[210.171763] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[210.180549] Starting dynamic subtest: pipe-C-eDP-1
[210.201943] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[210.210284] Starting dynamic subtest: pipe-D-eDP-1
[210.230943] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[210.235002] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[212.331201] [016/133] (806s left) sw_sync (alloc_fence)
[212.367540] Starting subtest: alloc_fence
[212.369800] Subtest alloc_fence: SUCCESS (0.000s)
[212.419800] [017/133] (806s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[212.476536] Starting subtest: multi-wait-all-for-submit-signaled
[212.478758] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[212.570411] [018/133] (806s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[212.644246] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[212.648603] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[212.741611] [019/133] (805s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[212.794527] Starting subtest: etime-single-wait-for-submit-submitted
[212.897820] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.103s)
[212.971103] [020/133] (805s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[213.067687] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[222.321284] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.252s)
[223.184213] [021/133] (795s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[223.565959] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[224.369671] [022/133] (794s left) kms_flip (flip-vs-rmfb)
[224.455446] Starting subtest: flip-vs-rmfb
[224.457457] Starting dynamic subtest: A-eDP1
[233.084138] Dynamic subtest A-eDP1: SUCCESS (8.630s)
[233.094630] Starting dynamic subtest: B-eDP1
[241.715150] Dynamic subtest B-eDP1: SUCCESS (8.629s)
[241.726294] Starting dynamic subtest: C-eDP1
[250.331500] Dynamic subtest C-eDP1: SUCCESS (8.614s)
[250.342775] Starting dynamic subtest: D-eDP1
[258.966965] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[258.971779] Subtest flip-vs-rmfb: SUCCESS (34.515s)
[260.145340] [023/133] (758s left) sysfs_heartbeat_interval (idempotent)
[260.208328] Starting subtest: idempotent
[260.210691] Starting dynamic subtest: vecs0
[260.213103] Dynamic subtest vecs0: SUCCESS (0.001s)
[260.223663] Starting dynamic subtest: ccs0
[260.225656] Dynamic subtest ccs0: SUCCESS (0.001s)
[260.233907] Starting dynamic subtest: vcs0
[260.235878] Dynamic subtest vcs0: SUCCESS (0.000s)
[260.244138] Starting dynamic subtest: bcs0
[260.246038] Dynamic subtest bcs0: SUCCESS (0.001s)
[260.254682] Starting dynamic subtest: rcs0
[260.256612] Dynamic subtest rcs0: SUCCESS (0.000s)
[260.266006] Starting dynamic subtest: vcs1
[260.268195] Dynamic subtest vcs1: SUCCESS (0.001s)
[260.270478] Subtest idempotent: SUCCESS (0.009s)
[260.319557] [024/133] (758s left) kms_psr (psr-dpms)
[260.394304] Starting subtest: psr-dpms
[260.396519] Starting dynamic subtest: eDP-1
[263.401025] Dynamic subtest eDP-1: SUCCESS (3.008s)
[263.403289] Subtest psr-dpms: SUCCESS (3.008s)
[264.250177] [025/133] (754s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[264.329518] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[264.333620] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[264.422267] [026/133] (754s left) kms_cursor_crc (cursor-alpha-transparent)
[264.501903] Starting subtest: cursor-alpha-transparent
[264.504017] Starting dynamic subtest: pipe-A-eDP-1
[265.115775] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.611s)
[265.125947] Starting dynamic subtest: pipe-D-eDP-1
[266.409610] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.293s)
[266.411513] Subtest cursor-alpha-transparent: SUCCESS (1.907s)
[267.206144] [027/133] (751s left) gen9_exec_parse (bb-chained)
[267.272469] Subtest bb-chained: SKIP (0.000s)
[267.332442] [028/133] (751s left) gem_mmap_gtt (basic-write-read)
[267.390914] Subtest basic-write-read: SKIP (0.000s)
[267.454061] [029/133] (751s left) gem_reg_read (timestamp-moving)
[267.516418] Starting subtest: timestamp-moving
[268.517314] Subtest timestamp-moving: SUCCESS (1.001s)
[268.596772] [030/133] (750s left) gem_partial_pwrite_pread (reads-uncached)
[268.662113] Subtest reads-uncached: SKIP (0.000s)
[268.752258] [031/133] (750s left) kms_plane (pixel-format-4-tiled-modifier)
[268.836168] Starting subtest: pixel-format-4-tiled-modifier
[268.861462] Starting dynamic subtest: pipe-A-plane-0
[269.505813] Dynamic subtest pipe-A-plane-0: SUCCESS (0.644s)
[269.515270] Starting dynamic subtest: pipe-A-plane-3
[269.938744] Dynamic subtest pipe-A-plane-3: SUCCESS (0.432s)
[269.947752] Starting dynamic subtest: pipe-A-plane-5
[269.951260] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[271.100328] Starting dynamic subtest: pipe-B-plane-0
[271.725848] Dynamic subtest pipe-B-plane-0: SUCCESS (0.633s)
[271.737118] Starting dynamic subtest: pipe-B-plane-3
[272.216230] Dynamic subtest pipe-B-plane-3: SUCCESS (0.488s)
[272.226111] Starting dynamic subtest: pipe-B-plane-5
[272.229897] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[272.527553] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.693s)
[273.375082] [032/133] (745s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[273.478564] Starting subtest: pr-primary-plane-update-sf-dmg-area
[273.482891] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[273.565308] [033/133] (745s left) kms_flip (plain-flip-interruptible)
[273.640577] Starting subtest: plain-flip-interruptible
[273.642935] Starting dynamic subtest: A-eDP1
[275.282670] Dynamic subtest A-eDP1: SUCCESS (1.641s)
[275.297363] Starting dynamic subtest: B-eDP1
[276.913164] Dynamic subtest B-eDP1: SUCCESS (1.628s)
[276.923342] Starting dynamic subtest: C-eDP1
[278.530199] Dynamic subtest C-eDP1: SUCCESS (1.616s)
[278.539593] Starting dynamic subtest: D-eDP1
[280.163386] Dynamic subtest D-eDP1: SUCCESS (1.632s)
[280.168924] Subtest plain-flip-interruptible: SUCCESS (6.527s)
[281.334790] [034/133] (738s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[281.396929] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[281.401390] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[281.576084] [035/133] (737s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[281.658153] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[281.660500] Starting dynamic subtest: pipe-A-eDP-1
[281.681032] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[281.693106] Starting dynamic subtest: pipe-B-eDP-1
[281.712419] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[281.721212] Starting dynamic subtest: pipe-C-eDP-1
[281.740448] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[281.749413] Starting dynamic subtest: pipe-D-eDP-1
[281.768524] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[281.770711] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[283.892481] [036/133] (735s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[283.974704] Starting subtest: 4-tiled-64bpp-rotate-270
[283.993780] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[284.095255] [037/133] (735s left) api_intel_allocator (two-level-inception)
[284.146774] Starting subtest: two-level-inception
[289.599356] Subtest two-level-inception: SUCCESS (5.399s)
[289.668195] [038/133] (729s left) syncobj_timeline (invalid-signal-bad-pad)
[289.729789] Starting subtest: invalid-signal-bad-pad
[289.732260] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[289.872282] [039/133] (729s left) drm_read (short-buffer-wakeup)
[290.006552] Starting subtest: short-buffer-wakeup
[322.218690] Subtest short-buffer-wakeup: SUCCESS (32.215s)
[322.362953] [040/133] (697s left) kms_getfb (getfb2-accept-nv12)
[322.435076] Starting subtest: getfb2-accept-nv12
[322.437350] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[322.529921] [041/133] (697s left) i915_hangman (gt-engine-error)
[322.592030] Starting subtest: gt-engine-error
[322.594262] Starting dynamic subtest: rcs0
[324.604447] Dynamic subtest rcs0: SUCCESS (2.013s)
[324.615612] Starting dynamic subtest: bcs0
[326.587612] Dynamic subtest bcs0: SUCCESS (1.981s)
[326.599388] Starting dynamic subtest: vcs0
[328.570714] Dynamic subtest vcs0: SUCCESS (1.981s)
[328.581870] Starting dynamic subtest: vcs1
[330.619495] Dynamic subtest vcs1: SUCCESS (2.046s)
[330.632492] Starting dynamic subtest: vecs0
[332.603086] Dynamic subtest vecs0: SUCCESS (1.981s)
[332.614955] Starting dynamic subtest: ccs0
[334.587202] Dynamic subtest ccs0: SUCCESS (1.982s)
[334.590066] Subtest gt-engine-error: SUCCESS (11.997s)
[334.673275] [042/133] (684s left) kms_psr (psr2-sprite-blt)
[334.764188] Starting subtest: psr2-sprite-blt
[334.766420] Starting dynamic subtest: eDP-1
[336.502623] Dynamic subtest eDP-1: SUCCESS (1.737s)
[336.505153] Subtest psr2-sprite-blt: SUCCESS (1.738s)
[337.368313] [043/133] (682s left) gem_lmem_swapping (massive)
[337.413627] Subtest massive: SKIP (0.000s)
[337.452744] [044/133] (682s left) i915_pm_rpm (gem-pread)
[338.960426] Starting subtest: gem-pread
[341.075089] Subtest gem-pread: SUCCESS (2.117s)
[341.162673] [045/133] (678s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[341.274467] Starting subtest: cursorA-vs-flipA-toggle
[352.400046] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.122s)
[353.261293] [046/133] (666s left) kms_feature_discovery (chamelium)
[353.346018] Starting subtest: chamelium
[353.350615] Subtest chamelium: SKIP (0.000s)
[353.425643] [047/133] (666s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[353.504735] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[353.507104] Starting dynamic subtest: pipe-A-eDP-1
[359.350009] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.849s)
[359.359306] Starting dynamic subtest: pipe-B-eDP-1
[365.991890] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.641s)
[365.994210] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.493s)
[366.858166] [048/133] (653s left) kms_invalid_mode (bad-vsync-end)
[366.938862] Starting subtest: bad-vsync-end
[366.941010] Starting dynamic subtest: pipe-A-eDP-1
[367.223486] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[367.234349] Starting dynamic subtest: pipe-B-eDP-1
[367.236263] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[367.245689] Starting dynamic subtest: pipe-C-eDP-1
[367.247830] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[367.257815] Starting dynamic subtest: pipe-D-eDP-1
[367.260069] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[367.262247] Subtest bad-vsync-end: SUCCESS (0.291s)
[368.086142] [049/133] (651s left) syncobj_wait (multi-wait-for-submit-signaled)
[368.133505] Starting subtest: multi-wait-for-submit-signaled
[368.135695] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[368.243378] [050/133] (651s left) kms_universal_plane (disable-primary-vs-flip)
[368.317894] Starting subtest: disable-primary-vs-flip
[368.319968] Starting dynamic subtest: pipe-A-eDP-1
[369.878382] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.562s)
[369.894147] Starting dynamic subtest: pipe-B-eDP-1
[372.112428] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.231s)
[372.122320] Starting dynamic subtest: pipe-C-eDP-1
[374.288636] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.172s)
[374.298621] Starting dynamic subtest: pipe-D-eDP-1
[376.451426] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.162s)
[376.453878] Subtest disable-primary-vs-flip: SUCCESS (8.134s)
[377.245808] [051/133] (642s left) perf_pmu (most-busy-check-all)
[377.290298] Starting subtest: most-busy-check-all
[377.292227] Starting dynamic subtest: rcs0
[378.653611] Dynamic subtest rcs0: SUCCESS (1.362s)
[378.666673] Starting dynamic subtest: bcs0
[380.637714] Dynamic subtest bcs0: SUCCESS (1.981s)
[380.650919] Starting dynamic subtest: vcs0
[382.621069] Dynamic subtest vcs0: SUCCESS (1.981s)
[382.633834] Starting dynamic subtest: vcs1
[384.605235] Dynamic subtest vcs1: SUCCESS (1.982s)
[384.618370] Starting dynamic subtest: vecs0
[386.653747] Dynamic subtest vecs0: SUCCESS (2.046s)
[386.667034] Starting dynamic subtest: ccs0
[388.637744] Dynamic subtest ccs0: SUCCESS (1.981s)
[388.640920] Subtest most-busy-check-all: SUCCESS (11.348s)
[388.739564] [052/133] (631s left) kms_content_protection (type1)
[388.864685] Starting subtest: type1
[388.869342] Subtest type1: SKIP (0.000s)
[388.988721] [053/133] (631s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[389.067772] Starting subtest: fbc-psr2-primary-mmap-gtt
[389.070195] Starting dynamic subtest: eDP-1
[389.074452] Dynamic subtest eDP-1: SKIP (0.000s)
[389.078719] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[389.141374] [054/133] (631s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[390.732121] Starting subtest: dpms-mode-unset-lpsp
[394.496063] Subtest dpms-mode-unset-lpsp: SUCCESS (3.762s)
[394.892948] [055/133] (625s left) gem_create (create-invalid-size)
[394.960619] Starting subtest: create-invalid-size
[394.962946] Subtest create-invalid-size: SUCCESS (0.000s)
[395.155880] [056/133] (625s left) gem_fence_thrash (bo-write-verify-y)
[395.199533] Starting subtest: bo-write-verify-y
[395.221503] Subtest bo-write-verify-y: SKIP (0.020s)
[395.285993] [057/133] (625s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[395.368191] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[399.487526] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.123s)
[400.656884] [058/133] (619s left) gem_exec_flush (basic-uc-rw-default)
[400.738891] Starting subtest: basic-uc-rw-default
[406.248740] Subtest basic-uc-rw-default: SUCCESS (5.511s)
[406.438313] [059/133] (613s left) kms_addfb_basic (legacy-format)
[406.493868] Starting subtest: legacy-format
[406.529563] Subtest legacy-format: SUCCESS (0.033s)
[406.659455] [060/133] (613s left) sw_sync (sync_merge)
[406.699289] Starting subtest: sync_merge
[406.701611] Subtest sync_merge: SUCCESS (0.000s)
[406.776823] [061/133] (613s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[406.852951] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[406.857236] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[406.956820] [062/133] (613s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[407.034588] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[410.630413] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.600s)
[411.849234] [063/133] (608s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[411.936653] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[411.940786] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[412.039290] [064/133] (608s left) kms_vrr (max-min)
[412.121002] Starting subtest: max-min
[412.127184] Subtest max-min: SKIP (0.000s)
[412.223907] [065/133] (608s left) gem_mmap_wc (write)
[412.282770] Subtest write: SKIP (0.000s)
[412.339347] [066/133] (608s left) gem_exec_reloc (basic-wc-gtt)
[412.389218] Subtest basic-wc-gtt: SKIP (0.000s)
[412.455326] [067/133] (608s left) gem_mmap_gtt (cpuset-basic-small-copy)
[412.505500] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[412.562178] [068/133] (608s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[412.644922] Starting subtest: psr-shrfb-scaledprimary
[418.408183] Subtest psr-shrfb-scaledprimary: SUCCESS (5.765s)
[418.524449] [069/133] (602s left) gem_mmap_gtt (medium-copy)
[418.581737] Subtest medium-copy: SKIP (0.000s)
[418.638689] [070/133] (602s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[418.683985] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[418.685901] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[418.778326] [071/133] (602s left) kms_flip (2x-flip-vs-dpms-on-nop)
[418.864168] Starting subtest: 2x-flip-vs-dpms-on-nop
[418.871334] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.008s)
[418.951092] [072/133] (602s left) kms_prop_blob (invalid-get-prop)
[419.008936] Starting subtest: invalid-get-prop
[419.010823] Subtest invalid-get-prop: SUCCESS (0.000s)
[419.117339] [073/133] (601s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[419.194683] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[419.197028] Starting dynamic subtest: pipe-A-eDP-1
[419.218819] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[419.230179] Starting dynamic subtest: pipe-B-eDP-1
[419.250912] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[419.259574] Starting dynamic subtest: pipe-C-eDP-1
[419.280383] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[419.288819] Starting dynamic subtest: pipe-D-eDP-1
[419.310072] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[419.314368] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[421.518437] [074/133] (599s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[421.599300] Starting subtest: fbc-rgb565-draw-mmap-cpu
[425.648315] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.052s)
[426.814624] [075/133] (594s left) kms_plane_lowres (tiling-yf)
[426.886007] Starting subtest: tiling-yf
[426.890560] Subtest tiling-yf: SKIP (0.000s)
[426.960052] [076/133] (594s left) kms_big_fb (linear-8bpp-rotate-90)
[427.060195] Starting subtest: linear-8bpp-rotate-90
[427.088590] Subtest linear-8bpp-rotate-90: SKIP (0.027s)
[427.204727] [077/133] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[427.283631] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[427.287997] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[427.377711] [078/133] (593s left) kms_chamelium_hpd (hdmi-hpd)
[427.741662] Subtest hdmi-hpd: SKIP (0.000s)
[428.513542] [079/133] (592s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[428.605122] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[435.708695] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.108s)
[435.810169] [080/133] (585s left) gem_exec_fence (concurrent)
[435.990547] Starting subtest: concurrent
[435.995396] Subtest concurrent: SKIP (0.000s)
[437.656627] [081/133] (583s left) syncobj_timeline (wait-all-complex)
[437.716311] Starting subtest: wait-all-complex
[437.930089] Subtest wait-all-complex: SUCCESS (0.214s)
[438.007648] [082/133] (583s left) kms_dp_aux_dev (basic)
[438.070466] Starting subtest: basic
[438.317610] Subtest basic: SUCCESS (0.246s)
[438.390356] [083/133] (583s left) syncobj_wait (wait-all-interrupted)
[438.440827] Starting subtest: wait-all-interrupted
[438.544994] Subtest wait-all-interrupted: SUCCESS (0.104s)
[438.619119] [084/133] (582s left) gem_exec_reloc (basic-write-cpu)
[438.676341] Subtest basic-write-cpu: SKIP (0.000s)
[438.742348] [085/133] (582s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[438.831872] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[439.455885] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.625s)
[439.616180] [086/133] (581s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[439.769448] Starting subtest: cursor-rapid-movement-64x64
[439.771599] Starting dynamic subtest: pipe-A-eDP-1
[440.326918] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[440.360158] Starting dynamic subtest: pipe-D-eDP-1
[441.567117] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.215s)
[441.568941] Subtest cursor-rapid-movement-64x64: SUCCESS (1.799s)
[442.415362] [087/133] (579s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[442.498319] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[442.502716] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[442.617140] [088/133] (579s left) kms_atomic_transition (plane-all-transition-nonblocking)
[442.688062] Starting subtest: plane-all-transition-nonblocking
[442.690338] Starting dynamic subtest: pipe-A-eDP-1
[445.182621] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[445.501688] Starting dynamic subtest: pipe-B-eDP-1
[448.745067] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.252s)
[449.047929] Subtest plane-all-transition-nonblocking: SUCCESS (6.359s)
[449.876435] [089/133] (571s left) kms_color (ctm-0-75)
[449.960825] Starting subtest: ctm-0-75
[449.962812] Starting dynamic subtest: pipe-A-eDP-1
[454.841256] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.881s)
[454.851232] Starting dynamic subtest: pipe-B-eDP-1
[460.526480] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.683s)
[460.545376] Starting dynamic subtest: pipe-C-eDP-1
[465.836248] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.307s)
[465.846569] Starting dynamic subtest: pipe-D-eDP-1
[471.131152] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.294s)
[471.133575] Subtest ctm-0-75: SUCCESS (21.169s)
[471.964912] [090/133] (549s left) gem_mmap_gtt (isolation)
[472.030768] Subtest isolation: SKIP (0.000s)
[472.098253] [091/133] (549s left) kms_cursor_edge_walk (128x128-left-edge)
[472.184418] Starting subtest: 128x128-left-edge
[472.186728] Starting dynamic subtest: pipe-A-eDP-1
[475.632643] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.448s)
[475.642825] Starting dynamic subtest: pipe-D-eDP-1
[478.750321] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[478.752705] Subtest 128x128-left-edge: SUCCESS (6.568s)
[479.572196] [092/133] (542s left) kms_vblank (crtc-id)
[479.658747] Starting subtest: crtc-id
[479.660886] Starting dynamic subtest: pipe-A-eDP-1
[480.107545] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.449s)
[480.122238] Starting dynamic subtest: pipe-D-eDP-1
[481.195767] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.084s)
[481.197767] Subtest crtc-id: SUCCESS (1.537s)
[482.030454] [093/133] (539s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[482.106628] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[482.111094] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[482.197012] [094/133] (539s left) gem_mmap_gtt (basic-copy)
[482.250582] Subtest basic-copy: SKIP (0.000s)
[482.311863] [095/133] (539s left) gem_wait (await)
[482.371936] Starting subtest: await
[482.374202] Starting dynamic subtest: all-engines
[483.476208] Dynamic subtest all-engines: SUCCESS (1.104s)
[483.497669] Starting dynamic subtest: rcs0
[484.540385] Dynamic subtest rcs0: SUCCESS (1.061s)
[484.562775] Starting dynamic subtest: bcs0
[485.602980] Dynamic subtest bcs0: SUCCESS (1.060s)
[485.621232] Starting dynamic subtest: vcs0
[486.657925] Dynamic subtest vcs0: SUCCESS (1.053s)
[486.676896] Starting dynamic subtest: vcs1
[487.718287] Dynamic subtest vcs1: SUCCESS (1.058s)
[487.736360] Starting dynamic subtest: vecs0
[488.772085] Dynamic subtest vecs0: SUCCESS (1.052s)
[488.790228] Starting dynamic subtest: ccs0
[489.830404] Dynamic subtest ccs0: SUCCESS (1.056s)
[489.832897] Subtest await: SUCCESS (7.458s)
[490.025449] [096/133] (531s left) gem_pxp (hw-rejects-pxp-context)
[490.147210] Starting subtest: hw-rejects-pxp-context
[490.151891] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[490.256046] [097/133] (531s left) gem_spin_batch (resubmit-new)
[490.323490] Starting subtest: resubmit-new
[490.325800] Starting dynamic subtest: rcs0
[490.332698] Dynamic subtest rcs0: SUCCESS (0.012s)
[490.345879] Starting dynamic subtest: bcs0
[490.348305] Dynamic subtest bcs0: SUCCESS (0.006s)
[490.358208] Starting dynamic subtest: vcs0
[490.360072] Dynamic subtest vcs0: SUCCESS (0.006s)
[490.370134] Starting dynamic subtest: vcs1
[490.372342] Dynamic subtest vcs1: SUCCESS (0.007s)
[490.381892] Starting dynamic subtest: vecs0
[490.384034] Dynamic subtest vecs0: SUCCESS (0.007s)
[490.393435] Starting dynamic subtest: ccs0
[490.395630] Dynamic subtest ccs0: SUCCESS (0.006s)
[490.397809] Subtest resubmit-new: SUCCESS (0.049s)
[491.659309] [098/133] (530s left) i915_drm_fdinfo (memory-info-idle)
[491.701032] Starting subtest: memory-info-idle
[491.703407] Starting dynamic subtest: smem0
[491.758178] Dynamic subtest smem0: SUCCESS (0.057s)
[491.760903] Subtest memory-info-idle: SUCCESS (0.058s)
[491.833080] [099/133] (530s left) gem_ctx_sseu (engines)
[491.901193] Subtest engines: SKIP (0.000s)
[491.957275] [100/133] (530s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[492.036437] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[492.038380] Starting dynamic subtest: pipe-A-eDP-1
[492.458004] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.394s)
[492.467613] Starting dynamic subtest: pipe-B-eDP-1
[493.832270] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.369s)
[493.844328] Starting dynamic subtest: pipe-C-eDP-1
[495.233224] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.404s)
[495.244377] Starting dynamic subtest: pipe-D-eDP-1
[496.612880] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.378s)
[496.615173] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.550s)
[497.770329] [101/133] (524s left) gem_bad_reloc (negative-reloc-lut)
[497.838141] Subtest negative-reloc-lut: SKIP (0.000s)
[497.906603] [102/133] (524s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[497.985261] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[497.989414] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[498.075917] [103/133] (524s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[498.167538] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[498.171663] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[498.264456] [104/133] (524s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[498.339670] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[498.341968] Starting dynamic subtest: pipe-A
[498.363573] Dynamic subtest pipe-A: SKIP (0.018s)
[498.373685] Starting dynamic subtest: pipe-B
[498.393642] Dynamic subtest pipe-B: SKIP (0.027s)
[498.403511] Starting dynamic subtest: pipe-C
[498.421563] Dynamic subtest pipe-C: SKIP (0.028s)
[498.431065] Starting dynamic subtest: pipe-D
[498.448274] Dynamic subtest pipe-D: SKIP (0.027s)
[498.452044] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.104s)
[498.546825] [105/133] (523s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[498.622165] Starting subtest: psr-rgb101010-draw-blt
[505.913357] Subtest psr-rgb101010-draw-blt: SUCCESS (7.293s)
[506.050692] [106/133] (516s left) gem_ctx_persistence (smoketest)
[506.118613] Starting subtest: smoketest
[546.555411] Subtest smoketest: SUCCESS (40.432s)
[546.648126] [107/133] (475s left) gem_exec_balancer (nop)
[546.718392] Starting subtest: nop
[593.406550] Subtest nop: SUCCESS (46.685s)
[593.715677] [108/133] (428s left) kms_psr (fbc-psr2-sprite-plane-move)
[593.785117] Starting subtest: fbc-psr2-sprite-plane-move
[593.787380] Starting dynamic subtest: eDP-1
[593.791678] Dynamic subtest eDP-1: SKIP (0.000s)
[593.795973] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[593.861527] [109/133] (428s left) gem_madvise (dontneed-before-mmap)
[593.903473] Starting subtest: dontneed-before-mmap
[593.919918] Subtest dontneed-before-mmap: SUCCESS (0.017s)
[594.016799] [110/133] (428s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[594.094801] Starting subtest: fbcpsr-shrfb-scaledprimary
[599.849806] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.758s)
[599.986016] [111/133] (422s left) api_intel_bb (reset-flags)
[600.034420] Starting subtest: reset-flags
[600.040212] Subtest reset-flags: SUCCESS (0.007s)
[600.174134] [112/133] (422s left) gem_exec_fence (syncobj-invalid-wait)
[600.594670] Starting subtest: syncobj-invalid-wait
[600.597245] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[601.695287] [113/133] (420s left) kms_vblank (ts-continuation-idle)
[601.768602] Starting subtest: ts-continuation-idle
[601.770536] Starting dynamic subtest: pipe-A-eDP-1
[602.171214] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.403s)
[602.188742] Starting dynamic subtest: pipe-D-eDP-1
[603.270708] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.098s)
[603.272893] Subtest ts-continuation-idle: SUCCESS (1.504s)
[604.148764] [114/133] (418s left) gem_pread (self)
[604.215570] Subtest self: SKIP (0.000s)
[604.273709] [115/133] (418s left) gem_exec_fence (syncobj-timeline-wait)
[604.790201] Starting subtest: syncobj-timeline-wait
[606.643764] Subtest syncobj-timeline-wait: SUCCESS (1.853s)
[606.884119] [116/133] (415s left) gem_tiled_wb
[607.009174] [117/133] (415s left) prime_vgem (basic-fence-blt)
[607.085189] Starting subtest: basic-fence-blt
[607.159193] Subtest basic-fence-blt: SUCCESS (0.076s)
[607.286087] [118/133] (415s left) kms_chamelium_color (ctm-0-50)
[607.378906] Subtest ctm-0-50: SKIP (0.000s)
[607.425874] [119/133] (415s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[607.518783] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[614.241574] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.727s)
[614.398190] [120/133] (408s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[614.486254] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[614.490381] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[614.601981] [121/133] (408s left) gem_readwrite (write-bad-handle)
[614.662824] Subtest write-bad-handle: SKIP (0.000s)
[614.719238] [122/133] (408s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[614.767849] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[614.873858] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[614.961306] [123/133] (408s left) kms_pipe_crc_basic (hang-read-crc)
[615.035949] Starting subtest: hang-read-crc
[615.038114] Starting dynamic subtest: pipe-A-eDP-1
[617.126554] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.089s)
[617.137555] Starting dynamic subtest: pipe-B-eDP-1
[619.786817] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.659s)
[619.798010] Starting dynamic subtest: pipe-C-eDP-1
[622.405165] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.618s)
[622.416683] Starting dynamic subtest: pipe-D-eDP-1
[625.058124] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.650s)
[625.060305] Subtest hang-read-crc: SUCCESS (10.021s)
[625.896314] [124/133] (397s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[625.982782] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[625.984922] Starting dynamic subtest: pipe-A
[626.007041] Dynamic subtest pipe-A: SKIP (0.020s)
[626.017008] Starting dynamic subtest: pipe-B
[626.036477] Dynamic subtest pipe-B: SKIP (0.029s)
[626.046592] Starting dynamic subtest: pipe-C
[626.066390] Dynamic subtest pipe-C: SKIP (0.029s)
[626.076073] Starting dynamic subtest: pipe-D
[626.095609] Dynamic subtest pipe-D: SKIP (0.029s)
[626.099694] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.109s)
[626.204447] [125/133] (396s left) sw_sync (alloc_merge_fence)
[626.239304] Starting subtest: alloc_merge_fence
[626.241170] Subtest alloc_merge_fence: SUCCESS (0.000s)
[626.289033] [126/133] (396s left) gem_eio (in-flight-contexts-1us)
[626.494681] Starting subtest: in-flight-contexts-1us
[637.612801] Subtest in-flight-contexts-1us: SUCCESS (11.115s)
[637.757148] [127/133] (385s left) i915_selftest (mock)
[642.244041] Starting subtest: mock
[642.246547] Starting dynamic subtest: sanitycheck
[642.905237] Dynamic subtest sanitycheck: SUCCESS (0.664s)
[642.918117] Starting dynamic subtest: shmem
[643.583065] Dynamic subtest shmem: SUCCESS (0.675s)
[643.592829] Starting dynamic subtest: fence
[651.730709] Dynamic subtest fence: SUCCESS (8.144s)
[651.741997] Starting dynamic subtest: scatterlist
[653.406861] Dynamic subtest scatterlist: SUCCESS (1.673s)
[653.418041] Starting dynamic subtest: syncmap
[656.579634] Dynamic subtest syncmap: SUCCESS (3.169s)
[656.590410] Starting dynamic subtest: uncore
[657.227256] Dynamic subtest uncore: SUCCESS (0.647s)
[657.237013] Starting dynamic subtest: ring
[657.895984] Dynamic subtest ring: SUCCESS (0.667s)
[657.905436] Starting dynamic subtest: engine
[658.547015] Dynamic subtest engine: SUCCESS (0.649s)
[658.557251] Starting dynamic subtest: timelines
[662.501474] Dynamic subtest timelines: SUCCESS (3.951s)
[662.516574] Starting dynamic subtest: requests
[666.656728] Dynamic subtest requests: SUCCESS (4.153s)
[666.667356] Starting dynamic subtest: objects
[667.394765] Dynamic subtest objects: SUCCESS (0.738s)
[667.408186] Starting dynamic subtest: phys
[668.137927] Dynamic subtest phys: SUCCESS (0.740s)
[668.148321] Starting dynamic subtest: dmabuf
[668.921277] Dynamic subtest dmabuf: SUCCESS (0.782s)
[668.930613] Starting dynamic subtest: vma
[674.518890] Dynamic subtest vma: SUCCESS (5.594s)
[674.531386] Starting dynamic subtest: evict
[675.912186] Dynamic subtest evict: SUCCESS (1.391s)
[675.924371] Starting dynamic subtest: gtt
[679.457857] Dynamic subtest gtt: SUCCESS (3.543s)
[679.468619] Starting dynamic subtest: hugepages
[680.332799] Dynamic subtest hugepages: SUCCESS (0.874s)
[680.343304] Starting dynamic subtest: memory_region
[682.851273] Dynamic subtest memory_region: SUCCESS (2.516s)
[682.854011] Subtest mock: SUCCESS (40.609s)
[682.881787] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[682.882484] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[682.885686] [128/133] (340s left) kms_psr (pr-sprite-render)
[684.321937] Starting subtest: pr-sprite-render
[684.326438] Subtest pr-sprite-render: SKIP (0.000s)
[684.459207] [FACT kms_psr (pr-sprite-render)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[684.460287] [FACT kms_psr (pr-sprite-render)] new: kernel.kmod_is_loaded.i915: true
[684.463936] [129/133] (338s left) kms_atomic (plane-cursor-legacy)
[684.564259] Starting subtest: plane-cursor-legacy
[684.566491] Starting dynamic subtest: pipe-A-eDP-1
[684.998085] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.431s)
[685.000409] Subtest plane-cursor-legacy: SUCCESS (0.434s)
[685.837379] [130/133] (337s left) kms_lease (invalid-create-leases)
[685.912935] Starting subtest: invalid-create-leases
[685.918157] Subtest invalid-create-leases: SUCCESS (0.005s)
[686.033026] [131/133] (337s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[686.118982] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[686.123190] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[686.230002] [132/133] (337s left) gem_ctx_persistence (engines-queued)
[686.289907] Starting subtest: engines-queued
[686.292114] Starting dynamic subtest: rcs0
[686.362605] Dynamic subtest rcs0: SUCCESS (0.072s)
[686.374347] Starting dynamic subtest: bcs0
[686.389126] Dynamic subtest bcs0: SUCCESS (0.025s)
[686.397589] Starting dynamic subtest: vcs0
[687.615491] Dynamic subtest vcs0: SUCCESS (1.223s)
[687.627876] Starting dynamic subtest: vcs1
[689.596565] Dynamic subtest vcs1: SUCCESS (1.977s)
[689.609734] Starting dynamic subtest: vecs0
[691.578899] Dynamic subtest vecs0: SUCCESS (1.980s)
[691.590766] Starting dynamic subtest: ccs0
[691.756473] Dynamic subtest ccs0: SUCCESS (0.178s)
[691.758910] Subtest engines-queued: SUCCESS (5.467s)
[691.900647] [133/133] (331s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[692.002223] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[692.006592] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[692.103213] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: sw_sync
results: parsing output: 5/ for test: gem_exec_schedule
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: vgem_basic
results: parsing output: 9/ for test: gem_create
results: parsing output: 10/ for test: kms_pm_rpm
results: parsing output: 11/ for test: gem_exec_whisper
results: parsing output: 12/ for test: drm_read
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: sw_sync
results: parsing output: 16/ for test: syncobj_wait
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_chamelium_edid
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: sysfs_heartbeat_interval
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: gen9_exec_parse
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: gem_reg_read
results: parsing output: 29/ for test: gem_partial_pwrite_pread
results: parsing output: 30/ for test: kms_plane
results: parsing output: 31/ for test: kms_psr2_sf
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_render_copy
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: api_intel_allocator
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: drm_read
results: parsing output: 39/ for test: kms_getfb
results: parsing output: 40/ for test: i915_hangman
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: gem_lmem_swapping
results: parsing output: 43/ for test: i915_pm_rpm
results: parsing output: 44/ for test: kms_cursor_legacy
results: parsing output: 45/ for test: kms_feature_discovery
results: parsing output: 46/ for test: kms_psr2_sf
results: parsing output: 47/ for test: kms_invalid_mode
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: kms_universal_plane
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_content_protection
results: parsing output: 52/ for test: kms_psr
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: gem_create
results: parsing output: 55/ for test: gem_fence_thrash
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_flush
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: sw_sync
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_vrr
results: parsing output: 64/ for test: gem_mmap_wc
results: parsing output: 65/ for test: gem_exec_reloc
results: parsing output: 66/ for test: gem_mmap_gtt
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_prop_blob
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_plane_lowres
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_chamelium_hpd
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_fence
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_dp_aux_dev
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_cursor_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_atomic_transition
results: parsing output: 88/ for test: kms_color
results: parsing output: 89/ for test: gem_mmap_gtt
results: parsing output: 90/ for test: kms_cursor_edge_walk
results: parsing output: 91/ for test: kms_vblank
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_mmap_gtt
results: parsing output: 94/ for test: gem_wait
results: parsing output: 95/ for test: gem_pxp
results: parsing output: 96/ for test: gem_spin_batch
results: parsing output: 97/ for test: i915_drm_fdinfo
results: parsing output: 98/ for test: gem_ctx_sseu
results: parsing output: 99/ for test: kms_ccs
results: parsing output: 100/ for test: gem_bad_reloc
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_ctx_persistence
results: parsing output: 106/ for test: gem_exec_balancer
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_madvise
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: api_intel_bb
results: parsing output: 111/ for test: gem_exec_fence
results: parsing output: 112/ for test: kms_vblank
results: parsing output: 113/ for test: gem_pread
results: parsing output: 114/ for test: gem_exec_fence
results: parsing output: 115/ for test: gem_tiled_wb
results: parsing output: 116/ for test: prime_vgem
results: parsing output: 117/ for test: kms_chamelium_color
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_readwrite
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_pipe_crc_basic
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: sw_sync
results: parsing output: 125/ for test: gem_eio
results: parsing output: 126/ for test: i915_selftest
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_atomic
results: parsing output: 129/ for test: kms_lease
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_ctx_persistence
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.