[86.345080] Initializing watchdogs
[86.345254]   /dev/watchdog0
[86.381612] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[86.395954] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.401596] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.406707] [001/130] (960s left) kms_flip (modeset-vs-vblank-race-interruptible)
[86.588340] Starting subtest: modeset-vs-vblank-race-interruptible
[86.592067] Subtest modeset-vs-vblank-race-interruptible: SKIP (0.002s)
[86.671490] [002/130] (960s left) gem_readwrite (read-bad-handle)
[86.765324] Starting subtest: read-bad-handle
[86.767241] Subtest read-bad-handle: SUCCESS (0.000s)
[86.856107] [003/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[87.002709] Subtest x-tiled-32bpp-rotate-270: SKIP (0.000s)
[87.072420] [004/130] (960s left) kms_selftest (drm_damage_helper)
[87.167608] Starting subtest: drm_damage_helper
[87.178460] Starting dynamic subtest: drm_test_damage_iter_no_damage
[87.253935] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.076s)
[87.266378] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[87.340570] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.086s)
[87.352821] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[87.432318] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.090s)
[87.446046] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[87.504500] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.072s)
[87.518178] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[87.575458] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.070s)
[87.587952] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[87.669285] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.093s)
[87.681783] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[87.770431] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.101s)
[87.784818] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[87.843437] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.072s)
[87.856030] Starting dynamic subtest: drm_test_damage_iter_single_damage
[87.916763] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.072s)
[87.932288] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[88.006505] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.091s)
[88.019529] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[88.079101] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.072s)
[88.091763] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[88.152307] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.072s)
[88.164649] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[88.248269] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.095s)
[88.260361] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[88.315660] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.069s)
[88.327704] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[88.394598] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.077s)
[88.407587] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[88.470296] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.075s)
[88.482785] Starting dynamic subtest: drm_test_damage_iter_damage
[88.554826] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.083s)
[88.567064] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[88.656706] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.102s)
[88.669802] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[88.766618] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.109s)
[88.778931] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[88.839080] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.072s)
[88.851493] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[88.923836] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.084s)
[88.924713] Subtest drm_damage_helper: SUCCESS (1.754s)
[89.075166] [005/130] (958s left) kms_invalid_mode (clock-too-high)
[89.226797] Starting subtest: clock-too-high
[89.228584] Subtest clock-too-high: SKIP (0.000s)
[89.295552] [006/130] (957s left) gem_create (create-size-update)
[89.390403] Starting subtest: create-size-update
[89.391291] Subtest create-size-update: SUCCESS (0.000s)
[89.601023] [007/130] (957s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[89.756686] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[89.826518] [008/130] (957s left) kms_addfb_basic (bad-pitch-65536)
[89.919790] Starting subtest: bad-pitch-65536
[89.921043] Subtest bad-pitch-65536: SUCCESS (0.001s)
[90.065262] [009/130] (957s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[90.212697] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[90.283242] [010/130] (957s left) kms_chamelium_frames (dp-crc-single)
[90.436477] Subtest dp-crc-single: SKIP (0.000s)
[90.507414] [011/130] (957s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[90.602300] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[90.709817] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[90.780394] [012/130] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[90.928268] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[91.004402] [013/130] (956s left) gen9_exec_parse (bb-start-param)
[91.130766] Starting subtest: bb-start-param
[91.134897] Subtest bb-start-param: SUCCESS (0.004s)
[91.347845] [014/130] (956s left) kms_addfb_basic (too-wide)
[91.497964] Starting subtest: too-wide
[91.498947] Subtest too-wide: SUCCESS (0.000s)
[91.596529] [015/130] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[91.753329] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[91.837159] [016/130] (956s left) prime_mmap (test_forked_cpu_write)
[91.931730] Starting subtest: test_forked_cpu_write
[91.933146] Starting dynamic subtest: test_forked_cpu_write-smem
[91.954117] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.022s)
[91.956272] Subtest test_forked_cpu_write: SUCCESS (0.023s)
[92.043880] [017/130] (956s left) syncobj_wait (wait-all-snapshot)
[92.141068] Starting subtest: wait-all-snapshot
[92.225650] Subtest wait-all-snapshot: SUCCESS (0.084s)
[92.296656] [018/130] (955s left) kms_psr (fbc-pr-basic)
[92.442584] Subtest fbc-pr-basic: SKIP (0.000s)
[92.513372] [019/130] (955s left) syncobj_wait (invalid-reset-bad-pad)
[92.608361] Starting subtest: invalid-reset-bad-pad
[92.609351] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[92.680355] [020/130] (955s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[92.827189] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[92.828126] Starting dynamic subtest: pipe-A
[92.830079] Dynamic subtest pipe-A: SKIP (0.000s)
[92.842196] Starting dynamic subtest: pipe-B
[92.843916] Dynamic subtest pipe-B: SKIP (0.000s)
[92.856122] Starting dynamic subtest: pipe-C
[92.857940] Dynamic subtest pipe-C: SKIP (0.000s)
[92.859756] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.001s)
[92.921334] [021/130] (955s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[93.076361] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[93.145559] [022/130] (955s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[93.294120] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[93.363579] [023/130] (955s left) kms_pm_rpm (modeset-non-lpsp)
[94.711837] Starting subtest: modeset-non-lpsp
[94.854664] Subtest modeset-non-lpsp: SKIP (0.142s)
[94.924448] [024/130] (953s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[95.057599] Starting subtest: writes-after-reads
[98.116098] Subtest writes-after-reads: SUCCESS (3.059s)
[98.223256] [025/130] (950s left) kms_frontbuffer_tracking (psr-suspend)
[98.369984] Subtest psr-suspend: SKIP (0.000s)
[98.447775] [026/130] (950s left) kms_properties (get_properties-sanity-non-atomic)
[98.594337] Starting subtest: get_properties-sanity-non-atomic
[98.596737] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[98.669507] [027/130] (950s left) kms_cursor_crc (cursor-sliding-256x85)
[98.816908] Subtest cursor-sliding-256x85: SKIP (0.000s)
[98.888950] [028/130] (949s left) i915_drm_fdinfo (memory-info-shared)
[98.995111] Starting subtest: memory-info-shared
[98.996310] Starting dynamic subtest: smem0
[99.034320] Dynamic subtest smem0: SUCCESS (0.040s)
[99.035275] Subtest memory-info-shared: SUCCESS (0.041s)
[99.112615] [029/130] (949s left) gem_render_copy (yf-tiled-to-vebox-linear)
[99.222300] Starting subtest: yf-tiled-to-vebox-linear
[99.224152] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[99.404272] [030/130] (949s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[99.639326] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[99.724703] [031/130] (949s left) perf_pmu (faulting-read)
[99.827557] Starting subtest: faulting-read
[99.828827] Starting dynamic subtest: gtt
[99.832375] Dynamic subtest gtt: SUCCESS (0.005s)
[99.847312] Starting dynamic subtest: wb
[99.848467] Dynamic subtest wb: SUCCESS (0.002s)
[99.862644] Starting dynamic subtest: wc
[99.863609] Dynamic subtest wc: SUCCESS (0.001s)
[99.877930] Starting dynamic subtest: uc
[99.879197] Dynamic subtest uc: SUCCESS (0.001s)
[99.880560] Subtest faulting-read: SUCCESS (0.010s)
[99.954673] [032/130] (949s left) kms_rotation_crc (sprite-rotation-270)
[100.102995] Starting subtest: sprite-rotation-270
[100.104686] Subtest sprite-rotation-270: SKIP (0.000s)
[100.176596] [033/130] (949s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[100.324206] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[100.396812] [034/130] (948s left) gem_ctx_param (invalid-get-no-zeromap)
[100.492253] Starting subtest: invalid-get-no-zeromap
[100.493196] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[100.569423] [035/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[100.724580] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[100.804467] [036/130] (948s left) kms_feature_discovery (psr2)
[100.951349] Starting subtest: psr2
[100.953125] Subtest psr2: SKIP (0.000s)
[101.028853] [037/130] (948s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[101.178729] Starting subtest: primary-y-tiled-reflect-x-0
[101.180514] Subtest primary-y-tiled-reflect-x-0: SKIP (0.000s)
[101.252370] [038/130] (948s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[101.399695] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[101.483632] [039/130] (948s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[102.824336] Starting subtest: modeset-non-lpsp-stress-no-wait
[102.857870] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.034s)
[102.933912] [040/130] (946s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[103.074136] Subtest extended-modeset-hang-newfb-with-reset: SKIP (0.000s)
[103.145599] [041/130] (946s left) fbdev (eof)
[103.257369] Subtest eof: SKIP (0.000s)
[103.334471] [042/130] (946s left) gem_mmap (basic-small-bo)
[103.430260] Starting subtest: basic-small-bo
[104.405112] Subtest basic-small-bo: SUCCESS (0.974s)
[104.511215] [043/130] (945s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[104.707876] Starting subtest: legacy-engines-hostile-preempt
[104.709612] Starting dynamic subtest: render
[104.814418] Dynamic subtest render: SUCCESS (0.104s)
[104.831083] Starting dynamic subtest: bsd
[104.912965] Dynamic subtest bsd: SUCCESS (0.096s)
[104.928362] Starting dynamic subtest: blt
[105.012655] Dynamic subtest blt: SUCCESS (0.098s)
[105.030687] Starting dynamic subtest: vebox
[105.108616] Dynamic subtest vebox: SUCCESS (0.094s)
[105.109812] Subtest legacy-engines-hostile-preempt: SUCCESS (0.400s)
[105.195154] [044/130] (944s left) kms_sequence (queue-idle)
[105.347789] Subtest queue-idle: SKIP (0.000s)
[105.422521] [045/130] (944s left) kms_big_fb (x-tiled-addfb-size-overflow)
[105.568965] Subtest x-tiled-addfb-size-overflow: SKIP (0.000s)
[105.641161] [046/130] (944s left) i915_pm_freq_api (freq-reset)
[105.748369] Subtest freq-reset: SKIP (0.000s)
[105.818471] [047/130] (944s left) gem_exec_gttfill (engines)
[105.936480] Starting subtest: engines
[105.937649] Starting dynamic subtest: rcs0
[139.378168] Dynamic subtest rcs0: SUCCESS (33.436s)
[139.396863] Starting dynamic subtest: bcs0
[175.606517] Dynamic subtest bcs0: SUCCESS (36.224s)
[175.625146] Starting dynamic subtest: vcs0
[209.326750] Dynamic subtest vcs0: SUCCESS (33.721s)
[209.342498] Starting dynamic subtest: vecs0
[243.134608] Dynamic subtest vecs0: SUCCESS (33.805s)
[243.136142] Subtest engines: SUCCESS (137.189s)
[243.321716] [048/130] (807s left) kms_writeback (writeback-fb-id-xrgb2101010)
[243.535147] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[243.607119] [049/130] (806s left) core_getstats
[243.770842] [050/130] (806s left) gem_pwrite (basic-self)
[243.860300] Starting subtest: basic-self
[244.096050] Subtest basic-self: SUCCESS (0.234s)
[244.185344] [051/130] (806s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[244.332430] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[244.404716] [052/130] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[244.555674] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[244.632041] [053/130] (806s left) gem_exec_schedule (smoketest)
[244.889488] Starting subtest: smoketest
[244.890567] Starting dynamic subtest: rcs0
[250.328963] Dynamic subtest rcs0: SUCCESS (5.435s)
[250.344908] Starting dynamic subtest: bcs0
[255.773963] Dynamic subtest bcs0: SUCCESS (5.443s)
[255.790260] Starting dynamic subtest: vcs0
[261.213759] Dynamic subtest vcs0: SUCCESS (5.439s)
[261.230668] Starting dynamic subtest: vecs0
[266.659087] Dynamic subtest vecs0: SUCCESS (5.446s)
[266.660097] Subtest smoketest: SUCCESS (21.766s)
[266.851694] [054/130] (784s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[266.996291] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[267.067600] [055/130] (783s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[267.212256] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[267.283730] [056/130] (783s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[267.426825] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[267.499803] [057/130] (783s left) gem_exec_schedule (u-fairslice)
[267.623407] Starting subtest: u-fairslice
[267.624273] Starting dynamic subtest: rcs0
[269.655914] Dynamic subtest rcs0: SUCCESS (2.028s)
[269.675899] Starting dynamic subtest: bcs0
[271.683712] Dynamic subtest bcs0: SUCCESS (2.030s)
[271.701315] Starting dynamic subtest: vcs0
[273.705862] Dynamic subtest vcs0: SUCCESS (2.021s)
[273.723707] Starting dynamic subtest: vecs0
[275.732679] Dynamic subtest vecs0: SUCCESS (2.025s)
[275.733791] Subtest u-fairslice: SUCCESS (8.108s)
[276.048301] [058/130] (775s left) gem_mmap_gtt (cpuset-medium-copy)
[276.146607] Starting subtest: cpuset-medium-copy
[284.541724] Subtest cpuset-medium-copy: SUCCESS (8.395s)
[284.674756] [059/130] (766s left) perf_pmu (busy-start)
[284.859831] Starting subtest: busy-start
[284.860764] Starting dynamic subtest: rcs0
[287.390420] Dynamic subtest rcs0: SUCCESS (2.531s)
[287.411503] Starting dynamic subtest: bcs0
[289.925731] Dynamic subtest bcs0: SUCCESS (2.534s)
[289.952380] Starting dynamic subtest: vcs0
[292.456585] Dynamic subtest vcs0: SUCCESS (2.530s)
[292.482262] Starting dynamic subtest: vecs0
[294.986080] Dynamic subtest vecs0: SUCCESS (2.529s)
[294.987374] Subtest busy-start: SUCCESS (10.128s)
[295.065842] [060/130] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[295.225259] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[295.307986] [061/130] (756s left) syncobj_timeline (invalid-signal-illegal-point)
[295.415494] Starting subtest: invalid-signal-illegal-point
[295.416479] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[295.489968] [062/130] (756s left) kms_async_flips (crc)
[295.639303] Subtest crc: SKIP (0.000s)
[295.716161] [063/130] (755s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[295.860799] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[295.947621] [064/130] (755s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[296.092319] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[296.166073] [065/130] (755s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[296.321266] Subtest cursor-vs-flip-legacy: SKIP (0.000s)
[296.390453] [066/130] (755s left) kms_concurrent (multi-plane-atomic-lowres)
[296.536645] Subtest multi-plane-atomic-lowres: SKIP (0.000s)
[296.608439] [067/130] (755s left) syncobj_timeline (wait-for-submit-snapshot)
[296.702363] Starting subtest: wait-for-submit-snapshot
[296.788292] Subtest wait-for-submit-snapshot: SUCCESS (0.086s)
[296.863206] [068/130] (755s left) gem_mmap_offset (open-flood)
[296.954329] Starting subtest: open-flood
[318.492261] Subtest open-flood: SUCCESS (21.537s)
[318.584676] [069/130] (733s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[318.775624] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[318.849809] [070/130] (733s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[318.997452] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[319.080056] [071/130] (733s left) gem_partial_pwrite_pread (reads-uncached)
[319.185358] Starting subtest: reads-uncached
[324.348892] Subtest reads-uncached: SUCCESS (5.164s)
[324.456975] [072/130] (727s left) gem_exec_reloc (basic-wc-cpu)
[324.563851] Starting subtest: basic-wc-cpu
[324.582157] Subtest basic-wc-cpu: SUCCESS (0.017s)
[324.693357] [073/130] (727s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[324.789257] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[324.790152] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.002s)
[324.868662] [074/130] (727s left) gem_vm_create (execbuf)
[324.974033] Starting subtest: execbuf
[324.983886] Subtest execbuf: SUCCESS (0.008s)
[325.083465] [075/130] (727s left) gem_ringfill (legacy-basic)
[325.492427] Starting subtest: legacy-basic
[325.493682] Starting dynamic subtest: default
[325.514503] Dynamic subtest default: SUCCESS (0.020s)
[325.539764] Starting dynamic subtest: render
[325.540897] Dynamic subtest render: SUCCESS (0.025s)
[325.558893] Starting dynamic subtest: bsd
[325.559978] Dynamic subtest bsd: SUCCESS (0.018s)
[325.577695] Starting dynamic subtest: bsd1
[325.587440] Dynamic subtest bsd1: SUCCESS (0.027s)
[325.608796] Starting dynamic subtest: blt
[325.609759] Dynamic subtest blt: SUCCESS (0.018s)
[325.630033] Starting dynamic subtest: vebox
[325.632602] Dynamic subtest vebox: SUCCESS (0.019s)
[325.634411] Subtest legacy-basic: SUCCESS (0.137s)
[325.717252] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[325.721113] [076/130] (726s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[325.866814] Subtest plane-all-transition-nonblocking-fencing: SKIP (0.000s)
[325.950774] [077/130] (726s left) gem_exec_whisper (basic-fds-priority-all)
[326.074858] Starting subtest: basic-fds-priority-all
[336.424178] Subtest basic-fds-priority-all: SUCCESS (10.350s)
[336.627523] [078/130] (716s left) gem_ccs (block-multicopy-inplace)
[336.732722] Subtest block-multicopy-inplace: SKIP (0.000s)
[336.825710] [079/130] (716s left) kms_getfb (getfb-handle-valid)
[336.970725] Starting subtest: getfb-handle-valid
[336.971605] Subtest getfb-handle-valid: SUCCESS (0.000s)
[337.072066] [080/130] (715s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[337.218615] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[337.310442] [081/130] (715s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[337.458547] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[337.460250] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[337.543915] [082/130] (715s left) sw_sync (alloc_fence_invalid_timeline)
[337.637996] Starting subtest: alloc_fence_invalid_timeline
[337.638906] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[337.729786] [083/130] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[337.876991] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[337.970539] [084/130] (715s left) gem_exec_fence (submit-chain)
[338.211845] Starting subtest: submit-chain
[338.235935] Subtest submit-chain: SUCCESS (0.024s)
[338.887390] [085/130] (714s left) kms_psr (pr-primary-mmap-gtt)
[339.032424] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[339.117467] [086/130] (714s left) gem_mmap_wc (invalid-flags)
[339.209300] Starting subtest: invalid-flags
[339.210214] Subtest invalid-flags: SUCCESS (0.000s)
[339.321743] [087/130] (714s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[339.468103] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[339.560194] [088/130] (714s left) kms_psr (pr-cursor-mmap-gtt)
[339.704912] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[339.789894] [089/130] (713s left) api_intel_allocator (standalone)
[339.884333] Starting subtest: standalone
[339.924873] Subtest standalone: SUCCESS (0.042s)
[340.015192] [090/130] (713s left) kms_flip (plain-flip-fb-recreate)
[340.161198] Starting subtest: plain-flip-fb-recreate
[340.163953] Subtest plain-flip-fb-recreate: SKIP (0.002s)
[340.279989] [091/130] (713s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[340.426197] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[340.525154] [092/130] (713s left) kms_plane_multiple (tiling-4)
[340.682353] Subtest tiling-4: SKIP (0.000s)
[340.769312] [093/130] (713s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[340.916284] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[340.917211] Starting dynamic subtest: pipe-A
[340.918877] Dynamic subtest pipe-A: SKIP (0.000s)
[340.936131] Starting dynamic subtest: pipe-B
[340.937798] Dynamic subtest pipe-B: SKIP (0.000s)
[340.957483] Starting dynamic subtest: pipe-C
[340.959239] Dynamic subtest pipe-C: SKIP (0.000s)
[340.961157] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.002s)
[341.044753] [094/130] (713s left) gem_exec_suspend (basic-s4-devices)
[341.160111] Starting subtest: basic-S4-devices
[341.161094] Starting dynamic subtest: smem
[351.073686] Dynamic subtest smem: SUCCESS (9.913s)
[351.074813] Subtest basic-S4-devices: SUCCESS (9.914s)
[357.290537] [095/130] (703s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[357.456332] Subtest fbc-dirtyfb-ioctl: SKIP (0.000s)
[357.549525] [096/130] (702s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[357.695123] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[357.697222] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.000s)
[357.783376] [097/130] (702s left) kms_cursor_crc (cursor-random-128x128)
[357.930462] Subtest cursor-random-128x128: SKIP (0.000s)
[358.017821] [098/130] (702s left) prime_busy (hang)
[358.122125] Starting subtest: hang
[358.123112] Starting dynamic subtest: rcs0
[364.703502] Dynamic subtest rcs0: SUCCESS (6.578s)
[364.723679] Starting dynamic subtest: bcs0
[371.679288] Dynamic subtest bcs0: SUCCESS (6.975s)
[371.699402] Starting dynamic subtest: vcs0
[378.719459] Dynamic subtest vcs0: SUCCESS (7.039s)
[378.738921] Starting dynamic subtest: vecs0
[385.694488] Dynamic subtest vecs0: SUCCESS (6.975s)
[385.695748] Subtest hang: SUCCESS (27.573s)
[385.814119] [099/130] (674s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[385.959573] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[386.056055] [100/130] (674s left) kms_lease (master-vs-lease)
[386.202550] Starting subtest: master-vs-lease
[386.206855] Subtest master-vs-lease: SUCCESS (0.003s)
[386.296654] [101/130] (674s left) kms_content_protection (type1)
[386.443313] Subtest type1: SKIP (0.000s)
[386.530930] [102/130] (674s left) i915_suspend (basic-s2idle-without-i915)
[386.621329] Starting subtest: basic-s2idle-without-i915
[408.512293] Subtest basic-s2idle-without-i915: SUCCESS (7.451s)
[412.620791] [103/130] (652s left) gem_partial_pwrite_pread (write-display)
[412.747893] Starting subtest: write-display
[426.130161] Subtest write-display: SUCCESS (13.097s)
[426.278291] [104/130] (638s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[426.432172] Subtest fbc-rgb565-draw-render: SKIP (0.000s)
[426.528038] [105/130] (638s left) kms_pipe_crc_basic (read-crc)
[426.673751] Subtest read-crc: SKIP (0.000s)
[426.762025] [106/130] (638s left) i915_query (engine-info-invalid)
[426.855969] Starting subtest: engine-info-invalid
[426.857174] Subtest engine-info-invalid: SUCCESS (0.000s)
[426.949361] [107/130] (638s left) gem_mmap_wc (read-write-distinct)
[427.041904] Starting subtest: read-write-distinct
[427.070716] Subtest read-write-distinct: SUCCESS (0.029s)
[427.189617] [108/130] (638s left) prime_mmap (test_correct)
[427.284678] Starting subtest: test_correct
[427.285572] Starting dynamic subtest: test_correct-smem
[427.291567] Dynamic subtest test_correct-smem: SUCCESS (0.007s)
[427.292791] Subtest test_correct: SUCCESS (0.008s)
[427.401862] [109/130] (638s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[427.549334] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[427.639809] [110/130] (638s left) gem_exec_schedule (in-order)
[427.764926] Starting subtest: in-order
[427.766026] Starting dynamic subtest: rcs0
[427.899472] Dynamic subtest rcs0: SUCCESS (0.130s)
[427.921536] Starting dynamic subtest: bcs0
[428.024041] Dynamic subtest bcs0: SUCCESS (0.124s)
[428.047691] Starting dynamic subtest: vcs0
[428.142579] Dynamic subtest vcs0: SUCCESS (0.116s)
[428.162682] Starting dynamic subtest: vecs0
[428.250419] Dynamic subtest vecs0: SUCCESS (0.106s)
[428.251830] Subtest in-order: SUCCESS (0.483s)
[428.605556] [111/130] (637s left) prime_mmap (test_reprime)
[428.698366] Starting subtest: test_reprime
[428.699299] Starting dynamic subtest: test_reprime-smem
[428.704218] Dynamic subtest test_reprime-smem: SUCCESS (0.007s)
[428.705432] Subtest test_reprime: SUCCESS (0.008s)
[428.821764] [112/130] (637s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[428.974483] Subtest short-flip-after-cursor-atomic-transitions: SKIP (0.000s)
[429.058213] [113/130] (636s left) prime_vgem (basic-read)
[429.165087] Starting subtest: basic-read
[429.234675] Subtest basic-read: SUCCESS (0.067s)
[429.349153] [114/130] (636s left) kms_hdr (bpc-switch)
[429.494499] Subtest bpc-switch: SKIP (0.000s)
[429.582884] [115/130] (636s left) gem_mmap_gtt (medium-copy-xy)
[429.677449] Starting subtest: medium-copy-XY
[435.108460] Subtest medium-copy-XY: SUCCESS (5.409s)
[435.237309] [116/130] (631s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[435.465488] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[435.553036] [117/130] (630s left) gem_vm_create (invalid-create)
[435.661456] Starting subtest: invalid-create
[435.662568] Subtest invalid-create: SUCCESS (0.000s)
[435.773232] [118/130] (630s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[435.924708] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.000s)
[436.012568] [119/130] (630s left) prime_self_import (reimport-vs-gem_close-race)
[436.102330] Starting subtest: reimport-vs-gem_close-race
[441.133454] Subtest reimport-vs-gem_close-race: SUCCESS (5.021s)
[441.227752] [120/130] (625s left) gem_exec_balancer (bonded-dual)
[441.349275] Starting subtest: bonded-dual
[445.553167] Subtest bonded-dual: SUCCESS (4.198s)
[445.774074] [121/130] (620s left) gem_mmap_wc (write-read)
[445.867943] Starting subtest: write-read
[445.898186] Subtest write-read: SUCCESS (0.029s)
[446.009387] [122/130] (620s left) gem_lmem_evict (dontneed-evict-race)
[446.122726] Subtest dontneed-evict-race: SKIP (0.000s)
[446.211424] [123/130] (620s left) api_intel_bb (render)
[446.304951] Starting subtest: render
[446.305986] Starting dynamic subtest: render-none-512
[446.425085] Dynamic subtest render-none-512: SUCCESS (0.120s)
[446.446435] Starting dynamic subtest: render-none-reloc-512
[446.533457] Dynamic subtest render-none-reloc-512: SUCCESS (0.107s)
[446.552852] Starting dynamic subtest: render-none-1024
[446.932575] Dynamic subtest render-none-1024: SUCCESS (0.395s)
[446.952169] Starting dynamic subtest: render-none-reloc-1024
[447.334694] Dynamic subtest render-none-reloc-1024: SUCCESS (0.404s)
[447.354213] Starting dynamic subtest: render-x-512
[447.438106] Dynamic subtest render-x-512: SUCCESS (0.101s)
[447.457563] Starting dynamic subtest: render-x-reloc-512
[447.527080] Dynamic subtest render-x-reloc-512: SUCCESS (0.088s)
[447.546671] Starting dynamic subtest: render-x-1024
[447.865536] Dynamic subtest render-x-1024: SUCCESS (0.338s)
[447.885058] Starting dynamic subtest: render-x-reloc-1024
[448.216715] Dynamic subtest render-x-reloc-1024: SUCCESS (0.349s)
[448.238131] Starting dynamic subtest: render-y-512
[448.318261] Dynamic subtest render-y-512: SUCCESS (0.101s)
[448.337422] Starting dynamic subtest: render-y-reloc-512
[448.408668] Dynamic subtest render-y-reloc-512: SUCCESS (0.089s)
[448.427647] Starting dynamic subtest: render-y-1024
[448.753624] Dynamic subtest render-y-1024: SUCCESS (0.343s)
[448.773060] Starting dynamic subtest: render-y-reloc-1024
[449.105107] Dynamic subtest render-y-reloc-1024: SUCCESS (0.349s)
[449.106198] Subtest render: SUCCESS (2.799s)
[449.229237] [124/130] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[449.378439] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[449.474655] [125/130] (617s left) gem_exec_reloc (basic-write-gtt-active)
[449.582367] Starting subtest: basic-write-gtt-active
[449.718003] Subtest basic-write-gtt-active: SUCCESS (0.135s)
[449.842236] [126/130] (617s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[449.939007] Starting subtest: etime-multi-wait-all-for-submit-submitted
[450.045416] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[450.140396] [127/130] (617s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[450.285975] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[450.374168] [128/130] (617s left) kms_properties (plane-properties-atomic)
[450.520389] Starting subtest: plane-properties-atomic
[450.522239] Subtest plane-properties-atomic: SKIP (0.000s)
[450.612561] [129/130] (616s left) vgem_basic (dmabuf-fence-before)
[450.703847] Starting subtest: dmabuf-fence-before
[450.704781] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[450.794039] [130/130] (616s left) kms_psr (psr-cursor-mmap-cpu)
[450.940766] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[451.027526] 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.