[53.732139] Initializing watchdogs
[53.732186]   /dev/watchdog0
[53.747012] [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]
[53.751581] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[53.753973] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[53.757767] [001/133] (960s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[53.874925] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[53.877401] Starting dynamic subtest: pipe-A-eDP-1
[54.739558] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.859s)
[54.747203] Starting dynamic subtest: pipe-B-eDP-1
[56.339664] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.595s)
[56.347863] Starting dynamic subtest: pipe-C-eDP-1
[57.828129] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.488s)
[57.839618] Starting dynamic subtest: pipe-D-eDP-1
[59.356146] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.526s)
[59.358416] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.472s)
[60.504472] [002/133] (953s left) kms_psr (fbc-pr-sprite-plane-move)
[60.602067] Starting subtest: fbc-pr-sprite-plane-move
[60.606287] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[60.671700] [003/133] (953s left) gem_eio (in-flight-contexts-immediate)
[60.830969] Starting subtest: in-flight-contexts-immediate
[70.312879] Subtest in-flight-contexts-immediate: SUCCESS (9.481s)
[70.464159] [004/133] (943s left) kms_async_flips (async-flip-suspend-resume)
[70.549322] Starting subtest: async-flip-suspend-resume
[70.930663] Starting dynamic subtest: pipe-A-eDP-1
[88.951954] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.361s)
[89.349765] Starting dynamic subtest: pipe-B-eDP-1
[106.936612] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.886s)
[107.321771] Starting dynamic subtest: pipe-C-eDP-1
[125.156696] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.113s)
[125.523223] Starting dynamic subtest: pipe-D-eDP-1
[142.966158] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.932s)
[142.968466] Subtest async-flip-suspend-resume: SUCCESS (13.808s)
[144.155118] [005/133] (870s left) kms_flip (modeset-vs-vblank-race)
[144.255708] Starting subtest: modeset-vs-vblank-race
[144.258179] Starting dynamic subtest: A-eDP1
[148.965415] Dynamic subtest A-eDP1: SUCCESS (4.705s)
[148.976324] Starting dynamic subtest: B-eDP1
[153.702322] Dynamic subtest B-eDP1: SUCCESS (4.733s)
[153.714054] Starting dynamic subtest: C-eDP1
[158.330437] Dynamic subtest C-eDP1: SUCCESS (4.625s)
[158.339337] Starting dynamic subtest: D-eDP1
[162.917072] Dynamic subtest D-eDP1: SUCCESS (4.585s)
[162.922629] Subtest modeset-vs-vblank-race: SUCCESS (18.657s)
[164.077701] [006/133] (850s left) kms_psr (pr-cursor-plane-move)
[164.184616] Starting subtest: pr-cursor-plane-move
[164.188680] Subtest pr-cursor-plane-move: SKIP (0.000s)
[164.256093] [007/133] (850s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[164.358210] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[171.726399] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.372s)
[171.850438] [008/133] (842s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[171.950842] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[171.955127] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[172.045273] [009/133] (842s left) kms_atomic_transition (modeset-transition-nonblocking)
[172.131180] Starting subtest: modeset-transition-nonblocking
[172.133081] Starting dynamic subtest: 1x-outputs
[183.173347] Dynamic subtest 1x-outputs: SUCCESS (11.039s)
[183.175626] Subtest modeset-transition-nonblocking: SUCCESS (11.040s)
[184.038819] [010/133] (830s left) i915_power (sanity)
[184.097894] Starting subtest: sanity
[188.141756] Subtest sanity: SUCCESS (4.038s)
[190.027312] [011/133] (824s left) gem_exec_whisper (basic-queues-all)
[190.094619] Starting subtest: basic-queues-all
[202.124196] Subtest basic-queues-all: SUCCESS (12.024s)
[202.305057] [012/133] (812s left) drm_read (empty-nonblock)
[202.448924] Starting subtest: empty-nonblock
[202.451368] Subtest empty-nonblock: SUCCESS (0.000s)
[202.562423] [013/133] (812s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[202.665384] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[202.669768] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[202.740721] [014/133] (812s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[202.837621] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[202.841894] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[202.907595] [015/133] (811s left) kms_psr (psr-sprite-plane-move)
[202.993964] Starting subtest: psr-sprite-plane-move
[202.996260] Starting dynamic subtest: eDP-1
[204.781193] Dynamic subtest eDP-1: SUCCESS (1.786s)
[204.783838] Subtest psr-sprite-plane-move: SUCCESS (1.787s)
[205.600946] [016/133] (809s left) gem_exec_create (legacy)
[205.667329] Starting subtest: legacy
[205.669201] Starting dynamic subtest: smem
[208.893000] Dynamic subtest smem: SUCCESS (3.225s)
[208.895626] Subtest legacy: SUCCESS (3.227s)
[209.072386] [017/133] (805s left) perf (blocking)
[209.199035] Starting subtest: blocking
[209.201248] Starting dynamic subtest: 0-rcs0
[219.209867] Dynamic subtest 0-rcs0: SUCCESS (10.009s)
[219.225736] Starting dynamic subtest: 1-vcs1
[229.235618] Dynamic subtest 1-vcs1: SUCCESS (10.023s)
[229.238526] Subtest blocking: SUCCESS (20.033s)
[229.293977] [018/133] (785s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[229.390942] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[229.395289] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[229.495146] [019/133] (785s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[229.576829] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[229.578747] Starting dynamic subtest: pipe-A-eDP-1
[230.321209] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.738s)
[230.332236] Starting dynamic subtest: pipe-B-eDP-1
[232.147024] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.824s)
[232.157669] Starting dynamic subtest: pipe-C-eDP-1
[233.980719] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.833s)
[233.989565] Starting dynamic subtest: pipe-D-eDP-1
[235.822392] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.840s)
[235.824847] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.239s)
[236.959320] [020/133] (778s left) kms_lease (lease-invalid-crtc)
[237.049587] Starting subtest: lease-invalid-crtc
[237.051746] Starting dynamic subtest: pipe-A-eDP-1
[237.053981] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[237.063497] Starting dynamic subtest: pipe-B-eDP-1
[237.065705] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[237.074357] Starting dynamic subtest: pipe-C-eDP-1
[237.076601] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[237.085729] Starting dynamic subtest: pipe-D-eDP-1
[237.087817] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[237.089916] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[237.125857] [021/133] (777s left) kms_content_protection (atomic-hdcp14)
[237.259493] Starting subtest: atomic-hdcp14
[237.263900] Subtest atomic-hdcp14: SKIP (0.000s)
[237.376632] [022/133] (777s left) gem_ctx_sseu (invalid-args)
[237.430904] Subtest invalid-args: SKIP (0.000s)
[237.501434] [023/133] (777s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[237.550388] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[237.656377] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[237.730997] [024/133] (777s left) gem_lmem_swapping (parallel-random-engines)
[237.781355] Subtest parallel-random-engines: SKIP (0.000s)
[237.825237] [025/133] (777s left) gem_softpin (evict-prime)
[237.883251] Starting subtest: evict-prime
[237.885591] Starting dynamic subtest: rcs0
[239.035106] Dynamic subtest rcs0: SUCCESS (1.152s)
[239.046478] Starting dynamic subtest: bcs0
[240.165905] Dynamic subtest bcs0: SUCCESS (1.129s)
[240.178053] Starting dynamic subtest: vcs0
[241.861620] Dynamic subtest vcs0: SUCCESS (1.693s)
[241.874154] Starting dynamic subtest: vcs1
[243.841297] Dynamic subtest vcs1: SUCCESS (1.978s)
[243.854439] Starting dynamic subtest: vecs0
[245.881628] Dynamic subtest vecs0: SUCCESS (2.040s)
[245.893415] Starting dynamic subtest: ccs0
[247.001058] Dynamic subtest ccs0: SUCCESS (1.119s)
[247.011676] Starting dynamic subtest: all
[248.893854] Dynamic subtest all: SUCCESS (1.891s)
[248.896889] Subtest evict-prime: SUCCESS (11.011s)
[248.973760] [026/133] (766s left) kms_plane_lowres (tiling-none)
[249.080250] Starting subtest: tiling-none
[249.082577] Starting dynamic subtest: pipe-A-eDP-1
[249.086919] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[249.373575] Starting dynamic subtest: pipe-B-eDP-1
[249.378093] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[249.387696] Starting dynamic subtest: pipe-C-eDP-1
[249.391937] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[249.401411] Starting dynamic subtest: pipe-D-eDP-1
[249.405294] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[249.409239] Subtest tiling-none: SKIP (0.293s)
[250.184711] [027/133] (765s left) kms_draw_crc (draw-method-mmap-cpu)
[250.274601] Starting subtest: draw-method-mmap-cpu
[250.276804] Starting dynamic subtest: xrgb8888-untiled
[250.541336] Dynamic subtest xrgb8888-untiled: SUCCESS (0.267s)
[250.553000] Starting dynamic subtest: rgb565-untiled
[250.762058] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[250.771967] Starting dynamic subtest: xrgb2101010-untiled
[250.985139] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[250.995286] Starting dynamic subtest: xbgr16161616f-untiled
[251.205940] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[251.215613] Starting dynamic subtest: xrgb8888-xtiled
[251.339452] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[251.350898] Starting dynamic subtest: rgb565-xtiled
[251.472723] Dynamic subtest rgb565-xtiled: SUCCESS (0.131s)
[251.487739] Starting dynamic subtest: xrgb2101010-xtiled
[251.583740] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[251.593719] Starting dynamic subtest: xbgr16161616f-xtiled
[251.706016] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[251.715745] Starting dynamic subtest: xrgb8888-4tiled
[251.839348] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[251.849027] Starting dynamic subtest: rgb565-4tiled
[251.983911] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[251.993740] Starting dynamic subtest: xrgb2101010-4tiled
[252.128229] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[252.137811] Starting dynamic subtest: xbgr16161616f-4tiled
[252.272575] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[252.274525] Subtest draw-method-mmap-cpu: SUCCESS (2.001s)
[252.377402] [028/133] (762s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[252.439023] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[252.500564] [029/133] (762s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[252.599459] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[252.601322] Starting dynamic subtest: pipe-A-eDP-1
[252.696903] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[252.707056] Starting dynamic subtest: pipe-B-eDP-1
[253.847682] Dynamic subtest pipe-B-eDP-1: SKIP (1.148s)
[253.852162] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.251s)
[255.040848] [030/133] (760s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[255.144981] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[255.149210] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[255.237417] [031/133] (760s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[255.339606] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[255.344098] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[255.437944] [032/133] (760s left) kms_rotation_crc (primary-rotation-180)
[255.542803] Starting subtest: primary-rotation-180
[262.597629] Subtest primary-rotation-180: SUCCESS (7.058s)
[263.797935] [033/133] (751s left) kms_pm_rpm (universal-planes-dpms)
[266.045380] Starting subtest: universal-planes-dpms
[266.048118] Starting dynamic subtest: plane-35
[274.382192] Dynamic subtest plane-35: SUCCESS (8.339s)
[274.395344] Starting dynamic subtest: plane-65
[283.025741] Dynamic subtest plane-65: SUCCESS (8.639s)
[283.038315] Starting dynamic subtest: plane-95
[291.645136] Dynamic subtest plane-95: SUCCESS (8.619s)
[291.657598] Starting dynamic subtest: plane-125
[300.355887] Dynamic subtest plane-125: SUCCESS (8.707s)
[300.369002] Starting dynamic subtest: plane-135
[308.640320] Dynamic subtest plane-135: SUCCESS (8.281s)
[308.653864] Starting dynamic subtest: plane-145
[316.967634] Dynamic subtest plane-145: SUCCESS (8.323s)
[316.971771] Subtest universal-planes-dpms: SUCCESS (50.925s)
[317.377859] [034/133] (698s left) kms_pm_lpsp (kms-lpsp)
[318.475908] Starting subtest: kms-lpsp
[318.478297] Starting dynamic subtest: pipe-A-eDP-1
[318.623266] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.151s)
[318.930213] Subtest kms-lpsp: SUCCESS (0.458s)
[319.788285] [035/133] (695s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[320.173707] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[321.004549] [036/133] (694s left) api_intel_allocator (two-level-inception-interruptible)
[321.062800] Starting subtest: two-level-inception-interruptible
[326.471408] Subtest two-level-inception-interruptible: SUCCESS (5.408s)
[326.572363] [037/133] (689s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[326.677263] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[326.681417] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[326.785048] [038/133] (688s left) gem_userptr_blits (huge-split)
[326.862644] Starting subtest: huge-split
[326.869613] Subtest huge-split: SUCCESS (0.007s)
[326.983318] [039/133] (688s left) gem_eio (in-flight-10ms)
[327.216180] Starting subtest: in-flight-10ms
[335.913075] Subtest in-flight-10ms: SUCCESS (8.696s)
[336.086238] [FACT gem_eio (in-flight-10ms)] new: kernel.kmod_is_loaded.vgem: true
[336.089779] [040/133] (679s left) perf_pmu (pmu-read)
[336.130103] Starting subtest: pmu-read
[336.132492] Subtest pmu-read: SUCCESS (0.003s)
[336.239985] [041/133] (679s left) kms_properties (plane-properties-atomic)
[336.332251] Starting subtest: plane-properties-atomic
[336.334417] Starting dynamic subtest: pipe-A-eDP-1
[336.911631] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.579s)
[336.922015] Starting dynamic subtest: pipe-B-eDP-1
[338.205358] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.289s)
[338.216338] Starting dynamic subtest: pipe-C-eDP-1
[339.432386] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.226s)
[339.443801] Starting dynamic subtest: pipe-D-eDP-1
[340.667030] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[340.669342] Subtest plane-properties-atomic: SUCCESS (4.336s)
[341.512232] [042/133] (674s left) gem_ctx_exec (basic-invalid-context)
[341.570314] Starting subtest: basic-invalid-context
[341.574943] Subtest basic-invalid-context: SUCCESS (0.005s)
[341.668270] [043/133] (674s left) gem_exec_params (rel-constants-invalid)
[341.728569] Starting subtest: rel-constants-invalid
[341.730725] Subtest rel-constants-invalid: SUCCESS (0.000s)
[341.830055] [044/133] (674s left) syncobj_wait (reset-multiple-signaled)
[341.889723] Starting subtest: reset-multiple-signaled
[341.892090] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[342.008835] [045/133] (674s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[342.110482] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[342.114954] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[342.243736] [046/133] (673s left) gem_ctx_persistence (legacy-engines-mixed)
[342.298263] Starting subtest: legacy-engines-mixed
[342.300573] Starting dynamic subtest: render
[342.369382] Dynamic subtest render: SUCCESS (0.070s)
[342.382393] Starting dynamic subtest: bsd1
[342.406514] Dynamic subtest bsd1: SUCCESS (0.035s)
[342.416357] Starting dynamic subtest: bsd2
[342.450796] Dynamic subtest bsd2: SUCCESS (0.043s)
[342.461297] Starting dynamic subtest: blt
[342.493670] Dynamic subtest blt: SUCCESS (0.041s)
[342.504724] Starting dynamic subtest: vebox
[342.547914] Dynamic subtest vebox: SUCCESS (0.052s)
[342.550024] Subtest legacy-engines-mixed: SUCCESS (0.251s)
[342.628439] [047/133] (673s left) gem_pread (display)
[342.693848] Subtest display: SKIP (0.000s)
[342.756316] [048/133] (673s left) fbdev (unaligned-write)
[342.799350] Starting subtest: unaligned-write
[342.900390] Subtest unaligned-write: SUCCESS (0.101s)
[342.964081] [049/133] (673s left) syncobj_timeline (device-signal-unordered)
[343.021391] Starting subtest: device-signal-unordered
[343.023774] Subtest device-signal-unordered: SUCCESS (0.001s)
[343.119454] [050/133] (673s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[343.218606] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[350.150809] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.933s)
[350.304086] [051/133] (666s left) kms_flip (2x-flip-vs-expired-vblank)
[350.396577] Starting subtest: 2x-flip-vs-expired-vblank
[350.408453] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[350.488985] [052/133] (665s left) kms_psr (psr2-sprite-render)
[350.593542] Starting subtest: psr2-sprite-render
[350.595865] Starting dynamic subtest: eDP-1
[352.313907] Dynamic subtest eDP-1: SUCCESS (1.718s)
[352.316091] Subtest psr2-sprite-render: SUCCESS (1.719s)
[353.175028] [053/133] (663s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[353.272194] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[353.274384] Starting dynamic subtest: pipe-A-eDP-1
[359.147118] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.879s)
[359.157278] Starting dynamic subtest: pipe-B-eDP-1
[365.802795] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.652s)
[365.805174] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.535s)
[366.678599] [054/133] (649s left) drm_read (short-buffer-nonblock)
[366.832015] Starting subtest: short-buffer-nonblock
[366.834440] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[366.973820] [055/133] (649s left) gem_exec_schedule (pi-userfault)
[367.165624] Starting subtest: pi-userfault
[367.167966] Starting dynamic subtest: rcs0
[367.175658] Dynamic subtest rcs0: SUCCESS (0.014s)
[367.185489] Starting dynamic subtest: bcs0
[367.187785] Dynamic subtest bcs0: SUCCESS (0.009s)
[367.198250] Starting dynamic subtest: vcs0
[367.200165] Dynamic subtest vcs0: SUCCESS (0.009s)
[367.210317] Starting dynamic subtest: vcs1
[367.212437] Dynamic subtest vcs1: SUCCESS (0.008s)
[367.222496] Starting dynamic subtest: vecs0
[367.224614] Dynamic subtest vecs0: SUCCESS (0.007s)
[367.234558] Starting dynamic subtest: ccs0
[367.236743] Dynamic subtest ccs0: SUCCESS (0.007s)
[367.238906] Subtest pi-userfault: SUCCESS (0.057s)
[367.402819] [056/133] (649s left) i915_pm_rpm (sysfs-read)
[369.059271] Starting subtest: sysfs-read
[370.221460] Subtest sysfs-read: SUCCESS (1.158s)
[370.602018] [057/133] (646s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[370.719385] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[371.765172] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.046s)
[371.899627] [058/133] (644s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[372.017576] Starting subtest: y-tiled-64bpp-rotate-270
[372.021356] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[372.117136] [059/133] (644s left) kms_psr (fbc-psr-primary-blt)
[372.215128] Starting subtest: fbc-psr-primary-blt
[372.217020] Starting dynamic subtest: eDP-1
[372.220405] Dynamic subtest eDP-1: SKIP (0.000s)
[372.223796] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[372.293572] [060/133] (644s left) vgem_basic (dmabuf-export)
[372.332324] Starting subtest: dmabuf-export
[372.360198] Subtest dmabuf-export: SUCCESS (0.032s)
[372.470884] [061/133] (644s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[372.520995] Starting subtest: multi-wait-all-for-submit-available-signaled
[372.523015] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[372.634807] [062/133] (644s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[372.728458] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[372.740145] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.011s)
[372.817554] [063/133] (644s left) syncobj_timeline (single-wait-all-available-submitted)
[372.868183] Starting subtest: single-wait-all-available-submitted
[372.870114] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[372.967295] [064/133] (643s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[373.066112] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[373.069647] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[373.179485] [065/133] (643s left) kms_universal_plane (disable-primary-vs-flip)
[373.277550] Starting subtest: disable-primary-vs-flip
[373.279516] Starting dynamic subtest: pipe-A-eDP-1
[374.874838] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.594s)
[374.885310] Starting dynamic subtest: pipe-B-eDP-1
[377.137999] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.262s)
[377.148969] Starting dynamic subtest: pipe-C-eDP-1
[379.368606] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.230s)
[379.378995] Starting dynamic subtest: pipe-D-eDP-1
[381.543301] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.172s)
[381.545566] Subtest disable-primary-vs-flip: SUCCESS (8.265s)
[382.347045] [066/133] (634s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[382.449686] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[382.453672] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[382.547666] [067/133] (634s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[382.644319] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[382.656014] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[382.733944] [068/133] (634s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[382.830277] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[382.834574] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[382.904349] [069/133] (634s left) vgem_basic (bad-handle)
[382.955238] Starting subtest: bad-handle
[382.957322] Subtest bad-handle: SUCCESS (0.000s)
[383.013447] [070/133] (634s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[383.096341] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[383.098591] Starting dynamic subtest: pipe-A-eDP-1
[383.121280] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.134319] Starting dynamic subtest: pipe-B-eDP-1
[383.154846] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.164784] Starting dynamic subtest: pipe-C-eDP-1
[383.185744] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.195485] Starting dynamic subtest: pipe-D-eDP-1
[383.216935] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[383.221236] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[385.410548] [071/133] (631s left) api_intel_bb (intel-bb-blit-x)
[385.469425] Starting subtest: intel-bb-blit-x
[388.988256] Subtest intel-bb-blit-x: SUCCESS (3.518s)
[389.104652] [072/133] (628s left) kms_cursor_crc (cursor-sliding-32x10)
[389.272541] Starting subtest: cursor-sliding-32x10
[389.334822] Subtest cursor-sliding-32x10: SKIP (0.059s)
[389.630438] [073/133] (627s left) prime_self_import (export-vs-gem_close-race)
[389.664883] Starting subtest: export-vs-gem_close-race
[394.763033] Subtest export-vs-gem_close-race: SUCCESS (5.096s)
[394.843548] [074/133] (622s left) perf (non-sampling-read-error)
[394.957943] Starting subtest: non-sampling-read-error
[394.966370] Subtest non-sampling-read-error: SUCCESS (0.006s)
[395.011474] [075/133] (622s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[395.117640] Starting subtest: bad-rotation-90-y-tiled-ccs
[395.119977] Starting dynamic subtest: pipe-A-eDP-1
[395.143343] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[395.156368] Starting dynamic subtest: pipe-B-eDP-1
[395.178565] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[395.188632] Starting dynamic subtest: pipe-C-eDP-1
[395.210653] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[395.220252] Starting dynamic subtest: pipe-D-eDP-1
[395.242915] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[395.247384] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.005s)
[397.377751] [076/133] (620s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[397.489277] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[404.412811] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.928s)
[404.566610] [077/133] (612s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[404.637304] Starting subtest: invalid-bsd2-flag-on-blt
[404.639735] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[404.755398] [078/133] (612s left) kms_cursor_crc (cursor-onscreen-128x42)
[405.003522] Starting subtest: cursor-onscreen-128x42
[405.053240] Subtest cursor-onscreen-128x42: SKIP (0.043s)
[405.286745] [079/133] (612s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[405.410931] Starting subtest: modeset-atomic-cursor-hotspot
[405.414922] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[405.556798] [080/133] (612s left) gem_render_copy (linear)
[405.622797] Starting subtest: linear
[405.624808] Starting dynamic subtest: smem
[405.663019] Dynamic subtest smem: SUCCESS (0.040s)
[405.665165] Subtest linear: SUCCESS (0.041s)
[405.912100] [081/133] (611s left) prime_self_import (basic-with_one_bo_two_files)
[405.951196] Starting subtest: basic-with_one_bo_two_files
[405.974160] Subtest basic-with_one_bo_two_files: SUCCESS (0.025s)
[406.081124] [082/133] (611s left) kms_chamelium_edid (hdmi-edid-read)
[406.466344] Subtest hdmi-edid-read: SKIP (0.000s)
[407.269918] [083/133] (610s left) gem_exec_params (readonly)
[407.326265] Starting subtest: readonly
[407.331009] Subtest readonly: SUCCESS (0.002s)
[407.418776] [084/133] (610s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[407.506588] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[407.508850] Starting dynamic subtest: pipe-A-default-mode
[408.652812] Dynamic subtest pipe-A-default-mode: SKIP (1.143s)
[408.656896] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.144s)
[408.811443] [085/133] (609s left) gem_mmap_gtt (cpuset-big-copy)
[408.865358] Subtest cpuset-big-copy: SKIP (0.000s)
[408.931962] [086/133] (608s left) sriov_basic (enable-vfs-autoprobe-on)
[408.985436] Starting subtest: enable-vfs-autoprobe-on
[408.987669] Starting dynamic subtest: numvfs-1
[409.071935] Dynamic subtest numvfs-1: FAIL (0.035s)
[409.084321] Starting dynamic subtest: numvfs-2
[409.158490] Dynamic subtest numvfs-2: FAIL (0.004s)
[409.170394] Starting dynamic subtest: numvfs-3
[409.243336] Dynamic subtest numvfs-3: FAIL (0.003s)
[409.254390] Starting dynamic subtest: numvfs-4
[409.327077] Dynamic subtest numvfs-4: FAIL (0.003s)
[409.338894] Starting dynamic subtest: numvfs-5
[409.412120] Dynamic subtest numvfs-5: FAIL (0.003s)
[409.423615] Starting dynamic subtest: numvfs-6
[409.496804] Dynamic subtest numvfs-6: FAIL (0.004s)
[409.508773] Starting dynamic subtest: numvfs-7
[409.577346] Dynamic subtest numvfs-7: FAIL (0.003s)
[409.588480] Starting dynamic subtest: numvfs-random
[409.648373] Dynamic subtest numvfs-random: FAIL (0.004s)
[409.659688] Starting dynamic subtest: numvfs-all
[409.718615] Dynamic subtest numvfs-all: FAIL (0.519s)
[409.720636] Subtest enable-vfs-autoprobe-on: FAIL (0.586s)
[409.758035] [087/133] (608s left) kms_plane_scaling (planes-downscale-factor-0-75)
[409.856053] Starting subtest: planes-downscale-factor-0-75
[409.859097] Starting dynamic subtest: pipe-A
[409.875129] Dynamic subtest pipe-A: SKIP (0.019s)
[409.886075] Starting dynamic subtest: pipe-B
[409.908056] Dynamic subtest pipe-B: SKIP (0.032s)
[409.918487] Starting dynamic subtest: pipe-C
[409.940482] Dynamic subtest pipe-C: SKIP (0.032s)
[409.955090] Starting dynamic subtest: pipe-D
[409.971301] Dynamic subtest pipe-D: SKIP (0.030s)
[409.974902] Subtest planes-downscale-factor-0-75: SKIP (0.116s)
[410.069652] [088/133] (607s left) kms_plane (plane-panning-top-left)
[410.170809] Starting subtest: plane-panning-top-left
[410.172371] Starting dynamic subtest: pipe-A
[411.898785] Dynamic subtest pipe-A: SUCCESS (1.727s)
[411.909950] Starting dynamic subtest: pipe-B
[414.266016] Dynamic subtest pipe-B: SUCCESS (2.364s)
[414.268300] Subtest plane-panning-top-left: SUCCESS (4.095s)
[415.076978] [089/133] (602s left) kms_plane_cursor (overlay)
[415.170740] Starting subtest: overlay
[415.175156] Starting dynamic subtest: pipe-A-eDP-1-size-64
[417.780824] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.611s)
[417.842500] Starting dynamic subtest: pipe-A-eDP-1-size-128
[420.292587] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.460s)
[420.355591] Starting dynamic subtest: pipe-A-eDP-1-size-256
[422.918086] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.572s)
[423.279804] Starting dynamic subtest: pipe-B-eDP-1-size-64
[426.480356] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.224s)
[426.544381] Starting dynamic subtest: pipe-B-eDP-1-size-128
[429.036457] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.505s)
[429.098605] Starting dynamic subtest: pipe-B-eDP-1-size-256
[431.571660] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.482s)
[431.924426] Starting dynamic subtest: pipe-C-eDP-1-size-64
[434.830616] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.919s)
[434.870018] Starting dynamic subtest: pipe-C-eDP-1-size-128
[437.030992] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.170s)
[437.070429] Starting dynamic subtest: pipe-C-eDP-1-size-256
[439.198907] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.136s)
[439.529267] Starting dynamic subtest: pipe-D-eDP-1-size-64
[442.330009] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.810s)
[442.367752] Starting dynamic subtest: pipe-D-eDP-1-size-128
[444.462904] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.103s)
[444.502343] Starting dynamic subtest: pipe-D-eDP-1-size-256
[446.629487] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.136s)
[446.950670] Subtest overlay: SUCCESS (31.781s)
[447.788848] [090/133] (570s left) perf_pmu (busy)
[447.855460] Starting subtest: busy
[447.857923] Starting dynamic subtest: rcs0
[448.467343] Dynamic subtest rcs0: SUCCESS (0.611s)
[448.480473] Starting dynamic subtest: bcs0
[449.035611] Dynamic subtest bcs0: SUCCESS (0.566s)
[449.050131] Starting dynamic subtest: vcs0
[450.939633] Dynamic subtest vcs0: SUCCESS (1.900s)
[450.952228] Starting dynamic subtest: vcs1
[452.924464] Dynamic subtest vcs1: SUCCESS (1.981s)
[452.937542] Starting dynamic subtest: vecs0
[454.908956] Dynamic subtest vecs0: SUCCESS (1.982s)
[454.922816] Starting dynamic subtest: ccs0
[455.474381] Dynamic subtest ccs0: SUCCESS (0.564s)
[455.477311] Subtest busy: SUCCESS (7.619s)
[455.589317] [091/133] (562s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[455.693832] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[455.706435] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[455.784740] [092/133] (562s left) kms_frontbuffer_tracking (fbc-stridechange)
[455.882030] Starting subtest: fbc-stridechange
[459.169924] Subtest fbc-stridechange: SUCCESS (3.291s)
[460.300054] [093/133] (557s left) gem_pread (bench)
[460.363136] Subtest bench: SKIP (0.000s)
[460.429953] [094/133] (557s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[460.533854] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[467.354611] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.823s)
[467.492693] [095/133] (550s left) syncobj_basic (bad-fd-to-handle)
[467.555122] Starting subtest: bad-fd-to-handle
[467.557396] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[467.653248] [096/133] (550s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[467.712392] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[467.714687] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[467.817954] [097/133] (550s left) perf (mi-rpc)
[467.910449] Starting subtest: mi-rpc
[467.915081] Subtest mi-rpc: SKIP (0.000s)
[467.958651] [098/133] (550s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[468.053599] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[468.057676] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[468.139779] [099/133] (550s left) kms_vblank (ts-continuation-modeset-rpm)
[468.234627] Starting subtest: ts-continuation-modeset-rpm
[468.236488] Starting dynamic subtest: pipe-A-eDP-1
[470.747029] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.510s)
[470.762603] Starting dynamic subtest: pipe-D-eDP-1
[472.994027] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.245s)
[472.995952] Subtest ts-continuation-modeset-rpm: SUCCESS (4.759s)
[473.792311] [100/133] (544s left) perf (non-system-wide-paranoid)
[473.897989] Starting subtest: non-system-wide-paranoid
[473.921189] Subtest non-system-wide-paranoid: SUCCESS (0.024s)
[473.968838] [101/133] (544s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[474.069852] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[474.072245] Starting dynamic subtest: pipe-A-eDP-1
[474.097428] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[474.109419] Starting dynamic subtest: pipe-B-eDP-1
[474.133900] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[474.144474] Starting dynamic subtest: pipe-C-eDP-1
[474.169238] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[474.179438] Starting dynamic subtest: pipe-D-eDP-1
[474.203949] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[474.208045] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[476.289071] [102/133] (542s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[476.386501] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[485.845809] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.461s)
[485.974370] [103/133] (532s left) gem_softpin (allocator-basic)
[486.028142] Starting subtest: allocator-basic
[486.105707] Subtest allocator-basic: SUCCESS (0.076s)
[486.239372] [104/133] (532s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[486.335669] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[493.077575] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.745s)
[493.197612] [105/133] (525s left) syncobj_basic (create-signaled)
[493.253947] Starting subtest: create-signaled
[493.256095] Subtest create-signaled: SUCCESS (0.000s)
[493.366138] [106/133] (525s left) gem_mmap (basic-small-bo)
[493.422741] Subtest basic-small-bo: SKIP (0.000s)
[493.488644] [107/133] (525s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[493.589068] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[500.241827] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.655s)
[500.371214] [108/133] (518s left) kms_force_connector_basic (force-load-detect)
[500.448745] Starting subtest: force-load-detect
[500.453382] Subtest force-load-detect: SKIP (0.000s)
[500.575119] [109/133] (518s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[500.677671] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[500.679980] Starting dynamic subtest: pipe-A-eDP-1
[500.750848] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[500.767609] Starting dynamic subtest: pipe-B-eDP-1
[501.901338] Dynamic subtest pipe-B-eDP-1: SKIP (1.147s)
[501.905784] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.229s)
[503.080164] [110/133] (515s left) gem_mmap_wc (coherency)
[503.131771] Subtest coherency: SKIP (0.000s)
[503.196217] [111/133] (515s left) kms_concurrent (multi-plane-atomic-lowres)
[503.301670] Starting subtest: multi-plane-atomic-lowres
[503.303558] Starting dynamic subtest: pipe-A-eDP-1
[503.787149] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.485s)
[503.798618] Starting dynamic subtest: pipe-B-eDP-1
[505.029401] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.240s)
[505.040702] Starting dynamic subtest: pipe-C-eDP-1
[506.223069] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.192s)
[506.233897] Starting dynamic subtest: pipe-D-eDP-1
[507.477898] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.255s)
[507.480254] Subtest multi-plane-atomic-lowres: SUCCESS (4.177s)
[508.314023] [112/133] (510s left) gem_gtt_cpu_tlb
[508.458954] [113/133] (510s left) i915_module_load (reload-no-display)
[508.501741] Starting subtest: reload-no-display
[512.789410] Subtest reload-no-display: SUCCESS (4.288s)
[512.816752] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[512.817498] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[512.820525] [114/133] (506s left) i915_drm_fdinfo (idle)
[514.341889] Starting subtest: idle
[514.344249] Starting dynamic subtest: rcs0
[514.910152] Dynamic subtest rcs0: SUCCESS (0.567s)
[514.922395] Starting dynamic subtest: bcs0
[515.416325] Dynamic subtest bcs0: SUCCESS (0.504s)
[515.426772] Starting dynamic subtest: vcs0
[515.923554] Dynamic subtest vcs0: SUCCESS (0.503s)
[515.933965] Starting dynamic subtest: vcs1
[516.429463] Dynamic subtest vcs1: SUCCESS (0.503s)
[516.438736] Starting dynamic subtest: vecs0
[516.935007] Dynamic subtest vecs0: SUCCESS (0.503s)
[516.947307] Starting dynamic subtest: ccs0
[517.440954] Dynamic subtest ccs0: SUCCESS (0.503s)
[517.443764] Subtest idle: SUCCESS (3.098s)
[517.490149] [FACT i915_drm_fdinfo (idle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[517.491404] [FACT i915_drm_fdinfo (idle)] new: kernel.kmod_is_loaded.i915: true
[517.495765] [115/133] (501s left) dumb_buffer (create-clear)
[517.570157] Starting subtest: create-clear
[551.948243] Subtest create-clear: SUCCESS (34.372s)
[552.073399] [116/133] (467s left) kms_flip (flip-vs-fences-interruptible)
[552.189598] Starting subtest: flip-vs-fences-interruptible
[552.193754] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[552.286359] [117/133] (466s left) kms_big_fb (linear-8bpp-rotate-270)
[552.388488] Starting subtest: linear-8bpp-rotate-270
[552.427356] Subtest linear-8bpp-rotate-270: SKIP (0.036s)
[552.526725] [118/133] (466s left) gen9_exec_parse (bb-oversize)
[552.584139] Subtest bb-oversize: SKIP (0.000s)
[552.664099] [119/133] (466s left) gem_exec_schedule (smoketest)
[552.852586] Starting subtest: smoketest
[552.854831] Starting dynamic subtest: rcs0
[558.378498] Dynamic subtest rcs0: SUCCESS (5.480s)
[558.390491] Starting dynamic subtest: bcs0
[563.905171] Dynamic subtest bcs0: SUCCESS (5.523s)
[563.920148] Starting dynamic subtest: vcs0
[569.424046] Dynamic subtest vcs0: SUCCESS (5.516s)
[569.439093] Starting dynamic subtest: vcs1
[574.930542] Dynamic subtest vcs1: SUCCESS (5.504s)
[574.942270] Starting dynamic subtest: vecs0
[580.424245] Dynamic subtest vecs0: SUCCESS (5.494s)
[580.437454] Starting dynamic subtest: ccs0
[585.961971] Dynamic subtest ccs0: SUCCESS (5.529s)
[585.964385] Subtest smoketest: SUCCESS (33.055s)
[586.110498] [120/133] (433s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[586.319528] Starting subtest: cursor-rapid-movement-64x21
[586.370596] Subtest cursor-rapid-movement-64x21: SKIP (0.046s)
[586.610179] [121/133] (432s left) gem_mmap (basic)
[586.664268] Subtest basic: SKIP (0.000s)
[586.723976] [122/133] (432s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[586.818784] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[587.443335] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.626s)
[587.603098] [123/133] (431s left) gem_exec_balancer (hang)
[587.868084] Starting subtest: hang
[589.880929] Subtest hang: SUCCESS (2.011s)
[589.991605] [124/133] (429s left) gem_ctx_exec (basic-norecovery)
[590.050882] Starting subtest: basic-norecovery
[590.409103] Subtest basic-norecovery: SUCCESS (0.358s)
[590.535044] [125/133] (429s left) kms_hdr (static-toggle)
[590.633266] Starting subtest: static-toggle
[590.639613] Subtest static-toggle: SKIP (0.000s)
[590.708186] [126/133] (428s left) kms_properties (invalid-properties-legacy)
[590.797396] Starting subtest: invalid-properties-legacy
[590.901793] Subtest invalid-properties-legacy: SUCCESS (0.105s)
[590.973860] [127/133] (428s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[591.062677] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[595.095994] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.035s)
[596.235786] [128/133] (423s left) gem_workarounds (suspend-resume-context)
[596.307399] Starting subtest: suspend-resume-context
[611.926423] Subtest suspend-resume-context: SUCCESS (1.184s)
[612.085979] [129/133] (407s left) gem_exec_fence (keep-in-fence)
[612.268488] Starting subtest: keep-in-fence
[612.270931] Starting dynamic subtest: rcs0
[612.326403] Dynamic subtest rcs0: SUCCESS (0.057s)
[612.339002] Starting dynamic subtest: bcs0
[612.382540] Dynamic subtest bcs0: SUCCESS (0.054s)
[612.394085] Starting dynamic subtest: vcs0
[614.366196] Dynamic subtest vcs0: SUCCESS (1.978s)
[614.379641] Starting dynamic subtest: vcs1
[616.351576] Dynamic subtest vcs1: SUCCESS (1.979s)
[616.365645] Starting dynamic subtest: vecs0
[618.336977] Dynamic subtest vecs0: SUCCESS (1.980s)
[618.350475] Starting dynamic subtest: ccs0
[618.571939] Dynamic subtest ccs0: SUCCESS (0.233s)
[618.575028] Subtest keep-in-fence: SUCCESS (6.294s)
[620.399703] [130/133] (399s left) gem_mmap_gtt (hang-user)
[620.467479] Subtest hang-user: SKIP (0.000s)
[620.525519] [131/133] (399s left) kms_atomic_transition (plane-all-transition-fencing)
[620.614970] Starting subtest: plane-all-transition-fencing
[620.617434] Starting dynamic subtest: pipe-A-eDP-1
[623.112683] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[623.433497] Starting dynamic subtest: pipe-B-eDP-1
[626.646988] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.221s)
[626.960819] Subtest plane-all-transition-fencing: SUCCESS (6.340s)
[627.779125] [132/133] (392s left) gem_pxp (protected-raw-src-copy-not-readible)
[627.918869] Starting subtest: protected-raw-src-copy-not-readible
[627.976338] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.059s)
[628.104231] [133/133] (391s left) syncobj_wait (multi-wait-for-submit-submitted)
[628.162774] Starting subtest: multi-wait-for-submit-submitted
[628.266990] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[628.341274] 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.