[87.080848] Initializing watchdogs
[87.081202]   /dev/watchdog0
[87.119891] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[87.134748] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.141238] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.146935] [001/130] (960s left) kms_flip (modeset-vs-vblank-race-interruptible)
[87.325630] Starting subtest: modeset-vs-vblank-race-interruptible
[87.328607] Subtest modeset-vs-vblank-race-interruptible: SKIP (0.002s)
[87.410936] [002/130] (960s left) gem_readwrite (read-bad-handle)
[87.506670] Starting subtest: read-bad-handle
[87.509897] Subtest read-bad-handle: SUCCESS (0.000s)
[87.604831] [003/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[87.752862] Subtest x-tiled-32bpp-rotate-270: SKIP (0.000s)
[87.822954] [004/130] (960s left) kms_selftest (drm_damage_helper)
[87.918316] Starting subtest: drm_damage_helper
[87.929182] Starting dynamic subtest: drm_test_damage_iter_no_damage
[88.009442] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.081s)
[88.022699] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[88.116381] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.106s)
[88.128842] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[88.211140] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.094s)
[88.223434] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[88.307083] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.097s)
[88.320051] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[88.379336] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.072s)
[88.393342] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[88.457417] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.077s)
[88.469942] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[88.562793] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.105s)
[88.575355] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[88.665512] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.100s)
[88.679183] Starting dynamic subtest: drm_test_damage_iter_single_damage
[88.760885] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.096s)
[88.773658] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[88.844828] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.083s)
[88.857745] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[88.909913] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.065s)
[88.922675] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[89.012603] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.101s)
[89.025195] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[89.105497] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.093s)
[89.117873] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[89.176673] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.071s)
[89.189064] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[89.262241] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.084s)
[89.274959] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[89.350175] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.088s)
[89.362863] Starting dynamic subtest: drm_test_damage_iter_damage
[89.425306] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.073s)
[89.439347] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[89.515124] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.089s)
[89.527507] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[89.609147] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.095s)
[89.622293] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[89.677251] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.067s)
[89.691363] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[89.781866] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.105s)
[89.782746] Subtest drm_damage_helper: SUCCESS (1.865s)
[89.938221] [005/130] (958s left) kms_invalid_mode (clock-too-high)
[90.087999] Starting subtest: clock-too-high
[90.089928] Subtest clock-too-high: SKIP (0.000s)
[90.157381] [006/130] (957s left) gem_create (create-size-update)
[90.252169] Starting subtest: create-size-update
[90.253055] Subtest create-size-update: SUCCESS (0.000s)
[90.466073] [007/130] (957s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[90.623190] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[90.692885] [008/130] (957s left) kms_addfb_basic (bad-pitch-65536)
[90.789363] Starting subtest: bad-pitch-65536
[90.790326] Subtest bad-pitch-65536: SUCCESS (0.001s)
[90.937742] [009/130] (957s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[91.085180] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[91.159418] [010/130] (957s left) kms_chamelium_frames (dp-crc-single)
[91.313272] Subtest dp-crc-single: SKIP (0.000s)
[91.384769] [011/130] (956s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[91.481950] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[91.584648] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[91.663523] [012/130] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[91.819330] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[91.900563] [013/130] (956s left) gen9_exec_parse (bb-start-param)
[92.029177] Starting subtest: bb-start-param
[92.031934] Subtest bb-start-param: SUCCESS (0.004s)
[92.247920] [014/130] (956s left) kms_addfb_basic (too-wide)
[92.394972] Starting subtest: too-wide
[92.395871] Subtest too-wide: SUCCESS (0.000s)
[92.487358] [015/130] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[92.634930] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[92.718901] [016/130] (956s left) prime_mmap (test_forked_cpu_write)
[92.814370] Starting subtest: test_forked_cpu_write
[92.815303] Starting dynamic subtest: test_forked_cpu_write-smem
[92.837864] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.023s)
[92.838834] Subtest test_forked_cpu_write: SUCCESS (0.024s)
[92.935875] [017/130] (955s left) syncobj_wait (wait-all-snapshot)
[93.031633] Starting subtest: wait-all-snapshot
[93.117102] Subtest wait-all-snapshot: SUCCESS (0.085s)
[93.189051] [018/130] (955s left) kms_psr (fbc-pr-basic)
[93.335523] Subtest fbc-pr-basic: SKIP (0.000s)
[93.406747] [019/130] (955s left) syncobj_wait (invalid-reset-bad-pad)
[93.503078] Starting subtest: invalid-reset-bad-pad
[93.504049] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[93.575323] [020/130] (955s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[93.721463] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[93.722425] Starting dynamic subtest: pipe-A
[93.724179] Dynamic subtest pipe-A: SKIP (0.000s)
[93.736864] Starting dynamic subtest: pipe-B
[93.738662] Dynamic subtest pipe-B: SKIP (0.000s)
[93.751318] Starting dynamic subtest: pipe-C
[93.752968] Dynamic subtest pipe-C: SKIP (0.000s)
[93.754643] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.001s)
[93.816605] [021/130] (955s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[93.964840] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[94.066329] [022/130] (955s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[94.213979] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[94.284332] [023/130] (955s left) kms_pm_rpm (modeset-non-lpsp)
[95.630495] Starting subtest: modeset-non-lpsp
[95.774446] Subtest modeset-non-lpsp: SKIP (0.141s)
[95.853656] [024/130] (953s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[95.985860] Starting subtest: writes-after-reads
[98.960833] Subtest writes-after-reads: SUCCESS (2.975s)
[99.065525] [025/130] (950s left) kms_frontbuffer_tracking (psr-suspend)
[99.214419] Subtest psr-suspend: SKIP (0.000s)
[99.309676] [026/130] (950s left) kms_properties (get_properties-sanity-non-atomic)
[99.456720] Starting subtest: get_properties-sanity-non-atomic
[99.459629] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[99.532651] [027/130] (950s left) kms_cursor_crc (cursor-sliding-256x85)
[99.679925] Subtest cursor-sliding-256x85: SKIP (0.000s)
[99.753575] [028/130] (949s left) i915_drm_fdinfo (memory-info-shared)
[99.857093] Starting subtest: memory-info-shared
[99.858155] Starting dynamic subtest: smem0
[99.900763] Dynamic subtest smem0: SUCCESS (0.043s)
[99.903621] Subtest memory-info-shared: SUCCESS (0.044s)
[99.978364] [029/130] (949s left) gem_render_copy (yf-tiled-to-vebox-linear)
[100.086201] Starting subtest: yf-tiled-to-vebox-linear
[100.087997] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[100.271193] [030/130] (949s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[100.505219] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[100.615780] [031/130] (949s left) perf_pmu (faulting-read)
[100.721263] Starting subtest: faulting-read
[100.723131] Starting dynamic subtest: gtt
[100.726442] Dynamic subtest gtt: SUCCESS (0.005s)
[100.741402] Starting dynamic subtest: wb
[100.742815] Dynamic subtest wb: SUCCESS (0.002s)
[100.758342] Starting dynamic subtest: wc
[100.759261] Dynamic subtest wc: SUCCESS (0.001s)
[100.773604] Starting dynamic subtest: uc
[100.774692] Dynamic subtest uc: SUCCESS (0.001s)
[100.775802] Subtest faulting-read: SUCCESS (0.011s)
[100.850920] [032/130] (949s left) kms_rotation_crc (sprite-rotation-270)
[100.997658] Starting subtest: sprite-rotation-270
[100.999422] Subtest sprite-rotation-270: SKIP (0.000s)
[101.074242] [033/130] (949s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[101.230407] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[101.306359] [034/130] (948s left) gem_ctx_param (invalid-get-no-zeromap)
[101.402123] Starting subtest: invalid-get-no-zeromap
[101.403186] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[101.487368] [035/130] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[101.649887] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[101.733959] [036/130] (948s left) kms_feature_discovery (psr2)
[101.885113] Starting subtest: psr2
[101.886705] Subtest psr2: SKIP (0.000s)
[101.960649] [037/130] (948s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[102.107273] Starting subtest: primary-y-tiled-reflect-x-0
[102.109103] Subtest primary-y-tiled-reflect-x-0: SKIP (0.000s)
[102.183651] [038/130] (948s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[102.330289] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[102.415743] [039/130] (948s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[103.747630] Starting subtest: modeset-non-lpsp-stress-no-wait
[103.784089] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.036s)
[103.865458] [040/130] (946s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[104.007820] Subtest extended-modeset-hang-newfb-with-reset: SKIP (0.000s)
[104.081687] [041/130] (946s left) fbdev (eof)
[104.190766] Subtest eof: SKIP (0.000s)
[104.266770] [042/130] (946s left) gem_mmap (basic-small-bo)
[104.361142] Starting subtest: basic-small-bo
[105.314468] Subtest basic-small-bo: SUCCESS (0.953s)
[105.422368] [043/130] (945s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[105.613783] Starting subtest: legacy-engines-hostile-preempt
[105.614860] Starting dynamic subtest: render
[105.710220] Dynamic subtest render: SUCCESS (0.095s)
[105.727374] Starting dynamic subtest: bsd
[105.799519] Dynamic subtest bsd: SUCCESS (0.087s)
[105.815634] Starting dynamic subtest: blt
[105.897139] Dynamic subtest blt: SUCCESS (0.095s)
[105.913138] Starting dynamic subtest: vebox
[105.991148] Dynamic subtest vebox: SUCCESS (0.092s)
[105.992449] Subtest legacy-engines-hostile-preempt: SUCCESS (0.377s)
[106.075620] [044/130] (944s left) kms_sequence (queue-idle)
[106.226989] Subtest queue-idle: SKIP (0.000s)
[106.303081] [045/130] (944s left) kms_big_fb (x-tiled-addfb-size-overflow)
[106.450019] Subtest x-tiled-addfb-size-overflow: SKIP (0.000s)
[106.528133] [046/130] (944s left) i915_pm_freq_api (freq-reset)
[106.632690] Subtest freq-reset: SKIP (0.000s)
[106.703918] [047/130] (944s left) gem_exec_gttfill (engines)
[106.822834] Starting subtest: engines
[106.823951] Starting dynamic subtest: rcs0
[139.725078] Dynamic subtest rcs0: SUCCESS (32.903s)
[139.742358] Starting dynamic subtest: bcs0
[176.064223] Dynamic subtest bcs0: SUCCESS (36.338s)
[176.080709] Starting dynamic subtest: vcs0
[209.649394] Dynamic subtest vcs0: SUCCESS (33.585s)
[209.665757] Starting dynamic subtest: vecs0
[243.643821] Dynamic subtest vecs0: SUCCESS (33.994s)
[243.644785] Subtest engines: SUCCESS (136.823s)
[243.833650] [048/130] (807s left) kms_writeback (writeback-fb-id-xrgb2101010)
[244.046905] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[244.119739] [049/130] (807s left) core_getstats
[244.282742] [050/130] (807s left) gem_pwrite (basic-self)
[244.376100] Starting subtest: basic-self
[244.613315] Subtest basic-self: SUCCESS (0.235s)
[244.701290] [051/130] (806s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[244.850642] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[244.924217] [052/130] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[245.071115] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[245.155345] [053/130] (806s left) gem_exec_schedule (smoketest)
[245.410354] Starting subtest: smoketest
[245.411317] Starting dynamic subtest: rcs0
[250.853932] Dynamic subtest rcs0: SUCCESS (5.439s)
[250.870166] Starting dynamic subtest: bcs0
[256.288740] Dynamic subtest bcs0: SUCCESS (5.434s)
[256.304903] Starting dynamic subtest: vcs0
[261.726923] Dynamic subtest vcs0: SUCCESS (5.439s)
[261.743466] Starting dynamic subtest: vecs0
[267.162551] Dynamic subtest vecs0: SUCCESS (5.434s)
[267.163820] Subtest smoketest: SUCCESS (21.748s)
[267.343803] [054/130] (784s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[267.487816] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[267.562075] [055/130] (784s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[267.706378] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[267.780609] [056/130] (784s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[267.923843] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[267.997994] [057/130] (783s left) gem_exec_schedule (u-fairslice)
[268.118873] Starting subtest: u-fairslice
[268.120157] Starting dynamic subtest: rcs0
[270.148956] Dynamic subtest rcs0: SUCCESS (2.029s)
[270.167447] Starting dynamic subtest: bcs0
[272.179821] Dynamic subtest bcs0: SUCCESS (2.028s)
[272.196815] Starting dynamic subtest: vcs0
[274.205126] Dynamic subtest vcs0: SUCCESS (2.026s)
[274.227432] Starting dynamic subtest: vecs0
[276.231517] Dynamic subtest vecs0: SUCCESS (2.025s)
[276.232576] Subtest u-fairslice: SUCCESS (8.111s)
[276.545996] [058/130] (775s left) gem_mmap_gtt (cpuset-medium-copy)
[276.638643] Starting subtest: cpuset-medium-copy
[285.017659] Subtest cpuset-medium-copy: SUCCESS (8.380s)
[285.147023] [059/130] (766s left) perf_pmu (busy-start)
[285.332457] Starting subtest: busy-start
[285.333483] Starting dynamic subtest: rcs0
[287.864853] Dynamic subtest rcs0: SUCCESS (2.531s)
[287.890027] Starting dynamic subtest: bcs0
[290.397897] Dynamic subtest bcs0: SUCCESS (2.532s)
[290.425435] Starting dynamic subtest: vcs0
[292.929657] Dynamic subtest vcs0: SUCCESS (2.531s)
[292.949649] Starting dynamic subtest: vecs0
[295.460603] Dynamic subtest vecs0: SUCCESS (2.530s)
[295.462419] Subtest busy-start: SUCCESS (10.128s)
[295.544528] [060/130] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[295.698822] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[295.781833] [061/130] (756s left) syncobj_timeline (invalid-signal-illegal-point)
[295.884887] Starting subtest: invalid-signal-illegal-point
[295.886467] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[295.976251] [062/130] (756s left) kms_async_flips (crc)
[296.120450] Subtest crc: SKIP (0.000s)
[296.193187] [063/130] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[296.335842] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[296.417245] [064/130] (756s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[296.563232] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[296.638434] [065/130] (755s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[296.790426] Subtest cursor-vs-flip-legacy: SKIP (0.000s)
[296.858918] [066/130] (755s left) kms_concurrent (multi-plane-atomic-lowres)
[297.002433] Subtest multi-plane-atomic-lowres: SKIP (0.000s)
[297.076465] [067/130] (755s left) syncobj_timeline (wait-for-submit-snapshot)
[297.169932] Starting subtest: wait-for-submit-snapshot
[297.256343] Subtest wait-for-submit-snapshot: SUCCESS (0.086s)
[297.335444] [068/130] (755s left) gem_mmap_offset (open-flood)
[297.427570] Starting subtest: open-flood
[318.963608] Subtest open-flood: SUCCESS (21.537s)
[319.062719] [069/130] (733s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[319.254822] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[319.330451] [070/130] (733s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[319.478921] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[319.560625] [071/130] (733s left) gem_partial_pwrite_pread (reads-uncached)
[319.666358] Starting subtest: reads-uncached
[324.868468] Subtest reads-uncached: SUCCESS (5.202s)
[324.985795] [072/130] (728s left) gem_exec_reloc (basic-wc-cpu)
[325.092849] Starting subtest: basic-wc-cpu
[325.110274] Subtest basic-wc-cpu: SUCCESS (0.016s)
[325.221661] [073/130] (727s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[325.318134] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[325.319073] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.002s)
[325.397952] [074/130] (727s left) gem_vm_create (execbuf)
[325.501093] Starting subtest: execbuf
[325.509874] Subtest execbuf: SUCCESS (0.008s)
[325.630868] [075/130] (727s left) gem_ringfill (legacy-basic)
[326.040923] Starting subtest: legacy-basic
[326.042724] Starting dynamic subtest: default
[326.067546] Dynamic subtest default: SUCCESS (0.027s)
[326.086754] Starting dynamic subtest: render
[326.089938] Dynamic subtest render: SUCCESS (0.020s)
[326.109973] Starting dynamic subtest: bsd
[326.113666] Dynamic subtest bsd: SUCCESS (0.023s)
[326.132933] Starting dynamic subtest: bsd1
[326.141535] Dynamic subtest bsd1: SUCCESS (0.027s)
[326.160671] Starting dynamic subtest: blt
[326.168378] Dynamic subtest blt: SUCCESS (0.025s)
[326.187445] Starting dynamic subtest: vebox
[326.189110] Dynamic subtest vebox: SUCCESS (0.019s)
[326.190210] Subtest legacy-basic: SUCCESS (0.149s)
[326.278490] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[326.282932] [076/130] (727s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[326.427738] Subtest plane-all-transition-nonblocking-fencing: SKIP (0.000s)
[326.510474] [077/130] (727s left) gem_exec_whisper (basic-fds-priority-all)
[326.629173] Starting subtest: basic-fds-priority-all
[336.981988] Subtest basic-fds-priority-all: SUCCESS (10.351s)
[337.181400] [078/130] (716s left) gem_ccs (block-multicopy-inplace)
[337.285333] Subtest block-multicopy-inplace: SKIP (0.000s)
[337.372720] [079/130] (716s left) kms_getfb (getfb-handle-valid)
[337.517842] Starting subtest: getfb-handle-valid
[337.518920] Subtest getfb-handle-valid: SUCCESS (0.000s)
[337.627257] [080/130] (716s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[337.771500] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[337.866687] [081/130] (716s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[338.011990] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[338.013760] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[338.098786] [082/130] (715s left) sw_sync (alloc_fence_invalid_timeline)
[338.188700] Starting subtest: alloc_fence_invalid_timeline
[338.189779] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[338.275994] [083/130] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[338.422271] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[338.516349] [084/130] (715s left) gem_exec_fence (submit-chain)
[338.757551] Starting subtest: submit-chain
[338.781669] Subtest submit-chain: SUCCESS (0.024s)
[339.409251] [085/130] (714s left) kms_psr (pr-primary-mmap-gtt)
[339.553857] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[339.637975] [086/130] (714s left) gem_mmap_wc (invalid-flags)
[339.727409] Starting subtest: invalid-flags
[339.728350] Subtest invalid-flags: SUCCESS (0.000s)
[339.841125] [087/130] (714s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[339.985505] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[340.078438] [088/130] (714s left) kms_psr (pr-cursor-mmap-gtt)
[340.224206] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[340.307437] [089/130] (714s left) api_intel_allocator (standalone)
[340.399299] Starting subtest: standalone
[340.441486] Subtest standalone: SUCCESS (0.042s)
[340.528767] [090/130] (714s left) kms_flip (plain-flip-fb-recreate)
[340.674170] Starting subtest: plain-flip-fb-recreate
[340.677103] Subtest plain-flip-fb-recreate: SKIP (0.002s)
[340.786150] [091/130] (714s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[340.935651] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[341.030480] [092/130] (713s left) kms_plane_multiple (tiling-4)
[341.175523] Subtest tiling-4: SKIP (0.000s)
[341.260301] [093/130] (713s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[341.407159] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[341.408063] Starting dynamic subtest: pipe-A
[341.409602] Dynamic subtest pipe-A: SKIP (0.000s)
[341.427401] Starting dynamic subtest: pipe-B
[341.429038] Dynamic subtest pipe-B: SKIP (0.000s)
[341.453625] Starting dynamic subtest: pipe-C
[341.455840] Dynamic subtest pipe-C: SKIP (0.000s)
[341.457821] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.002s)
[341.554468] [094/130] (713s left) gem_exec_suspend (basic-s4-devices)
[341.670696] Starting subtest: basic-S4-devices
[341.671664] Starting dynamic subtest: smem
[351.578378] Dynamic subtest smem: SUCCESS (9.907s)
[351.579600] Subtest basic-S4-devices: SUCCESS (9.908s)
[360.794644] [095/130] (703s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[360.951914] Subtest fbc-dirtyfb-ioctl: SKIP (0.000s)
[361.037942] [096/130] (703s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[361.183489] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[361.185300] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.000s)
[361.271181] [097/130] (703s left) kms_cursor_crc (cursor-random-128x128)
[361.418256] Subtest cursor-random-128x128: SKIP (0.000s)
[361.506443] [098/130] (702s left) prime_busy (hang)
[361.609607] Starting subtest: hang
[361.610633] Starting dynamic subtest: rcs0
[368.674538] Dynamic subtest rcs0: SUCCESS (7.062s)
[368.694193] Starting dynamic subtest: bcs0
[375.715491] Dynamic subtest bcs0: SUCCESS (7.039s)
[375.735024] Starting dynamic subtest: vcs0
[382.690370] Dynamic subtest vcs0: SUCCESS (6.975s)
[382.710832] Starting dynamic subtest: vecs0
[389.665840] Dynamic subtest vecs0: SUCCESS (6.975s)
[389.666784] Subtest hang: SUCCESS (28.056s)
[389.796571] [099/130] (674s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[389.942842] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[390.037692] [100/130] (674s left) kms_lease (master-vs-lease)
[390.186668] Starting subtest: master-vs-lease
[390.191454] Subtest master-vs-lease: SUCCESS (0.004s)
[390.279585] [101/130] (674s left) kms_content_protection (type1)
[390.429236] Subtest type1: SKIP (0.000s)
[390.516225] [102/130] (674s left) i915_suspend (basic-s2idle-without-i915)
[390.606275] Starting subtest: basic-s2idle-without-i915
[412.794228] Subtest basic-s2idle-without-i915: SUCCESS (7.441s)
[412.888472] [103/130] (652s left) gem_partial_pwrite_pread (write-display)
[413.032048] Starting subtest: write-display
[426.010834] Subtest write-display: SUCCESS (13.105s)
[426.135263] [104/130] (638s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[426.287713] Subtest fbc-rgb565-draw-render: SKIP (0.000s)
[426.386868] [105/130] (638s left) kms_pipe_crc_basic (read-crc)
[426.530717] Subtest read-crc: SKIP (0.000s)
[426.618113] [106/130] (638s left) i915_query (engine-info-invalid)
[426.710369] Starting subtest: engine-info-invalid
[426.711276] Subtest engine-info-invalid: SUCCESS (0.000s)
[426.798292] [107/130] (638s left) gem_mmap_wc (read-write-distinct)
[426.888493] Starting subtest: read-write-distinct
[426.917504] Subtest read-write-distinct: SUCCESS (0.029s)
[427.037876] [108/130] (638s left) prime_mmap (test_correct)
[427.131001] Starting subtest: test_correct
[427.131951] Starting dynamic subtest: test_correct-smem
[427.138037] Dynamic subtest test_correct-smem: SUCCESS (0.007s)
[427.139156] Subtest test_correct: SUCCESS (0.008s)
[427.251144] [109/130] (638s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[427.397709] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[427.488183] [110/130] (638s left) gem_exec_schedule (in-order)
[427.615291] Starting subtest: in-order
[427.616562] Starting dynamic subtest: rcs0
[427.739477] Dynamic subtest rcs0: SUCCESS (0.125s)
[427.763352] Starting dynamic subtest: bcs0
[427.863075] Dynamic subtest bcs0: SUCCESS (0.123s)
[427.886419] Starting dynamic subtest: vcs0
[427.975784] Dynamic subtest vcs0: SUCCESS (0.110s)
[427.997556] Starting dynamic subtest: vecs0
[428.085739] Dynamic subtest vecs0: SUCCESS (0.110s)
[428.087217] Subtest in-order: SUCCESS (0.474s)
[428.443237] [111/130] (637s left) prime_mmap (test_reprime)
[428.535411] Starting subtest: test_reprime
[428.536354] Starting dynamic subtest: test_reprime-smem
[428.542982] Dynamic subtest test_reprime-smem: SUCCESS (0.007s)
[428.543930] Subtest test_reprime: SUCCESS (0.008s)
[428.663006] [112/130] (637s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[428.813044] Subtest short-flip-after-cursor-atomic-transitions: SKIP (0.000s)
[428.895951] [113/130] (636s left) prime_vgem (basic-read)
[429.001579] Starting subtest: basic-read
[429.068531] Subtest basic-read: SUCCESS (0.065s)
[429.192540] [114/130] (636s left) kms_hdr (bpc-switch)
[429.340936] Subtest bpc-switch: SKIP (0.000s)
[429.433096] [115/130] (636s left) gem_mmap_gtt (medium-copy-xy)
[429.524195] Starting subtest: medium-copy-XY
[434.922411] Subtest medium-copy-XY: SUCCESS (5.408s)
[435.027740] [116/130] (631s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[435.250955] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[435.338002] [117/130] (630s left) gem_vm_create (invalid-create)
[435.446859] Starting subtest: invalid-create
[435.448363] Subtest invalid-create: SUCCESS (0.000s)
[435.563144] [118/130] (630s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[435.711069] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.000s)
[435.798086] [119/130] (630s left) prime_self_import (reimport-vs-gem_close-race)
[435.885891] Starting subtest: reimport-vs-gem_close-race
[440.918698] Subtest reimport-vs-gem_close-race: SUCCESS (5.037s)
[441.011355] [120/130] (625s left) gem_exec_balancer (bonded-dual)
[441.130910] Starting subtest: bonded-dual
[445.298489] Subtest bonded-dual: SUCCESS (4.171s)
[445.519795] [121/130] (621s left) gem_mmap_wc (write-read)
[445.613060] Starting subtest: write-read
[445.642708] Subtest write-read: SUCCESS (0.029s)
[445.758733] [122/130] (620s left) gem_lmem_evict (dontneed-evict-race)
[445.870217] Subtest dontneed-evict-race: SKIP (0.000s)
[445.960289] [123/130] (620s left) api_intel_bb (render)
[446.059689] Starting subtest: render
[446.060838] Starting dynamic subtest: render-none-512
[446.181123] Dynamic subtest render-none-512: SUCCESS (0.124s)
[446.200632] Starting dynamic subtest: render-none-reloc-512
[446.292076] Dynamic subtest render-none-reloc-512: SUCCESS (0.110s)
[446.313618] Starting dynamic subtest: render-none-1024
[446.692648] Dynamic subtest render-none-1024: SUCCESS (0.399s)
[446.712505] Starting dynamic subtest: render-none-reloc-1024
[447.089693] Dynamic subtest render-none-reloc-1024: SUCCESS (0.395s)
[447.111442] Starting dynamic subtest: render-x-512
[447.192894] Dynamic subtest render-x-512: SUCCESS (0.103s)
[447.214428] Starting dynamic subtest: render-x-reloc-512
[447.284300] Dynamic subtest render-x-reloc-512: SUCCESS (0.090s)
[447.303637] Starting dynamic subtest: render-x-1024
[447.625713] Dynamic subtest render-x-1024: SUCCESS (0.340s)
[447.645283] Starting dynamic subtest: render-x-reloc-1024
[447.984994] Dynamic subtest render-x-reloc-1024: SUCCESS (0.357s)
[448.004567] Starting dynamic subtest: render-y-512
[448.086390] Dynamic subtest render-y-512: SUCCESS (0.101s)
[448.106013] Starting dynamic subtest: render-y-reloc-512
[448.177032] Dynamic subtest render-y-reloc-512: SUCCESS (0.089s)
[448.196319] Starting dynamic subtest: render-y-1024
[448.516769] Dynamic subtest render-y-1024: SUCCESS (0.338s)
[448.538381] Starting dynamic subtest: render-y-reloc-1024
[448.872565] Dynamic subtest render-y-reloc-1024: SUCCESS (0.355s)
[448.873575] Subtest render: SUCCESS (2.815s)
[448.986080] [124/130] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[449.136404] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[449.226782] [125/130] (617s left) gem_exec_reloc (basic-write-gtt-active)
[449.334212] Starting subtest: basic-write-gtt-active
[449.468349] Subtest basic-write-gtt-active: SUCCESS (0.134s)
[449.591724] [126/130] (617s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[449.688104] Starting subtest: etime-multi-wait-all-for-submit-submitted
[449.798395] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.109s)
[449.889796] [127/130] (617s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[450.036122] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[450.124799] [128/130] (617s left) kms_properties (plane-properties-atomic)
[450.270952] Starting subtest: plane-properties-atomic
[450.272763] Subtest plane-properties-atomic: SKIP (0.000s)
[450.359335] [129/130] (616s left) vgem_basic (dmabuf-fence-before)
[450.449832] Starting subtest: dmabuf-fence-before
[450.450717] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[450.541079] [130/130] (616s left) kms_psr (psr-cursor-mmap-cpu)
[450.688078] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[450.775391] 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.