[91.217720] Initializing watchdogs
[91.217894]   /dev/watchdog0
[91.255043] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[91.269778] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.276376] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.281370] [001/130] (960s left) kms_flip (modeset-vs-vblank-race-interruptible)
[91.462074] Starting subtest: modeset-vs-vblank-race-interruptible
[91.463862] Subtest modeset-vs-vblank-race-interruptible: SKIP (0.002s)
[91.542678] [002/130] (960s left) gem_readwrite (read-bad-handle)
[91.638303] Starting subtest: read-bad-handle
[91.640367] Subtest read-bad-handle: SUCCESS (0.000s)
[91.730862] [003/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[91.881856] Subtest x-tiled-32bpp-rotate-270: SKIP (0.000s)
[91.952205] [004/130] (960s left) kms_selftest (drm_damage_helper)
[92.045700] Starting subtest: drm_damage_helper
[92.057632] Starting dynamic subtest: drm_test_damage_iter_no_damage
[92.137419] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.080s)
[92.150400] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[92.233703] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.096s)
[92.246635] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[92.318593] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.085s)
[92.332784] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[92.428250] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.107s)
[92.441063] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[92.509369] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.082s)
[92.522745] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[92.580355] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.070s)
[92.594367] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[92.661566] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.082s)
[92.674395] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[92.750999] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.088s)
[92.763836] Starting dynamic subtest: drm_test_damage_iter_single_damage
[92.839881] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.088s)
[92.852700] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[92.921533] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.081s)
[92.934313] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[93.021562] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.099s)
[93.034463] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[93.122366] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.100s)
[93.134973] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[93.202249] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.079s)
[93.214730] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[93.288866] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.085s)
[93.302975] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[93.379780] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.091s)
[93.392487] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[93.470395] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.091s)
[93.482905] Starting dynamic subtest: drm_test_damage_iter_damage
[93.552198] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.080s)
[93.564907] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[93.643750] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.092s)
[93.656859] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[93.727930] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.083s)
[93.740580] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[93.812354] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.084s)
[93.825062] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[93.900659] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.087s)
[93.901435] Subtest drm_damage_helper: SUCCESS (1.853s)
[94.042864] [005/130] (958s left) kms_invalid_mode (clock-too-high)
[94.192993] Starting subtest: clock-too-high
[94.194884] Subtest clock-too-high: SKIP (0.000s)
[94.270125] [006/130] (957s left) gem_create (create-size-update)
[94.364383] Starting subtest: create-size-update
[94.365285] Subtest create-size-update: SUCCESS (0.000s)
[94.584365] [007/130] (957s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[94.738295] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[94.808393] [008/130] (957s left) kms_addfb_basic (bad-pitch-65536)
[94.901692] Starting subtest: bad-pitch-65536
[94.902598] Subtest bad-pitch-65536: SUCCESS (0.001s)
[95.053230] [009/130] (957s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[95.207982] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[95.281887] [010/130] (957s left) kms_chamelium_frames (dp-crc-single)
[95.434418] Subtest dp-crc-single: SKIP (0.000s)
[95.506829] [011/130] (957s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[95.603076] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[95.709971] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[95.785062] [012/130] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[95.932943] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[96.011868] [013/130] (956s left) gen9_exec_parse (bb-start-param)
[96.136600] Starting subtest: bb-start-param
[96.141132] Subtest bb-start-param: SUCCESS (0.005s)
[96.360421] [014/130] (956s left) kms_addfb_basic (too-wide)
[96.511064] Starting subtest: too-wide
[96.511941] Subtest too-wide: SUCCESS (0.000s)
[96.600309] [015/130] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[96.747918] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[96.827194] [016/130] (956s left) prime_mmap (test_forked_cpu_write)
[96.924130] Starting subtest: test_forked_cpu_write
[96.925116] Starting dynamic subtest: test_forked_cpu_write-smem
[96.946419] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.023s)
[96.947415] Subtest test_forked_cpu_write: SUCCESS (0.023s)
[97.037481] [017/130] (955s left) syncobj_wait (wait-all-snapshot)
[97.136332] Starting subtest: wait-all-snapshot
[97.222947] Subtest wait-all-snapshot: SUCCESS (0.086s)
[97.294429] [018/130] (955s left) kms_psr (fbc-pr-basic)
[97.444545] Subtest fbc-pr-basic: SKIP (0.000s)
[97.513774] [019/130] (955s left) syncobj_wait (invalid-reset-bad-pad)
[97.609247] Starting subtest: invalid-reset-bad-pad
[97.610697] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[97.679430] [020/130] (955s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[97.825462] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[97.826433] Starting dynamic subtest: pipe-A
[97.828248] Dynamic subtest pipe-A: SKIP (0.000s)
[97.841069] Starting dynamic subtest: pipe-B
[97.842748] Dynamic subtest pipe-B: SKIP (0.000s)
[97.855173] Starting dynamic subtest: pipe-C
[97.856876] Dynamic subtest pipe-C: SKIP (0.000s)
[97.858432] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.001s)
[97.923190] [021/130] (955s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[98.075554] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[98.144671] [022/130] (955s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[98.293554] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[98.362461] [023/130] (955s left) kms_pm_rpm (modeset-non-lpsp)
[99.730418] Starting subtest: modeset-non-lpsp
[99.873246] Subtest modeset-non-lpsp: SKIP (0.143s)
[99.943967] [024/130] (953s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[100.080018] Starting subtest: writes-after-reads
[103.095884] Subtest writes-after-reads: SUCCESS (3.015s)
[103.219217] [025/130] (950s left) kms_frontbuffer_tracking (psr-suspend)
[103.371526] Subtest psr-suspend: SKIP (0.000s)
[103.451465] [026/130] (950s left) kms_properties (get_properties-sanity-non-atomic)
[103.597855] Starting subtest: get_properties-sanity-non-atomic
[103.600902] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[103.673646] [027/130] (950s left) kms_cursor_crc (cursor-sliding-256x85)
[103.820344] Subtest cursor-sliding-256x85: SKIP (0.000s)
[103.891611] [028/130] (949s left) i915_drm_fdinfo (memory-info-shared)
[103.993701] Starting subtest: memory-info-shared
[103.994801] Starting dynamic subtest: smem0
[104.032943] Dynamic subtest smem0: SUCCESS (0.039s)
[104.033908] Subtest memory-info-shared: SUCCESS (0.040s)
[104.108543] [029/130] (949s left) gem_render_copy (yf-tiled-to-vebox-linear)
[104.219302] Starting subtest: yf-tiled-to-vebox-linear
[104.220877] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[104.411071] [030/130] (949s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[104.649652] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[104.748589] [031/130] (949s left) perf_pmu (faulting-read)
[104.854558] Starting subtest: faulting-read
[104.855617] Starting dynamic subtest: gtt
[104.858506] Dynamic subtest gtt: SUCCESS (0.004s)
[104.873606] Starting dynamic subtest: wb
[104.874530] Dynamic subtest wb: SUCCESS (0.002s)
[104.888981] Starting dynamic subtest: wc
[104.890016] Dynamic subtest wc: SUCCESS (0.001s)
[104.904376] Starting dynamic subtest: uc
[104.905737] Dynamic subtest uc: SUCCESS (0.001s)
[104.907294] Subtest faulting-read: SUCCESS (0.010s)
[104.983263] [032/130] (949s left) kms_rotation_crc (sprite-rotation-270)
[105.131444] Starting subtest: sprite-rotation-270
[105.133235] Subtest sprite-rotation-270: SKIP (0.000s)
[105.205786] [033/130] (948s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[105.358262] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[105.428596] [034/130] (948s left) gem_ctx_param (invalid-get-no-zeromap)
[105.523231] Starting subtest: invalid-get-no-zeromap
[105.524227] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[105.597607] [035/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[105.747651] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[105.831992] [036/130] (948s left) kms_feature_discovery (psr2)
[105.982298] Starting subtest: psr2
[105.984523] Subtest psr2: SKIP (0.000s)
[106.060559] [037/130] (948s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[106.209568] Starting subtest: primary-y-tiled-reflect-x-0
[106.211476] Subtest primary-y-tiled-reflect-x-0: SKIP (0.000s)
[106.286866] [038/130] (948s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[106.436639] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[106.541501] [039/130] (948s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[107.883437] Starting subtest: modeset-non-lpsp-stress-no-wait
[107.919312] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.035s)
[107.991338] [040/130] (946s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[108.131646] Subtest extended-modeset-hang-newfb-with-reset: SKIP (0.000s)
[108.206204] [041/130] (946s left) fbdev (eof)
[108.317694] Subtest eof: SKIP (0.000s)
[108.385384] [042/130] (946s left) gem_mmap (basic-small-bo)
[108.480891] Starting subtest: basic-small-bo
[109.448602] Subtest basic-small-bo: SUCCESS (0.968s)
[109.552599] [043/130] (945s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[109.737544] Starting subtest: legacy-engines-hostile-preempt
[109.738519] Starting dynamic subtest: render
[109.834517] Dynamic subtest render: SUCCESS (0.096s)
[109.852157] Starting dynamic subtest: bsd
[109.925087] Dynamic subtest bsd: SUCCESS (0.088s)
[109.942831] Starting dynamic subtest: blt
[110.028361] Dynamic subtest blt: SUCCESS (0.099s)
[110.044058] Starting dynamic subtest: vebox
[110.131648] Dynamic subtest vebox: SUCCESS (0.102s)
[110.132966] Subtest legacy-engines-hostile-preempt: SUCCESS (0.394s)
[110.221524] [044/130] (944s left) kms_sequence (queue-idle)
[110.372703] Subtest queue-idle: SKIP (0.000s)
[110.446773] [045/130] (944s left) kms_big_fb (x-tiled-addfb-size-overflow)
[110.595582] Subtest x-tiled-addfb-size-overflow: SKIP (0.000s)
[110.668772] [046/130] (944s left) i915_pm_freq_api (freq-reset)
[110.774562] Subtest freq-reset: SKIP (0.000s)
[110.850147] [047/130] (944s left) gem_exec_gttfill (engines)
[110.967438] Starting subtest: engines
[110.968656] Starting dynamic subtest: rcs0
[143.952656] Dynamic subtest rcs0: SUCCESS (32.975s)
[143.969726] Starting dynamic subtest: bcs0
[180.415750] Dynamic subtest bcs0: SUCCESS (36.477s)
[180.432881] Starting dynamic subtest: vcs0
[214.234356] Dynamic subtest vcs0: SUCCESS (33.823s)
[214.253375] Starting dynamic subtest: vecs0
[247.984436] Dynamic subtest vecs0: SUCCESS (33.750s)
[247.986096] Subtest engines: SUCCESS (137.028s)
[248.171563] [048/130] (807s left) kms_writeback (writeback-fb-id-xrgb2101010)
[248.383802] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[248.456726] [049/130] (807s left) core_getstats
[248.629235] [050/130] (806s left) gem_pwrite (basic-self)
[248.724211] Starting subtest: basic-self
[248.957198] Subtest basic-self: SUCCESS (0.234s)
[249.046699] [051/130] (806s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[249.195469] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[249.268448] [052/130] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[249.418207] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[249.502253] [053/130] (806s left) gem_exec_schedule (smoketest)
[249.760049] Starting subtest: smoketest
[249.761030] Starting dynamic subtest: rcs0
[255.210071] Dynamic subtest rcs0: SUCCESS (5.445s)
[255.226669] Starting dynamic subtest: bcs0
[260.647919] Dynamic subtest bcs0: SUCCESS (5.437s)
[260.663927] Starting dynamic subtest: vcs0
[266.084302] Dynamic subtest vcs0: SUCCESS (5.436s)
[266.100343] Starting dynamic subtest: vecs0
[271.525197] Dynamic subtest vecs0: SUCCESS (5.440s)
[271.527038] Subtest smoketest: SUCCESS (21.761s)
[271.708023] [054/130] (784s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[271.854208] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[271.925832] [055/130] (783s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[272.072663] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[272.145834] [056/130] (783s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[272.290979] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[272.363433] [057/130] (783s left) gem_exec_schedule (u-fairslice)
[272.486229] Starting subtest: u-fairslice
[272.487288] Starting dynamic subtest: rcs0
[274.519388] Dynamic subtest rcs0: SUCCESS (2.031s)
[274.536453] Starting dynamic subtest: bcs0
[276.547198] Dynamic subtest bcs0: SUCCESS (2.028s)
[276.564517] Starting dynamic subtest: vcs0
[278.570260] Dynamic subtest vcs0: SUCCESS (2.023s)
[278.588444] Starting dynamic subtest: vecs0
[280.596493] Dynamic subtest vecs0: SUCCESS (2.024s)
[280.597420] Subtest u-fairslice: SUCCESS (8.108s)
[280.912605] [058/130] (775s left) gem_mmap_gtt (cpuset-medium-copy)
[281.004482] Starting subtest: cpuset-medium-copy
[289.380161] Subtest cpuset-medium-copy: SUCCESS (8.373s)
[289.584142] [059/130] (766s left) perf_pmu (busy-start)
[289.776169] Starting subtest: busy-start
[289.777125] Starting dynamic subtest: rcs0
[292.308741] Dynamic subtest rcs0: SUCCESS (2.531s)
[292.331994] Starting dynamic subtest: bcs0
[294.835775] Dynamic subtest bcs0: SUCCESS (2.526s)
[294.854312] Starting dynamic subtest: vcs0
[297.361337] Dynamic subtest vcs0: SUCCESS (2.524s)
[297.386125] Starting dynamic subtest: vecs0
[299.891489] Dynamic subtest vecs0: SUCCESS (2.529s)
[299.892762] Subtest busy-start: SUCCESS (10.113s)
[299.972699] [060/130] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[300.130147] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[300.214687] [061/130] (756s left) syncobj_timeline (invalid-signal-illegal-point)
[300.316325] Starting subtest: invalid-signal-illegal-point
[300.317284] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[300.391820] [062/130] (756s left) kms_async_flips (crc)
[300.537560] Subtest crc: SKIP (0.000s)
[300.610775] [063/130] (755s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[300.757881] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[300.837310] [064/130] (755s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[300.983314] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[301.056891] [065/130] (755s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[301.209813] Subtest cursor-vs-flip-legacy: SKIP (0.000s)
[301.278336] [066/130] (755s left) kms_concurrent (multi-plane-atomic-lowres)
[301.423657] Subtest multi-plane-atomic-lowres: SKIP (0.000s)
[301.496155] [067/130] (755s left) syncobj_timeline (wait-for-submit-snapshot)
[301.591365] Starting subtest: wait-for-submit-snapshot
[301.676268] Subtest wait-for-submit-snapshot: SUCCESS (0.085s)
[301.755803] [068/130] (755s left) gem_mmap_offset (open-flood)
[301.847410] Starting subtest: open-flood
[323.389265] Subtest open-flood: SUCCESS (21.536s)
[323.489943] [069/130] (733s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[323.682673] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[323.756378] [070/130] (733s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[323.904685] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[323.986710] [071/130] (733s left) gem_partial_pwrite_pread (reads-uncached)
[324.094044] Starting subtest: reads-uncached
[329.326134] Subtest reads-uncached: SUCCESS (5.232s)
[329.438855] [072/130] (727s left) gem_exec_reloc (basic-wc-cpu)
[329.546548] Starting subtest: basic-wc-cpu
[329.563529] Subtest basic-wc-cpu: SUCCESS (0.016s)
[329.673847] [073/130] (727s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[329.768039] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[329.768938] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.002s)
[329.849792] [074/130] (727s left) gem_vm_create (execbuf)
[329.955363] Starting subtest: execbuf
[329.966231] Subtest execbuf: SUCCESS (0.009s)
[330.079143] [075/130] (727s left) gem_ringfill (legacy-basic)
[330.483562] Starting subtest: legacy-basic
[330.484460] Starting dynamic subtest: default
[330.510458] Dynamic subtest default: SUCCESS (0.027s)
[330.528464] Starting dynamic subtest: render
[330.529390] Dynamic subtest render: SUCCESS (0.018s)
[330.548018] Starting dynamic subtest: bsd
[330.552740] Dynamic subtest bsd: SUCCESS (0.022s)
[330.570843] Starting dynamic subtest: bsd1
[330.574207] Dynamic subtest bsd1: SUCCESS (0.017s)
[330.591351] Starting dynamic subtest: blt
[330.594309] Dynamic subtest blt: SUCCESS (0.019s)
[330.613254] Starting dynamic subtest: vebox
[330.614334] Dynamic subtest vebox: SUCCESS (0.020s)
[330.615531] Subtest legacy-basic: SUCCESS (0.132s)
[330.699243] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[330.703057] [076/130] (726s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[330.851335] Subtest plane-all-transition-nonblocking-fencing: SKIP (0.000s)
[330.936323] [077/130] (726s left) gem_exec_whisper (basic-fds-priority-all)
[331.054057] Starting subtest: basic-fds-priority-all
[341.820249] Subtest basic-fds-priority-all: SUCCESS (10.764s)
[342.019931] [078/130] (715s left) gem_ccs (block-multicopy-inplace)
[342.123131] Subtest block-multicopy-inplace: SKIP (0.000s)
[342.215229] [079/130] (715s left) kms_getfb (getfb-handle-valid)
[342.362570] Starting subtest: getfb-handle-valid
[342.363543] Subtest getfb-handle-valid: SUCCESS (0.000s)
[342.470897] [080/130] (715s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[342.617643] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[342.715854] [081/130] (715s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[342.864917] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[342.866933] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[342.949465] [082/130] (715s left) sw_sync (alloc_fence_invalid_timeline)
[343.042613] Starting subtest: alloc_fence_invalid_timeline
[343.043665] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[343.129053] [083/130] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[343.275190] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[343.375538] [084/130] (714s left) gem_exec_fence (submit-chain)
[343.614461] Starting subtest: submit-chain
[343.638767] Subtest submit-chain: SUCCESS (0.024s)
[344.265744] [085/130] (714s left) kms_psr (pr-primary-mmap-gtt)
[344.415895] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[344.501770] [086/130] (713s left) gem_mmap_wc (invalid-flags)
[344.596185] Starting subtest: invalid-flags
[344.597540] Subtest invalid-flags: SUCCESS (0.000s)
[344.706555] [087/130] (713s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[344.854708] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[344.948332] [088/130] (713s left) kms_psr (pr-cursor-mmap-gtt)
[345.096549] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[345.181546] [089/130] (713s left) api_intel_allocator (standalone)
[345.279503] Starting subtest: standalone
[345.318962] Subtest standalone: SUCCESS (0.043s)
[345.415104] [090/130] (713s left) kms_flip (plain-flip-fb-recreate)
[345.563836] Starting subtest: plain-flip-fb-recreate
[345.565816] Subtest plain-flip-fb-recreate: SKIP (0.002s)
[345.675748] [091/130] (713s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[345.820941] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[345.916963] [092/130] (713s left) kms_plane_multiple (tiling-4)
[346.066121] Subtest tiling-4: SKIP (0.000s)
[346.153630] [093/130] (712s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[346.301180] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[346.302158] Starting dynamic subtest: pipe-A
[346.303770] Dynamic subtest pipe-A: SKIP (0.000s)
[346.322297] Starting dynamic subtest: pipe-B
[346.324040] Dynamic subtest pipe-B: SKIP (0.000s)
[346.357423] Starting dynamic subtest: pipe-C
[346.360132] Dynamic subtest pipe-C: SKIP (0.000s)
[346.362290] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.002s)
[346.448905] [094/130] (712s left) gem_exec_suspend (basic-s4-devices)
[346.563750] Starting subtest: basic-S4-devices
[346.564672] Starting dynamic subtest: smem
[356.335273] Dynamic subtest smem: SUCCESS (9.770s)
[356.336405] Subtest basic-S4-devices: SUCCESS (9.771s)
[364.553716] [095/130] (702s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[364.718420] Subtest fbc-dirtyfb-ioctl: SKIP (0.000s)
[364.805618] [096/130] (702s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[364.952626] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[364.954492] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.000s)
[365.041111] [097/130] (702s left) kms_cursor_crc (cursor-random-128x128)
[365.188421] Subtest cursor-random-128x128: SKIP (0.000s)
[365.275537] [098/130] (702s left) prime_busy (hang)
[365.380711] Starting subtest: hang
[365.381738] Starting dynamic subtest: rcs0
[371.680262] Dynamic subtest rcs0: SUCCESS (6.296s)
[371.699906] Starting dynamic subtest: bcs0
[378.721447] Dynamic subtest bcs0: SUCCESS (7.039s)
[378.743305] Starting dynamic subtest: vcs0
[385.695189] Dynamic subtest vcs0: SUCCESS (6.974s)
[385.714754] Starting dynamic subtest: vecs0
[392.671349] Dynamic subtest vecs0: SUCCESS (6.975s)
[392.672342] Subtest hang: SUCCESS (27.289s)
[392.802973] [099/130] (674s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[392.950726] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[393.046964] [100/130] (674s left) kms_lease (master-vs-lease)
[393.196125] Starting subtest: master-vs-lease
[393.200172] Subtest master-vs-lease: SUCCESS (0.004s)
[393.287841] [101/130] (674s left) kms_content_protection (type1)
[393.433293] Subtest type1: SKIP (0.000s)
[393.521138] [102/130] (674s left) i915_suspend (basic-s2idle-without-i915)
[393.610435] Starting subtest: basic-s2idle-without-i915
[415.607565] Subtest basic-s2idle-without-i915: SUCCESS (7.521s)
[415.701401] [103/130] (652s left) gem_partial_pwrite_pread (write-display)
[415.860183] Starting subtest: write-display
[428.442213] Subtest write-display: SUCCESS (12.828s)
[428.570174] [104/130] (639s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[428.723159] Subtest fbc-rgb565-draw-render: SKIP (0.000s)
[428.821003] [105/130] (639s left) kms_pipe_crc_basic (read-crc)
[428.968260] Subtest read-crc: SKIP (0.000s)
[429.052950] [106/130] (639s left) i915_query (engine-info-invalid)
[429.148721] Starting subtest: engine-info-invalid
[429.149776] Subtest engine-info-invalid: SUCCESS (0.000s)
[429.238924] [107/130] (639s left) gem_mmap_wc (read-write-distinct)
[429.331374] Starting subtest: read-write-distinct
[429.360201] Subtest read-write-distinct: SUCCESS (0.030s)
[429.478127] [108/130] (638s left) prime_mmap (test_correct)
[429.571945] Starting subtest: test_correct
[429.572821] Starting dynamic subtest: test_correct-smem
[429.578827] Dynamic subtest test_correct-smem: SUCCESS (0.007s)
[429.579749] Subtest test_correct: SUCCESS (0.008s)
[429.691215] [109/130] (638s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[429.836545] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[429.925238] [110/130] (638s left) gem_exec_schedule (in-order)
[430.052282] Starting subtest: in-order
[430.053478] Starting dynamic subtest: rcs0
[430.182218] Dynamic subtest rcs0: SUCCESS (0.130s)
[430.204102] Starting dynamic subtest: bcs0
[430.312989] Dynamic subtest bcs0: SUCCESS (0.128s)
[430.336587] Starting dynamic subtest: vcs0
[430.422984] Dynamic subtest vcs0: SUCCESS (0.111s)
[430.442720] Starting dynamic subtest: vecs0
[430.533275] Dynamic subtest vecs0: SUCCESS (0.108s)
[430.534531] Subtest in-order: SUCCESS (0.484s)
[430.897588] [111/130] (637s left) prime_mmap (test_reprime)
[430.991109] Starting subtest: test_reprime
[430.992080] Starting dynamic subtest: test_reprime-smem
[430.998286] Dynamic subtest test_reprime-smem: SUCCESS (0.007s)
[430.999209] Subtest test_reprime: SUCCESS (0.008s)
[431.118633] [112/130] (637s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[431.270602] Subtest short-flip-after-cursor-atomic-transitions: SKIP (0.000s)
[431.352263] [113/130] (637s left) prime_vgem (basic-read)
[431.457589] Starting subtest: basic-read
[431.524354] Subtest basic-read: SUCCESS (0.067s)
[431.639354] [114/130] (637s left) kms_hdr (bpc-switch)
[431.785848] Subtest bpc-switch: SKIP (0.000s)
[431.871451] [115/130] (637s left) gem_mmap_gtt (medium-copy-xy)
[431.963441] Starting subtest: medium-copy-XY
[437.341365] Subtest medium-copy-XY: SUCCESS (5.398s)
[437.467909] [116/130] (631s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[437.703715] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[437.789053] [117/130] (631s left) gem_vm_create (invalid-create)
[437.897970] Starting subtest: invalid-create
[437.898933] Subtest invalid-create: SUCCESS (0.000s)
[438.011787] [118/130] (631s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[438.163795] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.000s)
[438.251111] [119/130] (631s left) prime_self_import (reimport-vs-gem_close-race)
[438.337055] Starting subtest: reimport-vs-gem_close-race
[443.368885] Subtest reimport-vs-gem_close-race: SUCCESS (5.040s)
[443.458484] [120/130] (626s left) gem_exec_balancer (bonded-dual)
[443.578532] Starting subtest: bonded-dual
[447.731639] Subtest bonded-dual: SUCCESS (4.158s)
[447.951284] [121/130] (621s left) gem_mmap_wc (write-read)
[448.044790] Starting subtest: write-read
[448.074696] Subtest write-read: SUCCESS (0.028s)
[448.202076] [122/130] (621s left) gem_lmem_evict (dontneed-evict-race)
[448.312633] Subtest dontneed-evict-race: SKIP (0.000s)
[448.400708] [123/130] (621s left) api_intel_bb (render)
[448.497224] Starting subtest: render
[448.498239] Starting dynamic subtest: render-none-512
[448.622470] Dynamic subtest render-none-512: SUCCESS (0.123s)
[448.642871] Starting dynamic subtest: render-none-reloc-512
[448.729641] Dynamic subtest render-none-reloc-512: SUCCESS (0.109s)
[448.751484] Starting dynamic subtest: render-none-1024
[449.138315] Dynamic subtest render-none-1024: SUCCESS (0.403s)
[449.157913] Starting dynamic subtest: render-none-reloc-1024
[449.533617] Dynamic subtest render-none-reloc-1024: SUCCESS (0.398s)
[449.553125] Starting dynamic subtest: render-x-512
[449.639957] Dynamic subtest render-x-512: SUCCESS (0.105s)
[449.659574] Starting dynamic subtest: render-x-reloc-512
[449.731283] Dynamic subtest render-x-reloc-512: SUCCESS (0.090s)
[449.750452] Starting dynamic subtest: render-x-1024
[450.074300] Dynamic subtest render-x-1024: SUCCESS (0.342s)
[450.093788] Starting dynamic subtest: render-x-reloc-1024
[450.430127] Dynamic subtest render-x-reloc-1024: SUCCESS (0.355s)
[450.449687] Starting dynamic subtest: render-y-512
[450.532980] Dynamic subtest render-y-512: SUCCESS (0.101s)
[450.552747] Starting dynamic subtest: render-y-reloc-512
[450.622366] Dynamic subtest render-y-reloc-512: SUCCESS (0.089s)
[450.641535] Starting dynamic subtest: render-y-1024
[450.963697] Dynamic subtest render-y-1024: SUCCESS (0.339s)
[450.982937] Starting dynamic subtest: render-y-reloc-1024
[451.309642] Dynamic subtest render-y-reloc-1024: SUCCESS (0.345s)
[451.310604] Subtest render: SUCCESS (2.812s)
[451.425639] [124/130] (618s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[451.578307] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[451.676934] [125/130] (618s left) gem_exec_reloc (basic-write-gtt-active)
[451.784104] Starting subtest: basic-write-gtt-active
[451.922833] Subtest basic-write-gtt-active: SUCCESS (0.138s)
[452.048462] [126/130] (618s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[452.145280] Starting subtest: etime-multi-wait-all-for-submit-submitted
[452.255401] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.110s)
[452.350614] [127/130] (617s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[452.497982] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[452.591680] [128/130] (617s left) kms_properties (plane-properties-atomic)
[452.737168] Starting subtest: plane-properties-atomic
[452.738933] Subtest plane-properties-atomic: SKIP (0.000s)
[452.825854] [129/130] (617s left) vgem_basic (dmabuf-fence-before)
[452.918486] Starting subtest: dmabuf-fence-before
[452.920308] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[453.011963] [130/130] (617s left) kms_psr (psr-cursor-mmap-cpu)
[453.159923] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[453.244234] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: gem_readwrite
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_selftest
results: parsing output: 4/ for test: kms_invalid_mode
results: parsing output: 5/ for test: gem_create
results: parsing output: 6/ for test: kms_chamelium_hpd
results: parsing output: 7/ for test: kms_addfb_basic
results: parsing output: 8/ for test: kms_cursor_legacy
results: parsing output: 9/ for test: kms_chamelium_frames
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gen9_exec_parse
results: parsing output: 13/ for test: kms_addfb_basic
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: prime_mmap
results: parsing output: 16/ for test: syncobj_wait
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: syncobj_wait
results: parsing output: 19/ for test: kms_plane_scaling
results: parsing output: 20/ for test: kms_psr2_sf
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: kms_pm_rpm
results: parsing output: 23/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_properties
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: gem_render_copy
results: parsing output: 29/ for test: gem_exec_balancer
results: parsing output: 30/ for test: perf_pmu
results: parsing output: 31/ for test: kms_rotation_crc
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_ctx_param
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_feature_discovery
results: parsing output: 36/ for test: kms_rotation_crc
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_pm_rpm
results: parsing output: 39/ for test: kms_busy
results: parsing output: 40/ for test: fbdev
results: parsing output: 41/ for test: gem_mmap
results: parsing output: 42/ for test: gem_ctx_persistence
results: parsing output: 43/ for test: kms_sequence
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: i915_pm_freq_api
results: parsing output: 46/ for test: gem_exec_gttfill
results: parsing output: 47/ for test: kms_writeback
results: parsing output: 48/ for test: core_getstats
results: parsing output: 49/ for test: gem_pwrite
results: parsing output: 50/ for test: kms_psr2_sf
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_exec_schedule
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: gem_exec_schedule
results: parsing output: 57/ for test: gem_mmap_gtt
results: parsing output: 58/ for test: perf_pmu
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_async_flips
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_concurrent
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: gem_mmap_offset
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_partial_pwrite_pread
results: parsing output: 71/ for test: gem_exec_reloc
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: gem_vm_create
results: parsing output: 74/ for test: gem_ringfill
results: parsing output: 75/ for test: kms_atomic_transition
results: parsing output: 76/ for test: gem_exec_whisper
results: parsing output: 77/ for test: gem_ccs
results: parsing output: 78/ for test: kms_getfb
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_flip_scaled_crc
results: parsing output: 81/ for test: sw_sync
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: gem_mmap_wc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: api_intel_allocator
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_plane_multiple
results: parsing output: 92/ for test: kms_plane_scaling
results: parsing output: 93/ for test: gem_exec_suspend
results: parsing output: 94/ for test: kms_dirtyfb
results: parsing output: 95/ for test: kms_flip_scaled_crc
results: parsing output: 96/ for test: kms_cursor_crc
results: parsing output: 97/ for test: prime_busy
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_lease
results: parsing output: 100/ for test: kms_content_protection
results: parsing output: 101/ for test: i915_suspend
results: parsing output: 102/ for test: gem_partial_pwrite_pread
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_pipe_crc_basic
results: parsing output: 105/ for test: i915_query
results: parsing output: 106/ for test: gem_mmap_wc
results: parsing output: 107/ for test: prime_mmap
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: prime_mmap
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: prime_vgem
results: parsing output: 113/ for test: kms_hdr
results: parsing output: 114/ for test: gem_mmap_gtt
results: parsing output: 115/ for test: kms_ccs
results: parsing output: 116/ for test: gem_vm_create
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: prime_self_import
results: parsing output: 119/ for test: gem_exec_balancer
results: parsing output: 120/ for test: gem_mmap_wc
results: parsing output: 121/ for test: gem_lmem_evict
results: parsing output: 122/ for test: api_intel_bb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: kms_properties
results: parsing output: 128/ for test: vgem_basic
results: parsing output: 129/ for test: kms_psr
Done.