[88.917538] Initializing watchdogs
[88.917725]   /dev/watchdog0
[88.955415] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[88.970067] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.975949] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.980982] [001/130] (960s left) kms_flip (modeset-vs-vblank-race-interruptible)
[89.163320] Starting subtest: modeset-vs-vblank-race-interruptible
[89.166066] Subtest modeset-vs-vblank-race-interruptible: SKIP (0.002s)
[89.245430] [002/130] (960s left) gem_readwrite (read-bad-handle)
[89.339462] Starting subtest: read-bad-handle
[89.341120] Subtest read-bad-handle: SUCCESS (0.000s)
[89.424886] [003/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[89.575060] Subtest x-tiled-32bpp-rotate-270: SKIP (0.000s)
[89.646194] [004/130] (960s left) kms_selftest (drm_damage_helper)
[89.743230] Starting subtest: drm_damage_helper
[89.752780] Starting dynamic subtest: drm_test_damage_iter_no_damage
[89.846744] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.095s)
[89.859495] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[89.926937] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.080s)
[89.940310] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[90.010586] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.083s)
[90.023265] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[90.102436] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.090s)
[90.115085] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[90.184333] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.081s)
[90.198691] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[90.269290] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.084s)
[90.283185] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[90.362674] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.093s)
[90.374981] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[90.441566] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.079s)
[90.454284] Starting dynamic subtest: drm_test_damage_iter_single_damage
[90.528735] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.087s)
[90.541966] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[90.622880] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.090s)
[90.635655] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[90.695137] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.074s)
[90.707739] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[90.796675] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.101s)
[90.809536] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[90.872102] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.075s)
[90.885739] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[90.967833] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.096s)
[90.981016] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[91.056835] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.089s)
[91.069360] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[91.157685] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.100s)
[91.170718] Starting dynamic subtest: drm_test_damage_iter_damage
[91.241399] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.083s)
[91.255257] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[91.329659] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.087s)
[91.343334] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[91.419905] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.090s)
[91.432414] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[91.511261] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.089s)
[91.523837] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[91.610432] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.098s)
[91.611379] Subtest drm_damage_helper: SUCCESS (1.868s)
[91.749575] [005/130] (958s left) kms_invalid_mode (clock-too-high)
[91.911111] Starting subtest: clock-too-high
[91.913921] Subtest clock-too-high: SKIP (0.000s)
[91.987616] [006/130] (957s left) gem_create (create-size-update)
[92.082966] Starting subtest: create-size-update
[92.084478] Subtest create-size-update: SUCCESS (0.000s)
[92.291810] [007/130] (957s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[92.473141] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[92.554893] [008/130] (957s left) kms_addfb_basic (bad-pitch-65536)
[92.680916] Starting subtest: bad-pitch-65536
[92.681877] Subtest bad-pitch-65536: SUCCESS (0.001s)
[92.843266] [009/130] (957s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[92.996625] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[93.067676] [010/130] (957s left) kms_chamelium_frames (dp-crc-single)
[93.220100] Subtest dp-crc-single: SKIP (0.000s)
[93.291550] [011/130] (956s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[93.387561] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[93.495059] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[93.574418] [012/130] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[93.721259] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[93.797809] [013/130] (956s left) gen9_exec_parse (bb-start-param)
[93.924388] Starting subtest: bb-start-param
[93.928199] Subtest bb-start-param: SUCCESS (0.004s)
[94.150959] [014/130] (956s left) kms_addfb_basic (too-wide)
[94.298871] Starting subtest: too-wide
[94.299795] Subtest too-wide: SUCCESS (0.000s)
[94.386068] [015/130] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[94.533591] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[94.613307] [016/130] (955s left) prime_mmap (test_forked_cpu_write)
[94.708347] Starting subtest: test_forked_cpu_write
[94.709383] Starting dynamic subtest: test_forked_cpu_write-smem
[94.730823] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.022s)
[94.731997] Subtest test_forked_cpu_write: SUCCESS (0.023s)
[94.832248] [017/130] (955s left) syncobj_wait (wait-all-snapshot)
[94.926890] Starting subtest: wait-all-snapshot
[95.013037] Subtest wait-all-snapshot: SUCCESS (0.085s)
[95.083714] [018/130] (955s left) kms_psr (fbc-pr-basic)
[95.234894] Subtest fbc-pr-basic: SKIP (0.000s)
[95.306462] [019/130] (955s left) syncobj_wait (invalid-reset-bad-pad)
[95.401941] Starting subtest: invalid-reset-bad-pad
[95.402983] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[95.476929] [020/130] (955s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[95.622851] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[95.623771] Starting dynamic subtest: pipe-A
[95.625696] Dynamic subtest pipe-A: SKIP (0.000s)
[95.638202] Starting dynamic subtest: pipe-B
[95.639877] Dynamic subtest pipe-B: SKIP (0.000s)
[95.652228] Starting dynamic subtest: pipe-C
[95.653913] Dynamic subtest pipe-C: SKIP (0.000s)
[95.655466] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.001s)
[95.718404] [021/130] (955s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[95.868856] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[95.938572] [022/130] (955s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[96.089244] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[96.160445] [023/130] (954s left) kms_pm_rpm (modeset-non-lpsp)
[97.512377] Starting subtest: modeset-non-lpsp
[97.650665] Subtest modeset-non-lpsp: SKIP (0.138s)
[97.721516] [024/130] (953s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[97.858243] Starting subtest: writes-after-reads
[100.926375] Subtest writes-after-reads: SUCCESS (3.068s)
[101.035279] [025/130] (950s left) kms_frontbuffer_tracking (psr-suspend)
[101.185105] Subtest psr-suspend: SKIP (0.000s)
[101.268178] [026/130] (950s left) kms_properties (get_properties-sanity-non-atomic)
[101.419273] Starting subtest: get_properties-sanity-non-atomic
[101.421664] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[101.496398] [027/130] (949s left) kms_cursor_crc (cursor-sliding-256x85)
[101.645185] Subtest cursor-sliding-256x85: SKIP (0.000s)
[101.715144] [028/130] (949s left) i915_drm_fdinfo (memory-info-shared)
[101.818999] Starting subtest: memory-info-shared
[101.820148] Starting dynamic subtest: smem0
[101.860821] Dynamic subtest smem0: SUCCESS (0.041s)
[101.861890] Subtest memory-info-shared: SUCCESS (0.041s)
[101.933352] [029/130] (949s left) gem_render_copy (yf-tiled-to-vebox-linear)
[102.043247] Starting subtest: yf-tiled-to-vebox-linear
[102.044871] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[102.228947] [030/130] (949s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[102.466254] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[102.552463] [031/130] (949s left) perf_pmu (faulting-read)
[102.659146] Starting subtest: faulting-read
[102.660847] Starting dynamic subtest: gtt
[102.663459] Dynamic subtest gtt: SUCCESS (0.004s)
[102.678537] Starting dynamic subtest: wb
[102.679509] Dynamic subtest wb: SUCCESS (0.002s)
[102.693950] Starting dynamic subtest: wc
[102.694944] Dynamic subtest wc: SUCCESS (0.001s)
[102.711995] Starting dynamic subtest: uc
[102.713753] Dynamic subtest uc: SUCCESS (0.001s)
[102.714764] Subtest faulting-read: SUCCESS (0.011s)
[102.798620] [032/130] (949s left) kms_rotation_crc (sprite-rotation-270)
[102.945856] Starting subtest: sprite-rotation-270
[102.947755] Subtest sprite-rotation-270: SKIP (0.000s)
[103.019909] [033/130] (948s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[103.168998] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[103.241334] [034/130] (948s left) gem_ctx_param (invalid-get-no-zeromap)
[103.337098] Starting subtest: invalid-get-no-zeromap
[103.338059] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[103.412594] [035/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[103.559453] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[103.643761] [036/130] (948s left) kms_feature_discovery (psr2)
[103.790225] Starting subtest: psr2
[103.792146] Subtest psr2: SKIP (0.000s)
[103.864756] [037/130] (948s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[104.011229] Starting subtest: primary-y-tiled-reflect-x-0
[104.012960] Subtest primary-y-tiled-reflect-x-0: SKIP (0.000s)
[104.085448] [038/130] (948s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[104.235669] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[104.320012] [039/130] (948s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[105.658436] Starting subtest: modeset-non-lpsp-stress-no-wait
[105.693461] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.034s)
[105.768440] [040/130] (946s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[105.910491] Subtest extended-modeset-hang-newfb-with-reset: SKIP (0.000s)
[105.984676] [041/130] (946s left) fbdev (eof)
[106.096492] Subtest eof: SKIP (0.000s)
[106.168028] [042/130] (946s left) gem_mmap (basic-small-bo)
[106.262333] Starting subtest: basic-small-bo
[107.425975] Subtest basic-small-bo: SUCCESS (1.164s)
[107.528523] [043/130] (945s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[107.721868] Starting subtest: legacy-engines-hostile-preempt
[107.723076] Starting dynamic subtest: render
[107.818925] Dynamic subtest render: SUCCESS (0.095s)
[107.836204] Starting dynamic subtest: bsd
[107.929587] Dynamic subtest bsd: SUCCESS (0.108s)
[107.945820] Starting dynamic subtest: blt
[108.037447] Dynamic subtest blt: SUCCESS (0.104s)
[108.053134] Starting dynamic subtest: vebox
[108.139107] Dynamic subtest vebox: SUCCESS (0.101s)
[108.143206] Subtest legacy-engines-hostile-preempt: SUCCESS (0.417s)
[108.221106] [044/130] (944s left) kms_sequence (queue-idle)
[108.374931] Subtest queue-idle: SKIP (0.000s)
[108.448730] [045/130] (944s left) kms_big_fb (x-tiled-addfb-size-overflow)
[108.594225] Subtest x-tiled-addfb-size-overflow: SKIP (0.000s)
[108.668811] [046/130] (944s left) i915_pm_freq_api (freq-reset)
[108.772612] Subtest freq-reset: SKIP (0.000s)
[108.845321] [047/130] (944s left) gem_exec_gttfill (engines)
[108.962143] Starting subtest: engines
[108.963304] Starting dynamic subtest: rcs0
[141.780767] Dynamic subtest rcs0: SUCCESS (32.814s)
[141.796592] Starting dynamic subtest: bcs0
[178.364198] Dynamic subtest bcs0: SUCCESS (36.584s)
[178.381155] Starting dynamic subtest: vcs0
[211.987031] Dynamic subtest vcs0: SUCCESS (33.621s)
[212.002915] Starting dynamic subtest: vecs0
[245.892171] Dynamic subtest vecs0: SUCCESS (33.904s)
[245.893655] Subtest engines: SUCCESS (136.926s)
[246.080344] [048/130] (806s left) kms_writeback (writeback-fb-id-xrgb2101010)
[246.289858] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[246.362565] [049/130] (806s left) core_getstats
[246.525088] [050/130] (806s left) gem_pwrite (basic-self)
[246.617690] Starting subtest: basic-self
[246.851347] Subtest basic-self: SUCCESS (0.234s)
[246.947436] [051/130] (806s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[247.093174] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[247.165495] [052/130] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[247.315687] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[247.392901] [053/130] (806s left) gem_exec_schedule (smoketest)
[247.655339] Starting subtest: smoketest
[247.656230] Starting dynamic subtest: rcs0
[253.099394] Dynamic subtest rcs0: SUCCESS (5.439s)
[253.117838] Starting dynamic subtest: bcs0
[258.538017] Dynamic subtest bcs0: SUCCESS (5.439s)
[258.554722] Starting dynamic subtest: vcs0
[263.973396] Dynamic subtest vcs0: SUCCESS (5.436s)
[263.990382] Starting dynamic subtest: vecs0
[269.410074] Dynamic subtest vecs0: SUCCESS (5.435s)
[269.411211] Subtest smoketest: SUCCESS (21.751s)
[269.588239] [054/130] (783s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[269.732828] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[269.805653] [055/130] (783s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[269.954852] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[270.026771] [056/130] (783s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[270.172587] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[270.246451] [057/130] (783s left) gem_exec_schedule (u-fairslice)
[270.365736] Starting subtest: u-fairslice
[270.366730] Starting dynamic subtest: rcs0
[272.396691] Dynamic subtest rcs0: SUCCESS (2.029s)
[272.417824] Starting dynamic subtest: bcs0
[274.423554] Dynamic subtest bcs0: SUCCESS (2.027s)
[274.442185] Starting dynamic subtest: vcs0
[276.447799] Dynamic subtest vcs0: SUCCESS (2.024s)
[276.468956] Starting dynamic subtest: vecs0
[278.473529] Dynamic subtest vecs0: SUCCESS (2.022s)
[278.474939] Subtest u-fairslice: SUCCESS (8.104s)
[278.795202] [058/130] (775s left) gem_mmap_gtt (cpuset-medium-copy)
[278.886713] Starting subtest: cpuset-medium-copy
[287.275812] Subtest cpuset-medium-copy: SUCCESS (8.388s)
[287.403642] [059/130] (766s left) perf_pmu (busy-start)
[287.592631] Starting subtest: busy-start
[287.593645] Starting dynamic subtest: rcs0
[290.129327] Dynamic subtest rcs0: SUCCESS (2.535s)
[290.148407] Starting dynamic subtest: bcs0
[292.663048] Dynamic subtest bcs0: SUCCESS (2.533s)
[292.679863] Starting dynamic subtest: vcs0
[295.195388] Dynamic subtest vcs0: SUCCESS (2.531s)
[295.218470] Starting dynamic subtest: vecs0
[297.724720] Dynamic subtest vecs0: SUCCESS (2.530s)
[297.725713] Subtest busy-start: SUCCESS (10.133s)
[297.804921] [060/130] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[297.964770] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[298.041992] [061/130] (755s left) syncobj_timeline (invalid-signal-illegal-point)
[298.144304] Starting subtest: invalid-signal-illegal-point
[298.145253] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[298.221612] [062/130] (755s left) kms_async_flips (crc)
[298.368381] Subtest crc: SKIP (0.000s)
[298.441144] [063/130] (755s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[298.588607] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[298.671958] [064/130] (755s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[298.818056] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[298.891361] [065/130] (755s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[299.044697] Subtest cursor-vs-flip-legacy: SKIP (0.000s)
[299.113216] [066/130] (755s left) kms_concurrent (multi-plane-atomic-lowres)
[299.259249] Subtest multi-plane-atomic-lowres: SKIP (0.000s)
[299.331062] [067/130] (755s left) syncobj_timeline (wait-for-submit-snapshot)
[299.427721] Starting subtest: wait-for-submit-snapshot
[299.513828] Subtest wait-for-submit-snapshot: SUCCESS (0.086s)
[299.593430] [068/130] (754s left) gem_mmap_offset (open-flood)
[299.686794] Starting subtest: open-flood
[321.218232] Subtest open-flood: SUCCESS (21.532s)
[321.329235] [069/130] (733s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[321.516435] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[321.592434] [070/130] (733s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[321.738863] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[321.823516] [071/130] (732s left) gem_partial_pwrite_pread (reads-uncached)
[321.929757] Starting subtest: reads-uncached
[327.107911] Subtest reads-uncached: SUCCESS (5.177s)
[327.222432] [072/130] (727s left) gem_exec_reloc (basic-wc-cpu)
[327.328108] Starting subtest: basic-wc-cpu
[327.344449] Subtest basic-wc-cpu: SUCCESS (0.016s)
[327.458352] [073/130] (727s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[327.552176] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[327.554162] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.002s)
[327.635220] [074/130] (727s left) gem_vm_create (execbuf)
[327.740151] Starting subtest: execbuf
[327.747703] Subtest execbuf: SUCCESS (0.008s)
[327.849502] [075/130] (727s left) gem_ringfill (legacy-basic)
[328.242350] Starting subtest: legacy-basic
[328.243280] Starting dynamic subtest: default
[328.269616] Dynamic subtest default: SUCCESS (0.026s)
[328.291237] Starting dynamic subtest: render
[328.294018] Dynamic subtest render: SUCCESS (0.024s)
[328.314110] Starting dynamic subtest: bsd
[328.315134] Dynamic subtest bsd: SUCCESS (0.018s)
[328.334403] Starting dynamic subtest: bsd1
[328.335327] Dynamic subtest bsd1: SUCCESS (0.018s)
[328.353427] Starting dynamic subtest: blt
[328.358135] Dynamic subtest blt: SUCCESS (0.022s)
[328.378113] Starting dynamic subtest: vebox
[328.379195] Dynamic subtest vebox: SUCCESS (0.020s)
[328.380369] Subtest legacy-basic: SUCCESS (0.136s)
[328.464141] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[328.468274] [076/130] (726s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[328.614756] Subtest plane-all-transition-nonblocking-fencing: SKIP (0.000s)
[328.698953] [077/130] (726s left) gem_exec_whisper (basic-fds-priority-all)
[328.818261] Starting subtest: basic-fds-priority-all
[339.320640] Subtest basic-fds-priority-all: SUCCESS (10.502s)
[339.520522] [078/130] (715s left) gem_ccs (block-multicopy-inplace)
[339.623450] Subtest block-multicopy-inplace: SKIP (0.000s)
[339.708529] [079/130] (715s left) kms_getfb (getfb-handle-valid)
[339.863222] Starting subtest: getfb-handle-valid
[339.864674] Subtest getfb-handle-valid: SUCCESS (0.000s)
[339.974905] [080/130] (715s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[340.121267] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[340.215235] [081/130] (715s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[340.361296] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[340.363132] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[340.473459] [082/130] (715s left) sw_sync (alloc_fence_invalid_timeline)
[340.565165] Starting subtest: alloc_fence_invalid_timeline
[340.566147] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[340.651379] [083/130] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[340.798801] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[340.892482] [084/130] (715s left) gem_exec_fence (submit-chain)
[341.134811] Starting subtest: submit-chain
[341.158021] Subtest submit-chain: SUCCESS (0.025s)
[341.797954] [085/130] (714s left) kms_psr (pr-primary-mmap-gtt)
[341.945507] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[342.031318] [086/130] (714s left) gem_mmap_wc (invalid-flags)
[342.123536] Starting subtest: invalid-flags
[342.124442] Subtest invalid-flags: SUCCESS (0.000s)
[342.229563] [087/130] (713s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[342.375945] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[342.472849] [088/130] (713s left) kms_psr (pr-cursor-mmap-gtt)
[342.617933] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[342.704219] [089/130] (713s left) api_intel_allocator (standalone)
[342.796245] Starting subtest: standalone
[342.838538] Subtest standalone: SUCCESS (0.043s)
[342.933198] [090/130] (713s left) kms_flip (plain-flip-fb-recreate)
[343.080974] Starting subtest: plain-flip-fb-recreate
[343.083959] Subtest plain-flip-fb-recreate: SKIP (0.002s)
[343.187056] [091/130] (713s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[343.336254] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[343.428911] [092/130] (713s left) kms_plane_multiple (tiling-4)
[343.581324] Subtest tiling-4: SKIP (0.000s)
[343.663946] [093/130] (713s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[343.808808] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[343.809687] Starting dynamic subtest: pipe-A
[343.811475] Dynamic subtest pipe-A: SKIP (0.000s)
[343.829289] Starting dynamic subtest: pipe-B
[343.830951] Dynamic subtest pipe-B: SKIP (0.000s)
[343.848465] Starting dynamic subtest: pipe-C
[343.850136] Dynamic subtest pipe-C: SKIP (0.000s)
[343.851752] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.002s)
[343.931797] [094/130] (712s left) gem_exec_suspend (basic-s4-devices)
[344.047587] Starting subtest: basic-S4-devices
[344.048578] Starting dynamic subtest: smem
[354.504240] Dynamic subtest smem: SUCCESS (10.456s)
[354.505750] Subtest basic-S4-devices: SUCCESS (10.457s)
[361.722420] [095/130] (702s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[361.876215] Subtest fbc-dirtyfb-ioctl: SKIP (0.000s)
[361.966661] [096/130] (702s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[362.113084] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[362.114753] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.000s)
[362.200497] [097/130] (701s left) kms_cursor_crc (cursor-random-128x128)
[362.347854] Subtest cursor-random-128x128: SKIP (0.000s)
[362.433586] [098/130] (701s left) prime_busy (hang)
[362.537382] Starting subtest: hang
[362.538307] Starting dynamic subtest: rcs0
[369.697669] Dynamic subtest rcs0: SUCCESS (7.156s)
[369.717662] Starting dynamic subtest: bcs0
[376.672187] Dynamic subtest bcs0: SUCCESS (6.974s)
[376.693362] Starting dynamic subtest: vcs0
[383.712594] Dynamic subtest vcs0: SUCCESS (7.039s)
[383.733309] Starting dynamic subtest: vecs0
[390.687522] Dynamic subtest vecs0: SUCCESS (6.974s)
[390.688466] Subtest hang: SUCCESS (28.150s)
[390.783681] [099/130] (673s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[390.936512] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[391.037676] [100/130] (673s left) kms_lease (master-vs-lease)
[391.188883] Starting subtest: master-vs-lease
[391.191711] Subtest master-vs-lease: SUCCESS (0.003s)
[391.278974] [101/130] (673s left) kms_content_protection (type1)
[391.425168] Subtest type1: SKIP (0.000s)
[391.513599] [102/130] (673s left) i915_suspend (basic-s2idle-without-i915)
[391.601480] Starting subtest: basic-s2idle-without-i915
[413.925345] Subtest basic-s2idle-without-i915: SUCCESS (8.028s)
[414.025833] [103/130] (650s left) gem_partial_pwrite_pread (write-display)
[414.190791] Starting subtest: write-display
[426.735869] Subtest write-display: SUCCESS (12.811s)
[426.859451] [104/130] (637s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[427.011115] Subtest fbc-rgb565-draw-render: SKIP (0.000s)
[427.109142] [105/130] (637s left) kms_pipe_crc_basic (read-crc)
[427.254991] Subtest read-crc: SKIP (0.000s)
[427.343099] [106/130] (637s left) i915_query (engine-info-invalid)
[427.437854] Starting subtest: engine-info-invalid
[427.439045] Subtest engine-info-invalid: SUCCESS (0.000s)
[427.525039] [107/130] (637s left) gem_mmap_wc (read-write-distinct)
[427.614810] Starting subtest: read-write-distinct
[427.642499] Subtest read-write-distinct: SUCCESS (0.029s)
[427.765247] [108/130] (637s left) prime_mmap (test_correct)
[427.856907] Starting subtest: test_correct
[427.857817] Starting dynamic subtest: test_correct-smem
[427.863871] Dynamic subtest test_correct-smem: SUCCESS (0.007s)
[427.864802] Subtest test_correct: SUCCESS (0.008s)
[427.970942] [109/130] (637s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[428.117600] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[428.203256] [110/130] (637s left) gem_exec_schedule (in-order)
[428.330469] Starting subtest: in-order
[428.331639] Starting dynamic subtest: rcs0
[428.457672] Dynamic subtest rcs0: SUCCESS (0.126s)
[428.479681] Starting dynamic subtest: bcs0
[428.583460] Dynamic subtest bcs0: SUCCESS (0.124s)
[428.606219] Starting dynamic subtest: vcs0
[428.699636] Dynamic subtest vcs0: SUCCESS (0.117s)
[428.720671] Starting dynamic subtest: vecs0
[428.816351] Dynamic subtest vecs0: SUCCESS (0.115s)
[428.818441] Subtest in-order: SUCCESS (0.489s)
[429.159231] [111/130] (636s left) prime_mmap (test_reprime)
[429.251289] Starting subtest: test_reprime
[429.252227] Starting dynamic subtest: test_reprime-smem
[429.257439] Dynamic subtest test_reprime-smem: SUCCESS (0.007s)
[429.258568] Subtest test_reprime: SUCCESS (0.008s)
[429.382996] [112/130] (636s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[429.532098] Subtest short-flip-after-cursor-atomic-transitions: SKIP (0.000s)
[429.614974] [113/130] (635s left) prime_vgem (basic-read)
[429.720072] Starting subtest: basic-read
[429.792517] Subtest basic-read: SUCCESS (0.067s)
[429.886364] [114/130] (635s left) kms_hdr (bpc-switch)
[430.032528] Subtest bpc-switch: SKIP (0.000s)
[430.119902] [115/130] (635s left) gem_mmap_gtt (medium-copy-xy)
[430.211803] Starting subtest: medium-copy-XY
[435.613235] Subtest medium-copy-XY: SUCCESS (5.425s)
[435.723366] [116/130] (630s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[435.953919] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[436.039198] [117/130] (629s left) gem_vm_create (invalid-create)
[436.147651] Starting subtest: invalid-create
[436.148638] Subtest invalid-create: SUCCESS (0.000s)
[436.265146] [118/130] (629s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[436.415800] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.000s)
[436.502527] [119/130] (629s left) prime_self_import (reimport-vs-gem_close-race)
[436.592839] Starting subtest: reimport-vs-gem_close-race
[441.624809] Subtest reimport-vs-gem_close-race: SUCCESS (5.045s)
[441.716971] [120/130] (624s left) gem_exec_balancer (bonded-dual)
[441.844096] Starting subtest: bonded-dual
[446.000961] Subtest bonded-dual: SUCCESS (4.163s)
[446.218301] [121/130] (620s left) gem_mmap_wc (write-read)
[446.311469] Starting subtest: write-read
[446.342647] Subtest write-read: SUCCESS (0.029s)
[446.460331] [122/130] (619s left) gem_lmem_evict (dontneed-evict-race)
[446.572790] Subtest dontneed-evict-race: SKIP (0.000s)
[446.659660] [123/130] (619s left) api_intel_bb (render)
[446.753666] Starting subtest: render
[446.754668] Starting dynamic subtest: render-none-512
[446.874528] Dynamic subtest render-none-512: SUCCESS (0.120s)
[446.894028] Starting dynamic subtest: render-none-reloc-512
[446.983706] Dynamic subtest render-none-reloc-512: SUCCESS (0.108s)
[447.003367] Starting dynamic subtest: render-none-1024
[447.381409] Dynamic subtest render-none-1024: SUCCESS (0.397s)
[447.401574] Starting dynamic subtest: render-none-reloc-1024
[447.786227] Dynamic subtest render-none-reloc-1024: SUCCESS (0.404s)
[447.806112] Starting dynamic subtest: render-x-512
[447.888347] Dynamic subtest render-x-512: SUCCESS (0.101s)
[447.908122] Starting dynamic subtest: render-x-reloc-512
[447.979700] Dynamic subtest render-x-reloc-512: SUCCESS (0.090s)
[447.999010] Starting dynamic subtest: render-x-1024
[448.323766] Dynamic subtest render-x-1024: SUCCESS (0.342s)
[448.343390] Starting dynamic subtest: render-x-reloc-1024
[448.673281] Dynamic subtest render-x-reloc-1024: SUCCESS (0.350s)
[448.693508] Starting dynamic subtest: render-y-512
[448.775418] Dynamic subtest render-y-512: SUCCESS (0.101s)
[448.795864] Starting dynamic subtest: render-y-reloc-512
[448.865795] Dynamic subtest render-y-reloc-512: SUCCESS (0.088s)
[448.885370] Starting dynamic subtest: render-y-1024
[449.203576] Dynamic subtest render-y-1024: SUCCESS (0.338s)
[449.223789] Starting dynamic subtest: render-y-reloc-1024
[449.566168] Dynamic subtest render-y-reloc-1024: SUCCESS (0.362s)
[449.567640] Subtest render: SUCCESS (2.813s)
[449.698931] [124/130] (616s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[449.849774] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[449.951886] [125/130] (616s left) gem_exec_reloc (basic-write-gtt-active)
[450.059220] Starting subtest: basic-write-gtt-active
[450.199372] Subtest basic-write-gtt-active: SUCCESS (0.140s)
[450.330482] [126/130] (616s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[450.425483] Starting subtest: etime-multi-wait-all-for-submit-submitted
[450.535520] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.110s)
[450.626845] [127/130] (616s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[450.770359] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[450.859270] [128/130] (616s left) kms_properties (plane-properties-atomic)
[451.005804] Starting subtest: plane-properties-atomic
[451.007517] Subtest plane-properties-atomic: SKIP (0.000s)
[451.094085] [129/130] (615s left) vgem_basic (dmabuf-fence-before)
[451.186694] Starting subtest: dmabuf-fence-before
[451.187868] Subtest dmabuf-fence-before: SUCCESS (0.002s)
[451.275340] [130/130] (615s left) kms_psr (psr-cursor-mmap-cpu)
[451.421502] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[451.508699] 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.