[86.684721] Initializing watchdogs
[86.684914]   /dev/watchdog0
[86.722949] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[86.737201] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.743308] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.748409] [001/130] (960s left) kms_flip (modeset-vs-vblank-race-interruptible)
[86.924296] Starting subtest: modeset-vs-vblank-race-interruptible
[86.927252] Subtest modeset-vs-vblank-race-interruptible: SKIP (0.002s)
[87.006429] [002/130] (960s left) gem_readwrite (read-bad-handle)
[87.099261] Starting subtest: read-bad-handle
[87.101166] Subtest read-bad-handle: SUCCESS (0.000s)
[87.196956] [003/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[87.343053] Subtest x-tiled-32bpp-rotate-270: SKIP (0.000s)
[87.412707] [004/130] (960s left) kms_selftest (drm_damage_helper)
[87.511959] Starting subtest: drm_damage_helper
[87.519377] Starting dynamic subtest: drm_test_damage_iter_no_damage
[87.609171] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.089s)
[87.622205] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[87.684599] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.073s)
[87.697497] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[87.764384] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.079s)
[87.778544] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[87.862813] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.099s)
[87.880137] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[87.941919] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.079s)
[87.954667] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[88.046807] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.105s)
[88.060293] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[88.121269] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.073s)
[88.134443] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[88.193273] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.072s)
[88.205870] Starting dynamic subtest: drm_test_damage_iter_single_damage
[88.289300] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.097s)
[88.301963] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[88.384795] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.095s)
[88.397233] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[88.466212] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.080s)
[88.478913] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[88.537295] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.071s)
[88.550129] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[88.631946] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.094s)
[88.644856] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[88.725778] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.092s)
[88.738502] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[88.821566] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.096s)
[88.834044] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[88.922307] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.101s)
[88.935451] Starting dynamic subtest: drm_test_damage_iter_damage
[89.012427] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.089s)
[89.025123] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[89.115171] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.101s)
[89.127776] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[89.198568] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.084s)
[89.211207] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[89.291982] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.092s)
[89.304939] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[89.395933] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.104s)
[89.396813] Subtest drm_damage_helper: SUCCESS (1.889s)
[89.566549] [005/130] (957s left) kms_invalid_mode (clock-too-high)
[89.717445] Starting subtest: clock-too-high
[89.719249] Subtest clock-too-high: SKIP (0.000s)
[89.789910] [006/130] (957s left) gem_create (create-size-update)
[89.884473] Starting subtest: create-size-update
[89.885522] Subtest create-size-update: SUCCESS (0.000s)
[90.104481] [007/130] (957s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[90.257348] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[90.328237] [008/130] (957s left) kms_addfb_basic (bad-pitch-65536)
[90.423067] Starting subtest: bad-pitch-65536
[90.424109] Subtest bad-pitch-65536: SUCCESS (0.001s)
[90.565952] [009/130] (957s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[90.717859] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[90.790181] [010/130] (957s left) kms_chamelium_frames (dp-crc-single)
[90.945952] Subtest dp-crc-single: SKIP (0.000s)
[91.018510] [011/130] (956s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[91.114490] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[91.221922] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[91.304500] [012/130] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[91.453248] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[91.532106] [013/130] (956s left) gen9_exec_parse (bb-start-param)
[91.662085] Starting subtest: bb-start-param
[91.665233] Subtest bb-start-param: SUCCESS (0.005s)
[91.877822] [014/130] (956s left) kms_addfb_basic (too-wide)
[92.029567] Starting subtest: too-wide
[92.030566] Subtest too-wide: SUCCESS (0.000s)
[92.122626] [015/130] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[92.271554] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[92.348327] [016/130] (956s left) prime_mmap (test_forked_cpu_write)
[92.443598] Starting subtest: test_forked_cpu_write
[92.444935] Starting dynamic subtest: test_forked_cpu_write-smem
[92.464939] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.022s)
[92.466075] Subtest test_forked_cpu_write: SUCCESS (0.023s)
[92.556703] [017/130] (955s left) syncobj_wait (wait-all-snapshot)
[92.655568] Starting subtest: wait-all-snapshot
[92.741854] Subtest wait-all-snapshot: SUCCESS (0.085s)
[92.814232] [018/130] (955s left) kms_psr (fbc-pr-basic)
[92.962679] Subtest fbc-pr-basic: SKIP (0.000s)
[93.033664] [019/130] (955s left) syncobj_wait (invalid-reset-bad-pad)
[93.129016] Starting subtest: invalid-reset-bad-pad
[93.129989] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[93.199229] [020/130] (955s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[93.347644] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[93.348570] Starting dynamic subtest: pipe-A
[93.350260] Dynamic subtest pipe-A: SKIP (0.000s)
[93.363085] Starting dynamic subtest: pipe-B
[93.364730] Dynamic subtest pipe-B: SKIP (0.000s)
[93.377084] Starting dynamic subtest: pipe-C
[93.378766] Dynamic subtest pipe-C: SKIP (0.000s)
[93.380374] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.001s)
[93.443722] [021/130] (955s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[93.595800] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[93.665360] [022/130] (955s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[93.814171] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[93.883450] [023/130] (955s left) kms_pm_rpm (modeset-non-lpsp)
[95.226258] Starting subtest: modeset-non-lpsp
[95.364374] Subtest modeset-non-lpsp: SKIP (0.138s)
[95.434408] [024/130] (953s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[95.568263] Starting subtest: writes-after-reads
[98.547957] Subtest writes-after-reads: SUCCESS (2.980s)
[98.657469] [025/130] (950s left) kms_frontbuffer_tracking (psr-suspend)
[98.807231] Subtest psr-suspend: SKIP (0.000s)
[98.889944] [026/130] (950s left) kms_properties (get_properties-sanity-non-atomic)
[99.038430] Starting subtest: get_properties-sanity-non-atomic
[99.040917] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[99.115339] [027/130] (950s left) kms_cursor_crc (cursor-sliding-256x85)
[99.261664] Subtest cursor-sliding-256x85: SKIP (0.000s)
[99.334197] [028/130] (949s left) i915_drm_fdinfo (memory-info-shared)
[99.438149] Starting subtest: memory-info-shared
[99.439290] Starting dynamic subtest: smem0
[99.477260] Dynamic subtest smem0: SUCCESS (0.039s)
[99.479842] Subtest memory-info-shared: SUCCESS (0.040s)
[99.552825] [029/130] (949s left) gem_render_copy (yf-tiled-to-vebox-linear)
[99.665119] Starting subtest: yf-tiled-to-vebox-linear
[99.666889] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[99.846845] [030/130] (949s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[100.081143] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[100.166721] [031/130] (949s left) perf_pmu (faulting-read)
[100.274060] Starting subtest: faulting-read
[100.275368] Starting dynamic subtest: gtt
[100.276409] Dynamic subtest gtt: SUCCESS (0.004s)
[100.291430] Starting dynamic subtest: wb
[100.292355] Dynamic subtest wb: SUCCESS (0.002s)
[100.307299] Starting dynamic subtest: wc
[100.308335] Dynamic subtest wc: SUCCESS (0.001s)
[100.322601] Starting dynamic subtest: uc
[100.323667] Dynamic subtest uc: SUCCESS (0.001s)
[100.326020] Subtest faulting-read: SUCCESS (0.011s)
[100.400115] [032/130] (949s left) kms_rotation_crc (sprite-rotation-270)
[100.549031] Starting subtest: sprite-rotation-270
[100.551046] Subtest sprite-rotation-270: SKIP (0.000s)
[100.623948] [033/130] (949s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[100.772013] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[100.843555] [034/130] (948s left) gem_ctx_param (invalid-get-no-zeromap)
[100.938537] Starting subtest: invalid-get-no-zeromap
[100.939516] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[101.017290] [035/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[101.164892] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[101.249036] [036/130] (948s left) kms_feature_discovery (psr2)
[101.398161] Starting subtest: psr2
[101.399971] Subtest psr2: SKIP (0.000s)
[101.471350] [037/130] (948s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[101.620480] Starting subtest: primary-y-tiled-reflect-x-0
[101.622167] Subtest primary-y-tiled-reflect-x-0: SKIP (0.000s)
[101.694411] [038/130] (948s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[101.842981] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[101.922598] [039/130] (948s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[103.262214] Starting subtest: modeset-non-lpsp-stress-no-wait
[103.300980] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.038s)
[103.378050] [040/130] (946s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[103.520065] Subtest extended-modeset-hang-newfb-with-reset: SKIP (0.000s)
[103.591487] [041/130] (946s left) fbdev (eof)
[103.701916] Subtest eof: SKIP (0.000s)
[103.771495] [042/130] (946s left) gem_mmap (basic-small-bo)
[103.863414] Starting subtest: basic-small-bo
[104.835970] Subtest basic-small-bo: SUCCESS (0.973s)
[104.938797] [043/130] (945s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[105.131091] Starting subtest: legacy-engines-hostile-preempt
[105.132234] Starting dynamic subtest: render
[105.234317] Dynamic subtest render: SUCCESS (0.103s)
[105.251355] Starting dynamic subtest: bsd
[105.331709] Dynamic subtest bsd: SUCCESS (0.095s)
[105.347678] Starting dynamic subtest: blt
[105.426359] Dynamic subtest blt: SUCCESS (0.092s)
[105.442808] Starting dynamic subtest: vebox
[105.519774] Dynamic subtest vebox: SUCCESS (0.091s)
[105.520889] Subtest legacy-engines-hostile-preempt: SUCCESS (0.390s)
[105.618741] [044/130] (944s left) kms_sequence (queue-idle)
[105.770721] Subtest queue-idle: SKIP (0.000s)
[105.845445] [045/130] (944s left) kms_big_fb (x-tiled-addfb-size-overflow)
[105.993779] Subtest x-tiled-addfb-size-overflow: SKIP (0.000s)
[106.068075] [046/130] (944s left) i915_pm_freq_api (freq-reset)
[106.171199] Subtest freq-reset: SKIP (0.000s)
[106.248997] [047/130] (944s left) gem_exec_gttfill (engines)
[106.367908] Starting subtest: engines
[106.369284] Starting dynamic subtest: rcs0
[138.965918] Dynamic subtest rcs0: SUCCESS (32.599s)
[138.984332] Starting dynamic subtest: bcs0
[175.501914] Dynamic subtest bcs0: SUCCESS (36.531s)
[175.518437] Starting dynamic subtest: vcs0
[208.989043] Dynamic subtest vcs0: SUCCESS (33.486s)
[209.006248] Starting dynamic subtest: vecs0
[242.677159] Dynamic subtest vecs0: SUCCESS (33.687s)
[242.678701] Subtest engines: SUCCESS (136.306s)
[242.865872] [048/130] (807s left) kms_writeback (writeback-fb-id-xrgb2101010)
[243.071727] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[243.146332] [049/130] (807s left) core_getstats
[243.310028] [050/130] (807s left) gem_pwrite (basic-self)
[243.404228] Starting subtest: basic-self
[243.637070] Subtest basic-self: SUCCESS (0.234s)
[243.728271] [051/130] (807s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[243.874127] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[243.946373] [052/130] (807s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[244.093905] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[244.175304] [053/130] (807s left) gem_exec_schedule (smoketest)
[244.427971] Starting subtest: smoketest
[244.429121] Starting dynamic subtest: rcs0
[249.862981] Dynamic subtest rcs0: SUCCESS (5.432s)
[249.879167] Starting dynamic subtest: bcs0
[255.304879] Dynamic subtest bcs0: SUCCESS (5.439s)
[255.321246] Starting dynamic subtest: vcs0
[260.737618] Dynamic subtest vcs0: SUCCESS (5.433s)
[260.754179] Starting dynamic subtest: vecs0
[266.178368] Dynamic subtest vecs0: SUCCESS (5.440s)
[266.179304] Subtest smoketest: SUCCESS (21.747s)
[266.369340] [054/130] (784s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[266.517498] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[266.592381] [055/130] (784s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[266.738237] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[266.812227] [056/130] (784s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[266.958380] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[267.031211] [057/130] (784s left) gem_exec_schedule (u-fairslice)
[267.151407] Starting subtest: u-fairslice
[267.152422] Starting dynamic subtest: rcs0
[269.181855] Dynamic subtest rcs0: SUCCESS (2.028s)
[269.201140] Starting dynamic subtest: bcs0
[271.209675] Dynamic subtest bcs0: SUCCESS (2.027s)
[271.227815] Starting dynamic subtest: vcs0
[273.233838] Dynamic subtest vcs0: SUCCESS (2.023s)
[273.253518] Starting dynamic subtest: vecs0
[275.258414] Dynamic subtest vecs0: SUCCESS (2.025s)
[275.259852] Subtest u-fairslice: SUCCESS (8.106s)
[275.579431] [058/130] (776s left) gem_mmap_gtt (cpuset-medium-copy)
[275.674300] Starting subtest: cpuset-medium-copy
[284.059471] Subtest cpuset-medium-copy: SUCCESS (8.388s)
[284.180405] [059/130] (767s left) perf_pmu (busy-start)
[284.368610] Starting subtest: busy-start
[284.369635] Starting dynamic subtest: rcs0
[286.900213] Dynamic subtest rcs0: SUCCESS (2.531s)
[286.919799] Starting dynamic subtest: bcs0
[289.432562] Dynamic subtest bcs0: SUCCESS (2.531s)
[289.464606] Starting dynamic subtest: vcs0
[291.964639] Dynamic subtest vcs0: SUCCESS (2.531s)
[291.983234] Starting dynamic subtest: vecs0
[294.496535] Dynamic subtest vecs0: SUCCESS (2.531s)
[294.497785] Subtest busy-start: SUCCESS (10.128s)
[294.580392] [060/130] (757s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[294.739953] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[294.822507] [061/130] (757s left) syncobj_timeline (invalid-signal-illegal-point)
[294.928519] Starting subtest: invalid-signal-illegal-point
[294.929573] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[295.008804] [062/130] (756s left) kms_async_flips (crc)
[295.157281] Subtest crc: SKIP (0.000s)
[295.230484] [063/130] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[295.376914] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[295.458882] [064/130] (756s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[295.607669] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[295.688261] [065/130] (756s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[295.842469] Subtest cursor-vs-flip-legacy: SKIP (0.000s)
[295.913323] [066/130] (756s left) kms_concurrent (multi-plane-atomic-lowres)
[296.059805] Subtest multi-plane-atomic-lowres: SKIP (0.000s)
[296.133710] [067/130] (756s left) syncobj_timeline (wait-for-submit-snapshot)
[296.230876] Starting subtest: wait-for-submit-snapshot
[296.317384] Subtest wait-for-submit-snapshot: SUCCESS (0.086s)
[296.391806] [068/130] (755s left) gem_mmap_offset (open-flood)
[296.483231] Starting subtest: open-flood
[318.018081] Subtest open-flood: SUCCESS (21.535s)
[318.125438] [069/130] (734s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[318.315625] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[318.388683] [070/130] (734s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[318.534787] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[318.620322] [071/130] (733s left) gem_partial_pwrite_pread (reads-uncached)
[318.723752] Starting subtest: reads-uncached
[324.061456] Subtest reads-uncached: SUCCESS (5.338s)
[324.176079] [072/130] (728s left) gem_exec_reloc (basic-wc-cpu)
[324.285883] Starting subtest: basic-wc-cpu
[324.301230] Subtest basic-wc-cpu: SUCCESS (0.015s)
[324.411855] [073/130] (728s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[324.506331] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[324.508413] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[324.591456] [074/130] (728s left) gem_vm_create (execbuf)
[324.697058] Starting subtest: execbuf
[324.706810] Subtest execbuf: SUCCESS (0.009s)
[324.815716] [075/130] (728s left) gem_ringfill (legacy-basic)
[325.216721] Starting subtest: legacy-basic
[325.217696] Starting dynamic subtest: default
[325.241627] Dynamic subtest default: SUCCESS (0.026s)
[325.265963] Starting dynamic subtest: render
[325.267358] Dynamic subtest render: SUCCESS (0.024s)
[325.288392] Starting dynamic subtest: bsd
[325.289421] Dynamic subtest bsd: SUCCESS (0.019s)
[325.307644] Starting dynamic subtest: bsd1
[325.308697] Dynamic subtest bsd1: SUCCESS (0.019s)
[325.328489] Starting dynamic subtest: blt
[325.340598] Dynamic subtest blt: SUCCESS (0.031s)
[325.362210] Starting dynamic subtest: vebox
[325.363224] Dynamic subtest vebox: SUCCESS (0.020s)
[325.364226] Subtest legacy-basic: SUCCESS (0.148s)
[325.448246] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[325.451992] [076/130] (727s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[325.603556] Subtest plane-all-transition-nonblocking-fencing: SKIP (0.000s)
[325.690077] [077/130] (727s left) gem_exec_whisper (basic-fds-priority-all)
[325.808931] Starting subtest: basic-fds-priority-all
[336.321041] Subtest basic-fds-priority-all: SUCCESS (10.511s)
[336.524940] [078/130] (716s left) gem_ccs (block-multicopy-inplace)
[336.631548] Subtest block-multicopy-inplace: SKIP (0.000s)
[336.718332] [079/130] (716s left) kms_getfb (getfb-handle-valid)
[336.868925] Starting subtest: getfb-handle-valid
[336.870333] Subtest getfb-handle-valid: SUCCESS (0.000s)
[336.983571] [080/130] (716s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[337.129851] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[337.225284] [081/130] (716s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[337.371132] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[337.372890] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[337.456447] [082/130] (716s left) sw_sync (alloc_fence_invalid_timeline)
[337.550623] Starting subtest: alloc_fence_invalid_timeline
[337.551688] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[337.638154] [083/130] (716s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[337.784708] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[337.874739] [084/130] (715s left) gem_exec_fence (submit-chain)
[338.116294] Starting subtest: submit-chain
[338.139368] Subtest submit-chain: SUCCESS (0.024s)
[338.757480] [085/130] (715s left) kms_psr (pr-primary-mmap-gtt)
[338.903989] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[338.990401] [086/130] (714s left) gem_mmap_wc (invalid-flags)
[339.084752] Starting subtest: invalid-flags
[339.085769] Subtest invalid-flags: SUCCESS (0.000s)
[339.200610] [087/130] (714s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[339.347123] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[339.438747] [088/130] (714s left) kms_psr (pr-cursor-mmap-gtt)
[339.585029] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[339.669620] [089/130] (714s left) api_intel_allocator (standalone)
[339.763536] Starting subtest: standalone
[339.806642] Subtest standalone: SUCCESS (0.043s)
[339.897771] [090/130] (714s left) kms_flip (plain-flip-fb-recreate)
[340.047047] Starting subtest: plain-flip-fb-recreate
[340.049972] Subtest plain-flip-fb-recreate: SKIP (0.002s)
[340.160470] [091/130] (714s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[340.309752] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[340.414749] [092/130] (714s left) kms_plane_multiple (tiling-4)
[340.562766] Subtest tiling-4: SKIP (0.000s)
[340.650345] [093/130] (713s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[340.799104] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[340.800162] Starting dynamic subtest: pipe-A
[340.802366] Dynamic subtest pipe-A: SKIP (0.000s)
[340.820229] Starting dynamic subtest: pipe-B
[340.822223] Dynamic subtest pipe-B: SKIP (0.000s)
[340.843250] Starting dynamic subtest: pipe-C
[340.846148] Dynamic subtest pipe-C: SKIP (0.000s)
[340.848538] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.002s)
[340.928481] [094/130] (713s left) gem_exec_suspend (basic-s4-devices)
[341.046331] Starting subtest: basic-S4-devices
[341.047220] Starting dynamic subtest: smem
[350.904112] Dynamic subtest smem: SUCCESS (9.858s)
[350.905749] Subtest basic-S4-devices: SUCCESS (9.859s)
[358.118838] [095/130] (703s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[358.275649] Subtest fbc-dirtyfb-ioctl: SKIP (0.000s)
[358.364323] [096/130] (703s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[358.512544] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[358.514433] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.000s)
[358.600652] [097/130] (703s left) kms_cursor_crc (cursor-random-128x128)
[358.747354] Subtest cursor-random-128x128: SKIP (0.000s)
[358.834964] [098/130] (703s left) prime_busy (hang)
[358.944382] Starting subtest: hang
[358.947337] Starting dynamic subtest: rcs0
[365.663457] Dynamic subtest rcs0: SUCCESS (6.716s)
[365.683406] Starting dynamic subtest: bcs0
[372.703310] Dynamic subtest bcs0: SUCCESS (7.039s)
[372.723190] Starting dynamic subtest: vcs0
[379.679400] Dynamic subtest vcs0: SUCCESS (6.975s)
[379.700210] Starting dynamic subtest: vecs0
[386.720014] Dynamic subtest vecs0: SUCCESS (7.039s)
[386.721083] Subtest hang: SUCCESS (27.774s)
[386.839586] [099/130] (675s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[386.988188] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[387.086798] [100/130] (675s left) kms_lease (master-vs-lease)
[387.237372] Starting subtest: master-vs-lease
[387.240409] Subtest master-vs-lease: SUCCESS (0.003s)
[387.330633] [101/130] (674s left) kms_content_protection (type1)
[387.479157] Subtest type1: SKIP (0.000s)
[387.566557] [102/130] (674s left) i915_suspend (basic-s2idle-without-i915)
[387.656643] Starting subtest: basic-s2idle-without-i915
[407.447899] Subtest basic-s2idle-without-i915: SUCCESS (5.446s)
[414.551413] [103/130] (654s left) gem_partial_pwrite_pread (write-display)
[414.681102] Starting subtest: write-display
[427.992428] Subtest write-display: SUCCESS (13.254s)
[428.111765] [104/130] (641s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[428.551492] Subtest fbc-rgb565-draw-render: SKIP (0.000s)
[428.646249] [105/130] (641s left) kms_pipe_crc_basic (read-crc)
[428.799804] Subtest read-crc: SKIP (0.000s)
[428.889294] [106/130] (640s left) i915_query (engine-info-invalid)
[428.985255] Starting subtest: engine-info-invalid
[428.986264] Subtest engine-info-invalid: SUCCESS (0.000s)
[429.076626] [107/130] (640s left) gem_mmap_wc (read-write-distinct)
[429.171375] Starting subtest: read-write-distinct
[429.202375] Subtest read-write-distinct: SUCCESS (0.029s)
[429.322920] [108/130] (640s left) prime_mmap (test_correct)
[429.420294] Starting subtest: test_correct
[429.421444] Starting dynamic subtest: test_correct-smem
[429.428861] Dynamic subtest test_correct-smem: SUCCESS (0.008s)
[429.430151] Subtest test_correct: SUCCESS (0.009s)
[429.536395] [109/130] (640s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[429.691720] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[429.783270] [110/130] (640s left) gem_exec_schedule (in-order)
[429.913858] Starting subtest: in-order
[429.914896] Starting dynamic subtest: rcs0
[430.049553] Dynamic subtest rcs0: SUCCESS (0.133s)
[430.077734] Starting dynamic subtest: bcs0
[430.180511] Dynamic subtest bcs0: SUCCESS (0.130s)
[430.201999] Starting dynamic subtest: vcs0
[430.303534] Dynamic subtest vcs0: SUCCESS (0.122s)
[430.323941] Starting dynamic subtest: vecs0
[430.420753] Dynamic subtest vecs0: SUCCESS (0.116s)
[430.422809] Subtest in-order: SUCCESS (0.507s)
[430.755622] [111/130] (639s left) prime_mmap (test_reprime)
[430.851687] Starting subtest: test_reprime
[430.852566] Starting dynamic subtest: test_reprime-smem
[430.858188] Dynamic subtest test_reprime-smem: SUCCESS (0.007s)
[430.859167] Subtest test_reprime: SUCCESS (0.008s)
[430.956802] [112/130] (639s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[431.115386] Subtest short-flip-after-cursor-atomic-transitions: SKIP (0.000s)
[431.200873] [113/130] (639s left) prime_vgem (basic-read)
[431.310616] Starting subtest: basic-read
[431.383155] Subtest basic-read: SUCCESS (0.066s)
[431.497605] [114/130] (638s left) kms_hdr (bpc-switch)
[431.646093] Subtest bpc-switch: SKIP (0.000s)
[431.735254] [115/130] (638s left) gem_mmap_gtt (medium-copy-xy)
[431.953063] Starting subtest: medium-copy-XY
[437.575587] Subtest medium-copy-XY: SUCCESS (5.614s)
[437.702872] [116/130] (632s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[438.167440] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[438.258796] [117/130] (632s left) gem_vm_create (invalid-create)
[438.373069] Starting subtest: invalid-create
[438.374668] Subtest invalid-create: SUCCESS (0.001s)
[438.479182] [118/130] (632s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[438.640339] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.000s)
[438.730880] [119/130] (632s left) prime_self_import (reimport-vs-gem_close-race)
[438.821261] Starting subtest: reimport-vs-gem_close-race
[443.849308] Subtest reimport-vs-gem_close-race: SUCCESS (5.023s)
[443.936743] [120/130] (627s left) gem_exec_balancer (bonded-dual)
[444.062886] Starting subtest: bonded-dual
[448.349132] Subtest bonded-dual: SUCCESS (4.284s)
[448.562870] [121/130] (622s left) gem_mmap_wc (write-read)
[448.662393] Starting subtest: write-read
[448.694152] Subtest write-read: SUCCESS (0.029s)
[448.801125] [122/130] (622s left) gem_lmem_evict (dontneed-evict-race)
[448.918515] Subtest dontneed-evict-race: SKIP (0.000s)
[449.005763] [123/130] (622s left) api_intel_bb (render)
[449.102140] Starting subtest: render
[449.103650] Starting dynamic subtest: render-none-512
[449.222276] Dynamic subtest render-none-512: SUCCESS (0.117s)
[449.242880] Starting dynamic subtest: render-none-reloc-512
[449.327177] Dynamic subtest render-none-reloc-512: SUCCESS (0.104s)
[449.347545] Starting dynamic subtest: render-none-1024
[449.723392] Dynamic subtest render-none-1024: SUCCESS (0.397s)
[449.746564] Starting dynamic subtest: render-none-reloc-1024
[450.120891] Dynamic subtest render-none-reloc-1024: SUCCESS (0.397s)
[450.140943] Starting dynamic subtest: render-x-512
[450.224516] Dynamic subtest render-x-512: SUCCESS (0.101s)
[450.244604] Starting dynamic subtest: render-x-reloc-512
[450.313884] Dynamic subtest render-x-reloc-512: SUCCESS (0.089s)
[450.333634] Starting dynamic subtest: render-x-1024
[450.656827] Dynamic subtest render-x-1024: SUCCESS (0.341s)
[450.676602] Starting dynamic subtest: render-x-reloc-1024
[451.006924] Dynamic subtest render-x-reloc-1024: SUCCESS (0.349s)
[451.029079] Starting dynamic subtest: render-y-512
[451.109406] Dynamic subtest render-y-512: SUCCESS (0.101s)
[451.130866] Starting dynamic subtest: render-y-reloc-512
[451.198779] Dynamic subtest render-y-reloc-512: SUCCESS (0.088s)
[451.220726] Starting dynamic subtest: render-y-1024
[451.546687] Dynamic subtest render-y-1024: SUCCESS (0.346s)
[451.566590] Starting dynamic subtest: render-y-reloc-1024
[451.930055] Dynamic subtest render-y-reloc-1024: SUCCESS (0.383s)
[451.931520] Subtest render: SUCCESS (2.828s)
[452.050825] [124/130] (619s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[452.221985] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[452.319014] [125/130] (619s left) gem_exec_reloc (basic-write-gtt-active)
[452.487593] Starting subtest: basic-write-gtt-active
[452.631350] Subtest basic-write-gtt-active: SUCCESS (0.141s)
[452.810280] [126/130] (618s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[452.955250] Starting subtest: etime-multi-wait-all-for-submit-submitted
[453.069557] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[453.176180] [127/130] (618s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[453.407424] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[453.498083] [128/130] (618s left) kms_properties (plane-properties-atomic)
[453.779497] Starting subtest: plane-properties-atomic
[453.782011] Subtest plane-properties-atomic: SKIP (0.000s)
[453.871794] [129/130] (618s left) vgem_basic (dmabuf-fence-before)
[454.090231] Starting subtest: dmabuf-fence-before
[454.091768] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[454.187772] [130/130] (617s left) kms_psr (psr-cursor-mmap-cpu)
[454.417188] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[454.503596] 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.