[60.590187] Initializing watchdogs
[60.590235]   /dev/watchdog0
[60.605995] [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]
[60.610366] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.612590] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.616204] [001/133] (960s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[60.735839] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[60.738256] Starting dynamic subtest: pipe-A-eDP-1
[61.688554] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.942s)
[61.695859] Starting dynamic subtest: pipe-B-eDP-1
[63.320997] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.623s)
[63.330325] Starting dynamic subtest: pipe-C-eDP-1
[64.867699] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.544s)
[64.875944] Starting dynamic subtest: pipe-D-eDP-1
[66.373080] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.501s)
[66.375477] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.615s)
[67.539665] [002/133] (953s left) kms_psr (fbc-pr-sprite-plane-move)
[67.636827] Starting subtest: fbc-pr-sprite-plane-move
[67.640862] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[67.708240] [003/133] (953s left) gem_eio (in-flight-contexts-immediate)
[67.866893] Starting subtest: in-flight-contexts-immediate
[78.321111] Subtest in-flight-contexts-immediate: SUCCESS (10.447s)
[78.482767] [004/133] (942s left) kms_async_flips (async-flip-suspend-resume)
[78.584259] Starting subtest: async-flip-suspend-resume
[78.956372] Starting dynamic subtest: pipe-A-eDP-1
[96.966802] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.423s)
[97.335413] Starting dynamic subtest: pipe-B-eDP-1
[115.950079] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.264s)
[116.342714] Starting dynamic subtest: pipe-C-eDP-1
[134.966312] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.274s)
[135.335021] Starting dynamic subtest: pipe-D-eDP-1
[153.965258] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.285s)
[153.967481] Subtest async-flip-suspend-resume: SUCCESS (14.732s)
[161.152306] [005/133] (866s left) kms_flip (modeset-vs-vblank-race)
[161.253086] Starting subtest: modeset-vs-vblank-race
[161.255153] Starting dynamic subtest: A-eDP1
[165.995705] Dynamic subtest A-eDP1: SUCCESS (4.711s)
[166.008296] Starting dynamic subtest: B-eDP1
[170.752525] Dynamic subtest B-eDP1: SUCCESS (4.731s)
[170.761997] Starting dynamic subtest: C-eDP1
[175.294347] Dynamic subtest C-eDP1: SUCCESS (4.526s)
[175.305809] Starting dynamic subtest: D-eDP1
[179.762143] Dynamic subtest D-eDP1: SUCCESS (4.459s)
[179.764550] Subtest modeset-vs-vblank-race: SUCCESS (18.436s)
[180.932349] [006/133] (846s left) kms_psr (pr-cursor-plane-move)
[181.040473] Starting subtest: pr-cursor-plane-move
[181.044987] Subtest pr-cursor-plane-move: SKIP (0.000s)
[181.111782] [007/133] (846s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[181.197433] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[188.642123] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.441s)
[188.790079] [008/133] (838s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[188.888365] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[188.892559] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[188.992546] [009/133] (838s left) kms_atomic_transition (modeset-transition-nonblocking)
[189.084780] Starting subtest: modeset-transition-nonblocking
[189.087014] Starting dynamic subtest: 1x-outputs
[199.967820] Dynamic subtest 1x-outputs: SUCCESS (10.875s)
[199.970335] Subtest modeset-transition-nonblocking: SUCCESS (10.877s)
[200.834710] [010/133] (826s left) i915_power (sanity)
[200.884041] Starting subtest: sanity
[204.929576] Subtest sanity: SUCCESS (4.037s)
[205.078106] [011/133] (822s left) gem_exec_whisper (basic-queues-all)
[205.138159] Starting subtest: basic-queues-all
[218.921532] Subtest basic-queues-all: SUCCESS (13.770s)
[219.098636] [012/133] (808s left) drm_read (empty-nonblock)
[219.238652] Starting subtest: empty-nonblock
[219.241578] Subtest empty-nonblock: SUCCESS (0.000s)
[219.380912] [013/133] (808s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[219.478628] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[219.482856] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[219.549201] [014/133] (808s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[219.641851] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[219.646259] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[219.714138] [015/133] (807s left) kms_psr (psr-sprite-plane-move)
[219.804575] Starting subtest: psr-sprite-plane-move
[219.806723] Starting dynamic subtest: eDP-1
[221.579520] Dynamic subtest eDP-1: SUCCESS (1.774s)
[221.581760] Subtest psr-sprite-plane-move: SUCCESS (1.775s)
[222.407083] [016/133] (805s left) gem_exec_create (legacy)
[222.475639] Starting subtest: legacy
[222.478118] Starting dynamic subtest: smem
[224.566527] Dynamic subtest smem: SUCCESS (2.089s)
[224.569336] Subtest legacy: SUCCESS (2.091s)
[224.761855] [017/133] (803s left) perf (blocking)
[224.890357] Starting subtest: blocking
[224.892327] Starting dynamic subtest: 0-rcs0
[234.917529] Dynamic subtest 0-rcs0: SUCCESS (10.022s)
[234.928695] Starting dynamic subtest: 1-vcs0
[244.944025] Dynamic subtest 1-vcs0: SUCCESS (10.021s)
[244.946350] Subtest blocking: SUCCESS (20.044s)
[244.993344] [018/133] (782s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[245.090382] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[245.094888] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[245.194437] [019/133] (782s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[245.290154] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[245.292113] Starting dynamic subtest: pipe-A-eDP-1
[246.034229] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[246.044946] Starting dynamic subtest: pipe-B-eDP-1
[247.843052] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.807s)
[247.854681] Starting dynamic subtest: pipe-C-eDP-1
[249.680434] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.835s)
[249.690685] Starting dynamic subtest: pipe-D-eDP-1
[251.514771] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.832s)
[251.516948] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.215s)
[252.641049] [020/133] (775s left) kms_lease (lease-invalid-crtc)
[252.739283] Starting subtest: lease-invalid-crtc
[252.741819] Starting dynamic subtest: pipe-A-eDP-1
[252.744234] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[252.755894] Starting dynamic subtest: pipe-B-eDP-1
[252.758030] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[252.767692] Starting dynamic subtest: pipe-C-eDP-1
[252.769547] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[252.778964] Starting dynamic subtest: pipe-D-eDP-1
[252.780793] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[252.782629] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[252.818482] [021/133] (775s left) kms_content_protection (atomic-hdcp14)
[252.954556] Starting subtest: atomic-hdcp14
[252.959095] Subtest atomic-hdcp14: SKIP (0.000s)
[253.072393] [022/133] (774s left) gem_ctx_sseu (invalid-args)
[253.129067] Subtest invalid-args: SKIP (0.000s)
[253.193288] [023/133] (774s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[253.253627] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[253.356615] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[253.432482] [024/133] (774s left) gem_lmem_swapping (parallel-random-engines)
[253.471830] Subtest parallel-random-engines: SKIP (0.000s)
[253.517694] [025/133] (774s left) gem_softpin (evict-prime)
[253.574964] Starting subtest: evict-prime
[253.577267] Starting dynamic subtest: rcs0
[254.729321] Dynamic subtest rcs0: SUCCESS (1.154s)
[254.742572] Starting dynamic subtest: bcs0
[255.867154] Dynamic subtest bcs0: SUCCESS (1.137s)
[255.880734] Starting dynamic subtest: vcs0
[258.953368] Dynamic subtest vcs0: SUCCESS (3.084s)
[258.967972] Starting dynamic subtest: vcs1
[260.995072] Dynamic subtest vcs1: SUCCESS (2.041s)
[261.014531] Starting dynamic subtest: vecs0
[262.987461] Dynamic subtest vecs0: SUCCESS (1.991s)
[263.000073] Starting dynamic subtest: ccs0
[264.113155] Dynamic subtest ccs0: SUCCESS (1.126s)
[264.125764] Starting dynamic subtest: all
[265.933616] Dynamic subtest all: SUCCESS (1.819s)
[265.936022] Subtest evict-prime: SUCCESS (12.363s)
[266.026194] [026/133] (762s left) kms_plane_lowres (tiling-none)
[266.119801] Starting subtest: tiling-none
[266.122149] Starting dynamic subtest: pipe-A-eDP-1
[266.126867] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[266.424918] Starting dynamic subtest: pipe-B-eDP-1
[266.429912] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[266.439826] Starting dynamic subtest: pipe-C-eDP-1
[266.444225] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[266.454157] Starting dynamic subtest: pipe-D-eDP-1
[266.458405] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[266.462862] Subtest tiling-none: SKIP (0.302s)
[267.259564] [027/133] (760s left) kms_draw_crc (draw-method-mmap-cpu)
[267.350731] Starting subtest: draw-method-mmap-cpu
[267.352628] Starting dynamic subtest: xrgb8888-untiled
[267.615745] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[267.625846] Starting dynamic subtest: rgb565-untiled
[267.849011] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[267.859861] Starting dynamic subtest: xrgb2101010-untiled
[268.071160] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[268.081946] Starting dynamic subtest: xbgr16161616f-untiled
[268.304064] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[268.313508] Starting dynamic subtest: xrgb8888-xtiled
[268.436883] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[268.446791] Starting dynamic subtest: rgb565-xtiled
[268.570390] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[268.582157] Starting dynamic subtest: xrgb2101010-xtiled
[268.692638] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[268.703933] Starting dynamic subtest: xbgr16161616f-xtiled
[268.826156] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[268.836760] Starting dynamic subtest: xrgb8888-4tiled
[268.970562] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[268.981177] Starting dynamic subtest: rgb565-4tiled
[269.114823] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[269.124662] Starting dynamic subtest: xrgb2101010-4tiled
[269.259401] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.144s)
[269.269857] Starting dynamic subtest: xbgr16161616f-4tiled
[269.403604] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[269.405540] Subtest draw-method-mmap-cpu: SUCCESS (2.056s)
[269.508717] [028/133] (758s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[269.562374] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[269.619595] [029/133] (758s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[269.713311] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[269.715145] Starting dynamic subtest: pipe-A-eDP-1
[269.811098] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[269.822624] Starting dynamic subtest: pipe-B-eDP-1
[270.948248] Dynamic subtest pipe-B-eDP-1: SKIP (1.134s)
[270.953013] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.237s)
[272.133316] [030/133] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[272.223968] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[272.228337] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[272.319908] [031/133] (756s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[272.425436] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[272.429793] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[272.536805] [032/133] (755s left) kms_rotation_crc (primary-rotation-180)
[272.634702] Starting subtest: primary-rotation-180
[279.673638] Subtest primary-rotation-180: SUCCESS (7.042s)
[280.893541] [033/133] (747s left) kms_pm_rpm (universal-planes-dpms)
[283.124855] Starting subtest: universal-planes-dpms
[283.127257] Starting dynamic subtest: plane-35
[291.728018] Dynamic subtest plane-35: SUCCESS (8.606s)
[291.741525] Starting dynamic subtest: plane-65
[300.131909] Dynamic subtest plane-65: SUCCESS (8.399s)
[300.145145] Starting dynamic subtest: plane-95
[308.741667] Dynamic subtest plane-95: SUCCESS (8.606s)
[308.755386] Starting dynamic subtest: plane-125
[317.138565] Dynamic subtest plane-125: SUCCESS (8.392s)
[317.151755] Starting dynamic subtest: plane-135
[325.439951] Dynamic subtest plane-135: SUCCESS (8.296s)
[325.452661] Starting dynamic subtest: plane-145
[333.844364] Dynamic subtest plane-145: SUCCESS (8.399s)
[333.847499] Subtest universal-planes-dpms: SUCCESS (50.714s)
[334.221298] [034/133] (694s left) kms_pm_lpsp (kms-lpsp)
[335.318992] Starting subtest: kms-lpsp
[335.321160] Starting dynamic subtest: pipe-A-eDP-1
[335.471319] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.150s)
[335.780845] Subtest kms-lpsp: SUCCESS (0.461s)
[336.656332] [035/133] (691s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[337.039229] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[337.869438] [036/133] (690s left) api_intel_allocator (two-level-inception-interruptible)
[337.920243] Starting subtest: two-level-inception-interruptible
[343.329904] Subtest two-level-inception-interruptible: SUCCESS (5.408s)
[343.453996] [037/133] (685s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[343.559278] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[343.563268] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[343.675050] [038/133] (684s left) gem_userptr_blits (huge-split)
[343.750286] Starting subtest: huge-split
[343.755428] Subtest huge-split: SUCCESS (0.007s)
[343.893372] [039/133] (684s left) gem_eio (in-flight-10ms)
[344.121567] Starting subtest: in-flight-10ms
[352.857337] Subtest in-flight-10ms: SUCCESS (8.732s)
[353.019663] [FACT gem_eio (in-flight-10ms)] new: kernel.kmod_is_loaded.vgem: true
[353.022981] [040/133] (675s left) perf_pmu (pmu-read)
[353.069986] Starting subtest: pmu-read
[353.075302] Subtest pmu-read: SUCCESS (0.003s)
[353.175277] [041/133] (675s left) kms_properties (plane-properties-atomic)
[353.261606] Starting subtest: plane-properties-atomic
[353.263901] Starting dynamic subtest: pipe-A-eDP-1
[353.809281] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.547s)
[353.822495] Starting dynamic subtest: pipe-B-eDP-1
[355.064791] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.254s)
[355.075785] Starting dynamic subtest: pipe-C-eDP-1
[356.303405] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.236s)
[356.316421] Starting dynamic subtest: pipe-D-eDP-1
[357.533159] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[357.535415] Subtest plane-properties-atomic: SUCCESS (4.269s)
[358.333985] [042/133] (670s left) gem_ctx_exec (basic-invalid-context)
[358.389840] Starting subtest: basic-invalid-context
[358.394674] Subtest basic-invalid-context: SUCCESS (0.005s)
[358.532558] [043/133] (670s left) gem_exec_params (rel-constants-invalid)
[358.589990] Starting subtest: rel-constants-invalid
[358.592326] Subtest rel-constants-invalid: SUCCESS (0.000s)
[358.689969] [044/133] (670s left) syncobj_wait (reset-multiple-signaled)
[358.738935] Starting subtest: reset-multiple-signaled
[358.741285] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[358.825224] [045/133] (670s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[358.919406] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[358.923710] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[359.021184] [046/133] (669s left) gem_ctx_persistence (legacy-engines-mixed)
[359.075383] Starting subtest: legacy-engines-mixed
[359.077548] Starting dynamic subtest: render
[359.145204] Dynamic subtest render: SUCCESS (0.071s)
[359.157459] Starting dynamic subtest: bsd1
[359.194997] Dynamic subtest bsd1: SUCCESS (0.048s)
[359.208471] Starting dynamic subtest: bsd2
[359.253008] Dynamic subtest bsd2: SUCCESS (0.055s)
[359.266370] Starting dynamic subtest: blt
[359.314060] Dynamic subtest blt: SUCCESS (0.058s)
[359.333175] Starting dynamic subtest: vebox
[359.375068] Dynamic subtest vebox: SUCCESS (0.058s)
[359.377293] Subtest legacy-engines-mixed: SUCCESS (0.303s)
[359.460099] [047/133] (669s left) gem_pread (display)
[359.508379] Subtest display: SKIP (0.000s)
[359.564539] [048/133] (669s left) fbdev (unaligned-write)
[359.606186] Starting subtest: unaligned-write
[359.708614] Subtest unaligned-write: SUCCESS (0.100s)
[359.774573] [049/133] (669s left) syncobj_timeline (device-signal-unordered)
[359.820971] Starting subtest: device-signal-unordered
[359.823253] Subtest device-signal-unordered: SUCCESS (0.001s)
[359.926985] [050/133] (669s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[360.022253] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[366.996494] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.974s)
[367.135481] [051/133] (662s left) kms_flip (2x-flip-vs-expired-vblank)
[367.240420] Starting subtest: 2x-flip-vs-expired-vblank
[367.252797] Subtest 2x-flip-vs-expired-vblank: SKIP (0.012s)
[367.336522] [052/133] (661s left) kms_psr (psr2-sprite-render)
[367.430229] Starting subtest: psr2-sprite-render
[367.432473] Starting dynamic subtest: eDP-1
[369.170980] Dynamic subtest eDP-1: SUCCESS (1.739s)
[369.173158] Subtest psr2-sprite-render: SUCCESS (1.740s)
[370.032347] [053/133] (659s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[370.125696] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[370.128023] Starting dynamic subtest: pipe-A-eDP-1
[376.032092] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.907s)
[376.044348] Starting dynamic subtest: pipe-B-eDP-1
[382.700737] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.663s)
[382.703069] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.573s)
[383.571794] [054/133] (645s left) drm_read (short-buffer-nonblock)
[383.731541] Starting subtest: short-buffer-nonblock
[383.733915] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[383.868962] [055/133] (645s left) gem_exec_schedule (pi-userfault)
[384.046632] Starting subtest: pi-userfault
[384.048883] Starting dynamic subtest: rcs0
[384.055466] Dynamic subtest rcs0: SUCCESS (0.011s)
[384.066232] Starting dynamic subtest: bcs0
[384.068075] Dynamic subtest bcs0: SUCCESS (0.008s)
[384.078570] Starting dynamic subtest: vcs0
[384.080686] Dynamic subtest vcs0: SUCCESS (0.007s)
[384.091279] Starting dynamic subtest: vcs1
[384.093560] Dynamic subtest vcs1: SUCCESS (0.008s)
[384.110719] Starting dynamic subtest: vecs0
[384.112572] Dynamic subtest vecs0: SUCCESS (0.008s)
[384.122686] Starting dynamic subtest: ccs0
[384.124475] Dynamic subtest ccs0: SUCCESS (0.008s)
[384.126273] Subtest pi-userfault: SUCCESS (0.056s)
[384.283815] [056/133] (645s left) i915_pm_rpm (sysfs-read)
[386.111654] Starting subtest: sysfs-read
[387.272967] Subtest sysfs-read: SUCCESS (1.156s)
[387.646605] [057/133] (641s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[387.748391] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[388.774012] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.026s)
[388.907733] [058/133] (640s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[389.000600] Starting subtest: y-tiled-64bpp-rotate-270
[389.004990] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[389.108606] [059/133] (640s left) kms_psr (fbc-psr-primary-blt)
[389.193278] Starting subtest: fbc-psr-primary-blt
[389.195134] Starting dynamic subtest: eDP-1
[389.198329] Dynamic subtest eDP-1: SKIP (0.000s)
[389.201600] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[389.267317] [060/133] (640s left) vgem_basic (dmabuf-export)
[389.307992] Starting subtest: dmabuf-export
[389.332918] Subtest dmabuf-export: SUCCESS (0.027s)
[389.440294] [061/133] (640s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[389.496914] Starting subtest: multi-wait-all-for-submit-available-signaled
[389.499032] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.002s)
[389.605491] [062/133] (640s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[389.705601] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[389.718069] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.011s)
[389.811069] [063/133] (639s left) syncobj_timeline (single-wait-all-available-submitted)
[389.871658] Starting subtest: single-wait-all-available-submitted
[389.873727] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[389.987884] [064/133] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[390.080109] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[390.083855] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[390.171420] [065/133] (639s left) kms_universal_plane (disable-primary-vs-flip)
[390.260266] Starting subtest: disable-primary-vs-flip
[390.262284] Starting dynamic subtest: pipe-A-eDP-1
[391.794149] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.532s)
[391.806800] Starting dynamic subtest: pipe-B-eDP-1
[393.979998] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.185s)
[393.990937] Starting dynamic subtest: pipe-C-eDP-1
[396.233083] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.253s)
[396.244733] Starting dynamic subtest: pipe-D-eDP-1
[398.384982] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.149s)
[398.386993] Subtest disable-primary-vs-flip: SUCCESS (8.125s)
[399.188493] [066/133] (630s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[399.291353] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[399.295743] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[399.396969] [067/133] (630s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[399.489902] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[399.502852] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[399.583410] [068/133] (630s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[399.672653] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[399.677027] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[399.747631] [069/133] (630s left) vgem_basic (bad-handle)
[399.797746] Starting subtest: bad-handle
[399.800164] Subtest bad-handle: SUCCESS (0.000s)
[399.858344] [070/133] (630s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[399.944468] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[399.946694] Starting dynamic subtest: pipe-A-eDP-1
[399.969029] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[399.982017] Starting dynamic subtest: pipe-B-eDP-1
[400.003447] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[400.013989] Starting dynamic subtest: pipe-C-eDP-1
[400.036265] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[400.046629] Starting dynamic subtest: pipe-D-eDP-1
[400.068776] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[400.072802] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[402.267979] [071/133] (627s left) api_intel_bb (intel-bb-blit-x)
[402.318617] Starting subtest: intel-bb-blit-x
[406.146204] Subtest intel-bb-blit-x: SUCCESS (3.829s)
[406.270083] [072/133] (623s left) kms_cursor_crc (cursor-sliding-32x10)
[406.432851] Starting subtest: cursor-sliding-32x10
[406.487713] Subtest cursor-sliding-32x10: SKIP (0.048s)
[406.772472] [073/133] (623s left) prime_self_import (export-vs-gem_close-race)
[406.808675] Starting subtest: export-vs-gem_close-race
[411.907137] Subtest export-vs-gem_close-race: SUCCESS (5.100s)
[411.985557] [074/133] (618s left) perf (non-sampling-read-error)
[412.097378] Starting subtest: non-sampling-read-error
[412.106764] Subtest non-sampling-read-error: SUCCESS (0.006s)
[412.153127] [075/133] (618s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[412.243659] Starting subtest: bad-rotation-90-y-tiled-ccs
[412.245872] Starting dynamic subtest: pipe-A-eDP-1
[412.269666] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[412.281804] Starting dynamic subtest: pipe-B-eDP-1
[412.303654] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[412.314675] Starting dynamic subtest: pipe-C-eDP-1
[412.336568] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[412.347168] Starting dynamic subtest: pipe-D-eDP-1
[412.371268] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[412.375515] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.005s)
[414.521855] [076/133] (615s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[414.619348] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[421.605847] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.990s)
[421.755762] [077/133] (608s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[421.813331] Starting subtest: invalid-bsd2-flag-on-blt
[421.815763] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[421.916017] [078/133] (608s left) kms_cursor_crc (cursor-onscreen-128x42)
[422.136654] Starting subtest: cursor-onscreen-128x42
[422.185490] Subtest cursor-onscreen-128x42: SKIP (0.044s)
[422.412697] [079/133] (607s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[422.529953] Starting subtest: modeset-atomic-cursor-hotspot
[422.533805] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[422.676004] [080/133] (607s left) gem_render_copy (linear)
[422.732603] Starting subtest: linear
[422.734630] Starting dynamic subtest: smem
[422.773947] Dynamic subtest smem: SUCCESS (0.041s)
[422.776472] Subtest linear: SUCCESS (0.042s)
[423.015660] [081/133] (607s left) prime_self_import (basic-with_one_bo_two_files)
[423.053287] Starting subtest: basic-with_one_bo_two_files
[423.071281] Subtest basic-with_one_bo_two_files: SUCCESS (0.020s)
[423.181190] [082/133] (607s left) kms_chamelium_edid (hdmi-edid-read)
[423.563964] Subtest hdmi-edid-read: SKIP (0.000s)
[424.429277] [083/133] (606s left) gem_exec_params (readonly)
[424.482983] Starting subtest: readonly
[424.488205] Subtest readonly: SUCCESS (0.003s)
[424.591741] [084/133] (605s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[424.681598] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[424.683770] Starting dynamic subtest: pipe-A-default-mode
[425.813605] Dynamic subtest pipe-A-default-mode: SKIP (1.128s)
[425.817780] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.130s)
[425.961092] [085/133] (604s left) gem_mmap_gtt (cpuset-big-copy)
[426.013093] Subtest cpuset-big-copy: SKIP (0.000s)
[426.081828] [086/133] (604s left) sriov_basic (enable-vfs-autoprobe-on)
[426.136414] Starting subtest: enable-vfs-autoprobe-on
[426.138824] Starting dynamic subtest: numvfs-1
[426.221847] Dynamic subtest numvfs-1: FAIL (0.033s)
[426.233917] Starting dynamic subtest: numvfs-2
[426.307367] Dynamic subtest numvfs-2: FAIL (0.004s)
[426.319653] Starting dynamic subtest: numvfs-3
[426.392879] Dynamic subtest numvfs-3: FAIL (0.004s)
[426.407339] Starting dynamic subtest: numvfs-4
[426.481141] Dynamic subtest numvfs-4: FAIL (0.004s)
[426.492611] Starting dynamic subtest: numvfs-5
[426.568521] Dynamic subtest numvfs-5: FAIL (0.004s)
[426.581052] Starting dynamic subtest: numvfs-6
[426.654094] Dynamic subtest numvfs-6: FAIL (0.004s)
[426.666079] Starting dynamic subtest: numvfs-7
[426.734051] Dynamic subtest numvfs-7: FAIL (0.004s)
[426.743506] Starting dynamic subtest: numvfs-random
[426.801009] Dynamic subtest numvfs-random: FAIL (0.004s)
[426.811458] Starting dynamic subtest: numvfs-all
[426.871481] Dynamic subtest numvfs-all: FAIL (0.527s)
[426.873243] Subtest enable-vfs-autoprobe-on: FAIL (0.594s)
[426.913448] [087/133] (603s left) kms_plane_scaling (planes-downscale-factor-0-75)
[427.005920] Starting subtest: planes-downscale-factor-0-75
[427.007909] Starting dynamic subtest: pipe-A
[427.030118] Dynamic subtest pipe-A: SKIP (0.021s)
[427.041045] Starting dynamic subtest: pipe-B
[427.065428] Dynamic subtest pipe-B: SKIP (0.034s)
[427.075636] Starting dynamic subtest: pipe-C
[427.100260] Dynamic subtest pipe-C: SKIP (0.033s)
[427.110375] Starting dynamic subtest: pipe-D
[427.132441] Dynamic subtest pipe-D: SKIP (0.033s)
[427.135651] Subtest planes-downscale-factor-0-75: SKIP (0.124s)
[427.235721] [088/133] (603s left) kms_plane (plane-panning-top-left)
[427.328098] Starting subtest: plane-panning-top-left
[427.329864] Starting dynamic subtest: pipe-A
[429.048565] Dynamic subtest pipe-A: SUCCESS (1.719s)
[429.059253] Starting dynamic subtest: pipe-B
[431.433260] Dynamic subtest pipe-B: SUCCESS (2.385s)
[431.436025] Subtest plane-panning-top-left: SUCCESS (4.108s)
[432.323777] [089/133] (598s left) kms_plane_cursor (overlay)
[432.419135] Starting subtest: overlay
[432.423316] Starting dynamic subtest: pipe-A-eDP-1-size-64
[434.973449] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.555s)
[435.036073] Starting dynamic subtest: pipe-A-eDP-1-size-128
[437.596434] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.571s)
[437.658128] Starting dynamic subtest: pipe-A-eDP-1-size-256
[440.231657] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.583s)
[440.581010] Starting dynamic subtest: pipe-B-eDP-1-size-64
[443.932663] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.363s)
[443.994710] Starting dynamic subtest: pipe-B-eDP-1-size-128
[446.566787] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.583s)
[446.628624] Starting dynamic subtest: pipe-B-eDP-1-size-256
[449.201747] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.583s)
[449.550235] Starting dynamic subtest: pipe-C-eDP-1-size-64
[452.512029] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.973s)
[452.551297] Starting dynamic subtest: pipe-C-eDP-1-size-128
[454.789946] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.248s)
[454.829193] Starting dynamic subtest: pipe-C-eDP-1-size-256
[457.079085] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.259s)
[457.409157] Starting dynamic subtest: pipe-D-eDP-1-size-64
[460.401639] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.006s)
[460.440354] Starting dynamic subtest: pipe-D-eDP-1-size-128
[462.679327] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.248s)
[462.719072] Starting dynamic subtest: pipe-D-eDP-1-size-256
[464.957567] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.248s)
[465.278842] Subtest overlay: SUCCESS (32.860s)
[466.153395] [090/133] (564s left) perf_pmu (busy)
[466.194867] Starting subtest: busy
[466.197296] Starting dynamic subtest: rcs0
[466.774014] Dynamic subtest rcs0: SUCCESS (0.581s)
[466.786363] Starting dynamic subtest: bcs0
[467.338934] Dynamic subtest bcs0: SUCCESS (0.562s)
[467.352255] Starting dynamic subtest: vcs0
[469.000698] Dynamic subtest vcs0: SUCCESS (1.658s)
[469.014563] Starting dynamic subtest: vcs1
[470.983754] Dynamic subtest vcs1: SUCCESS (1.980s)
[470.995577] Starting dynamic subtest: vecs0
[472.968948] Dynamic subtest vecs0: SUCCESS (1.982s)
[472.984649] Starting dynamic subtest: ccs0
[473.546602] Dynamic subtest ccs0: SUCCESS (0.575s)
[473.549557] Subtest busy: SUCCESS (7.354s)
[473.678804] [091/133] (557s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[473.778901] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[473.791620] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.012s)
[473.884837] [092/133] (557s left) kms_frontbuffer_tracking (fbc-stridechange)
[473.973760] Starting subtest: fbc-stridechange
[477.224437] Subtest fbc-stridechange: SUCCESS (3.252s)
[478.385623] [093/133] (552s left) gem_pread (bench)
[478.445953] Subtest bench: SKIP (0.000s)
[478.510286] [094/133] (552s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[478.601122] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[485.485264] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.886s)
[485.623640] [095/133] (545s left) syncobj_basic (bad-fd-to-handle)
[485.685213] Starting subtest: bad-fd-to-handle
[485.687147] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[485.791337] [096/133] (545s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[485.853534] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[485.857176] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[485.970201] [097/133] (545s left) perf (mi-rpc)
[486.059554] Starting subtest: mi-rpc
[486.064177] Subtest mi-rpc: SKIP (0.000s)
[486.108684] [098/133] (545s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[486.199542] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[486.203697] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[486.295432] [099/133] (544s left) kms_vblank (ts-continuation-modeset-rpm)
[486.380640] Starting subtest: ts-continuation-modeset-rpm
[486.382424] Starting dynamic subtest: pipe-A-eDP-1
[488.922664] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.542s)
[488.940672] Starting dynamic subtest: pipe-D-eDP-1
[491.176085] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.250s)
[491.178302] Subtest ts-continuation-modeset-rpm: SUCCESS (4.795s)
[491.981792] [100/133] (539s left) perf (non-system-wide-paranoid)
[492.079478] Starting subtest: non-system-wide-paranoid
[492.103431] Subtest non-system-wide-paranoid: SUCCESS (0.024s)
[492.149215] [101/133] (539s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[492.239758] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[492.242025] Starting dynamic subtest: pipe-A-eDP-1
[492.267249] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[492.280395] Starting dynamic subtest: pipe-B-eDP-1
[492.304167] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[492.314673] Starting dynamic subtest: pipe-C-eDP-1
[492.338338] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[492.348871] Starting dynamic subtest: pipe-D-eDP-1
[492.373173] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[492.377303] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[494.537471] [102/133] (536s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[494.632272] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[504.095909] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.464s)
[504.238869] [103/133] (527s left) gem_softpin (allocator-basic)
[504.298412] Starting subtest: allocator-basic
[504.363422] Subtest allocator-basic: SUCCESS (0.067s)
[504.495670] [104/133] (526s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[504.590573] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[511.353422] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.765s)
[511.457841] [105/133] (520s left) syncobj_basic (create-signaled)
[511.508830] Starting subtest: create-signaled
[511.510973] Subtest create-signaled: SUCCESS (0.000s)
[511.609748] [106/133] (519s left) gem_mmap (basic-small-bo)
[511.660124] Subtest basic-small-bo: SKIP (0.000s)
[511.719302] [107/133] (519s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[511.812094] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[518.471779] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.661s)
[518.589003] [108/133] (513s left) kms_force_connector_basic (force-load-detect)
[518.673569] Starting subtest: force-load-detect
[518.678070] Subtest force-load-detect: SKIP (0.000s)
[518.787158] [109/133] (512s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[518.882562] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[518.884867] Starting dynamic subtest: pipe-A-eDP-1
[518.959380] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[518.972050] Starting dynamic subtest: pipe-B-eDP-1
[520.062085] Dynamic subtest pipe-B-eDP-1: SKIP (1.100s)
[520.067299] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.181s)
[521.254398] [110/133] (510s left) gem_mmap_wc (coherency)
[521.302325] Subtest coherency: SKIP (0.000s)
[521.361336] [111/133] (510s left) kms_concurrent (multi-plane-atomic-lowres)
[521.454895] Starting subtest: multi-plane-atomic-lowres
[521.456783] Starting dynamic subtest: pipe-A-eDP-1
[521.937347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.481s)
[521.954791] Starting dynamic subtest: pipe-B-eDP-1
[523.227674] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.289s)
[523.239169] Starting dynamic subtest: pipe-C-eDP-1
[524.453237] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.221s)
[524.464736] Starting dynamic subtest: pipe-D-eDP-1
[525.663896] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s)
[525.666233] Subtest multi-plane-atomic-lowres: SUCCESS (4.209s)
[526.505138] [112/133] (505s left) gem_gtt_cpu_tlb
[526.612887] [113/133] (505s left) i915_module_load (reload-no-display)
[526.646030] Starting subtest: reload-no-display
[533.913953] Subtest reload-no-display: SUCCESS (7.266s)
[533.943857] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[533.944575] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[533.947775] [114/133] (497s left) i915_drm_fdinfo (idle)
[535.501261] Starting subtest: idle
[535.503492] Starting dynamic subtest: rcs0
[536.042099] Dynamic subtest rcs0: SUCCESS (0.539s)
[536.051662] Starting dynamic subtest: bcs0
[536.572806] Dynamic subtest bcs0: SUCCESS (0.529s)
[536.585677] Starting dynamic subtest: vcs0
[537.079417] Dynamic subtest vcs0: SUCCESS (0.503s)
[537.091492] Starting dynamic subtest: vcs1
[537.584967] Dynamic subtest vcs1: SUCCESS (0.503s)
[537.597288] Starting dynamic subtest: vecs0
[538.090912] Dynamic subtest vecs0: SUCCESS (0.503s)
[538.101126] Starting dynamic subtest: ccs0
[538.596576] Dynamic subtest ccs0: SUCCESS (0.503s)
[538.599159] Subtest idle: SUCCESS (3.095s)
[538.651319] [FACT i915_drm_fdinfo (idle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[538.652533] [FACT i915_drm_fdinfo (idle)] new: kernel.kmod_is_loaded.i915: true
[538.656282] [115/133] (493s left) dumb_buffer (create-clear)
[538.718806] Starting subtest: create-clear
[573.427539] Subtest create-clear: SUCCESS (34.698s)
[573.524575] [116/133] (458s left) kms_flip (flip-vs-fences-interruptible)
[573.633353] Starting subtest: flip-vs-fences-interruptible
[573.637855] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[573.741494] [117/133] (458s left) kms_big_fb (linear-8bpp-rotate-270)
[573.855647] Starting subtest: linear-8bpp-rotate-270
[573.892744] Subtest linear-8bpp-rotate-270: SKIP (0.036s)
[574.016480] [118/133] (458s left) gen9_exec_parse (bb-oversize)
[574.069464] Subtest bb-oversize: SKIP (0.000s)
[574.149051] [119/133] (457s left) gem_exec_schedule (smoketest)
[574.330708] Starting subtest: smoketest
[574.332992] Starting dynamic subtest: rcs0
[579.873597] Dynamic subtest rcs0: SUCCESS (5.496s)
[579.887745] Starting dynamic subtest: bcs0
[585.374653] Dynamic subtest bcs0: SUCCESS (5.497s)
[585.387967] Starting dynamic subtest: vcs0
[590.872089] Dynamic subtest vcs0: SUCCESS (5.493s)
[590.885262] Starting dynamic subtest: vcs1
[596.369393] Dynamic subtest vcs1: SUCCESS (5.492s)
[596.381942] Starting dynamic subtest: vecs0
[601.870059] Dynamic subtest vecs0: SUCCESS (5.499s)
[601.887460] Starting dynamic subtest: ccs0
[607.370811] Dynamic subtest ccs0: SUCCESS (5.498s)
[607.372862] Subtest smoketest: SUCCESS (32.985s)
[607.515400] [120/133] (424s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[607.731349] Starting subtest: cursor-rapid-movement-64x21
[607.788766] Subtest cursor-rapid-movement-64x21: SKIP (0.050s)
[608.012979] [121/133] (424s left) gem_mmap (basic)
[608.068858] Subtest basic: SKIP (0.000s)
[608.127125] [122/133] (424s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[608.215291] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[608.839891] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.626s)
[609.017107] [123/133] (423s left) gem_exec_balancer (hang)
[609.296419] Starting subtest: hang
[610.987806] Subtest hang: SUCCESS (1.693s)
[611.099228] [124/133] (421s left) gem_ctx_exec (basic-norecovery)
[611.156199] Starting subtest: basic-norecovery
[611.499643] Subtest basic-norecovery: SUCCESS (0.343s)
[611.628278] [125/133] (420s left) kms_hdr (static-toggle)
[611.717390] Starting subtest: static-toggle
[611.723345] Subtest static-toggle: SKIP (0.000s)
[611.792318] [126/133] (420s left) kms_properties (invalid-properties-legacy)
[611.886671] Starting subtest: invalid-properties-legacy
[611.992989] Subtest invalid-properties-legacy: SUCCESS (0.106s)
[612.066243] [127/133] (420s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[612.157348] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[616.293426] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.140s)
[617.513822] [128/133] (414s left) gem_workarounds (suspend-resume-context)
[617.581630] Starting subtest: suspend-resume-context
[633.938611] Subtest suspend-resume-context: SUCCESS (1.574s)
[641.093593] [129/133] (398s left) gem_exec_fence (keep-in-fence)
[641.277603] Starting subtest: keep-in-fence
[641.280515] Starting dynamic subtest: rcs0
[641.353964] Dynamic subtest rcs0: SUCCESS (0.075s)
[641.366472] Starting dynamic subtest: bcs0
[641.412597] Dynamic subtest bcs0: SUCCESS (0.056s)
[641.424990] Starting dynamic subtest: vcs0
[642.763100] Dynamic subtest vcs0: SUCCESS (1.332s)
[642.776660] Starting dynamic subtest: vcs1
[644.746483] Dynamic subtest vcs1: SUCCESS (1.962s)
[644.760695] Starting dynamic subtest: vecs0
[646.794837] Dynamic subtest vecs0: SUCCESS (2.031s)
[646.809116] Starting dynamic subtest: ccs0
[647.025843] Dynamic subtest ccs0: SUCCESS (0.228s)
[647.029097] Subtest keep-in-fence: SUCCESS (5.698s)
[648.868392] [130/133] (390s left) gem_mmap_gtt (hang-user)
[648.933109] Subtest hang-user: SKIP (0.000s)
[648.992412] [131/133] (390s left) kms_atomic_transition (plane-all-transition-fencing)
[649.083804] Starting subtest: plane-all-transition-fencing
[649.086593] Starting dynamic subtest: pipe-A-eDP-1
[651.588624] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[651.900424] Starting dynamic subtest: pipe-B-eDP-1
[655.113121] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.216s)
[655.428001] Subtest plane-all-transition-fencing: SUCCESS (6.323s)
[656.254629] [132/133] (383s left) gem_pxp (protected-raw-src-copy-not-readible)
[656.374668] Starting subtest: protected-raw-src-copy-not-readible
[656.431426] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[656.550309] [133/133] (383s left) syncobj_wait (multi-wait-for-submit-submitted)
[656.601108] Starting subtest: multi-wait-for-submit-submitted
[656.708061] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[656.796502] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_async_flips
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: kms_psr
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: kms_atomic_transition
results: parsing output: 9/ for test: i915_power
results: parsing output: 10/ for test: gem_exec_whisper
results: parsing output: 11/ for test: drm_read
results: parsing output: 12/ for test: kms_flip_scaled_crc
results: parsing output: 13/ for test: kms_plane
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: gem_exec_create
results: parsing output: 16/ for test: perf
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: kms_lease
results: parsing output: 20/ for test: kms_content_protection
results: parsing output: 21/ for test: gem_ctx_sseu
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: gem_lmem_swapping
results: parsing output: 24/ for test: gem_softpin
results: parsing output: 25/ for test: kms_plane_lowres
results: parsing output: 26/ for test: kms_draw_crc
results: parsing output: 27/ for test: kms_pipe_b_c_ivb
results: parsing output: 28/ for test: kms_psr2_sf
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_rotation_crc
results: parsing output: 32/ for test: kms_pm_rpm
results: parsing output: 33/ for test: kms_pm_lpsp
results: parsing output: 34/ for test: kms_chamelium_hpd
results: parsing output: 35/ for test: api_intel_allocator
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_userptr_blits
results: parsing output: 38/ for test: gem_eio
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: kms_properties
results: parsing output: 41/ for test: gem_ctx_exec
results: parsing output: 42/ for test: gem_exec_params
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: gem_ctx_persistence
results: parsing output: 46/ for test: gem_pread
results: parsing output: 47/ for test: fbdev
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: drm_read
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: i915_pm_rpm
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: vgem_basic
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_universal_plane
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_flip_scaled_crc
results: parsing output: 68/ for test: vgem_basic
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: api_intel_bb
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: prime_self_import
results: parsing output: 73/ for test: perf
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_exec_params
results: parsing output: 77/ for test: kms_cursor_crc
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: gem_render_copy
results: parsing output: 80/ for test: prime_self_import
results: parsing output: 81/ for test: kms_chamelium_edid
results: parsing output: 82/ for test: gem_exec_params
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: gem_mmap_gtt
results: parsing output: 85/ for test: sriov_basic
results: parsing output: 86/ for test: kms_plane_scaling
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: kms_plane_cursor
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_pread
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: syncobj_basic
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: perf
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_vblank
results: parsing output: 99/ for test: perf
results: parsing output: 100/ for test: kms_ccs
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_softpin
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: syncobj_basic
results: parsing output: 105/ for test: gem_mmap
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_force_connector_basic
results: parsing output: 108/ for test: kms_psr2_sf
results: parsing output: 109/ for test: gem_mmap_wc
results: parsing output: 110/ for test: kms_concurrent
results: parsing output: 111/ for test: gem_gtt_cpu_tlb
results: parsing output: 112/ for test: i915_module_load
results: parsing output: 113/ for test: i915_drm_fdinfo
results: parsing output: 114/ for test: dumb_buffer
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: gen9_exec_parse
results: parsing output: 118/ for test: gem_exec_schedule
results: parsing output: 119/ for test: kms_cursor_crc
results: parsing output: 120/ for test: gem_mmap
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: gem_exec_balancer
results: parsing output: 123/ for test: gem_ctx_exec
results: parsing output: 124/ for test: kms_hdr
results: parsing output: 125/ for test: kms_properties
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_workarounds
results: parsing output: 128/ for test: gem_exec_fence
results: parsing output: 129/ for test: gem_mmap_gtt
results: parsing output: 130/ for test: kms_atomic_transition
results: parsing output: 131/ for test: gem_pxp
results: parsing output: 132/ for test: syncobj_wait
Done.