[89.149115] Initializing watchdogs
[89.149296]   /dev/watchdog0
[89.189222] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.204368] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.210662] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.218688] [001/133] (960s left) kms_joiner (invalid-modeset-big-joiner)
[89.437183] Starting subtest: invalid-modeset-big-joiner
[89.446336] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[89.582323] [002/133] (960s left) prime_self_import (export-vs-gem_close-race)
[89.688149] Starting subtest: export-vs-gem_close-race
[94.713319] Subtest export-vs-gem_close-race: SUCCESS (5.023s)
[94.847695] [003/133] (955s left) i915_power (sanity)
[94.959931] Starting subtest: sanity
[98.985613] Subtest sanity: SUCCESS (4.024s)
[99.120299] [004/133] (950s left) kms_psr (psr-primary-page-flip)
[99.311249] Subtest psr-primary-page-flip: SKIP (0.000s)
[99.412983] [005/133] (950s left) prime_vgem (basic-fence-mmap)
[99.628138] Starting subtest: basic-fence-mmap
[99.688915] Subtest basic-fence-mmap: SUCCESS (0.059s)
[99.841540] [FACT prime_vgem (basic-fence-mmap)] new: kernel.kmod_is_loaded.vgem: true
[99.847779] [006/133] (950s left) kms_tiled_display (basic-test-pattern)
[100.134083] Starting subtest: basic-test-pattern
[102.096827] Subtest basic-test-pattern: FAIL (1.096s)
[102.188594] [007/133] (948s left) gem_exec_suspend (basic-s4-devices)
[102.319083] Starting subtest: basic-S4-devices
[102.323667] Starting dynamic subtest: smem
[113.555558] Dynamic subtest smem: SUCCESS (11.235s)
[113.559547] Subtest basic-S4-devices: SUCCESS (11.237s)
[122.834963] [008/133] (936s left) gem_userptr_blits (dmabuf-unsync)
[122.976809] Subtest dmabuf-unsync: SKIP (0.000s)
[123.169170] [009/133] (936s left) kms_plane_scaling (planes-downscale-factor-0-5)
[123.305563] Starting subtest: planes-downscale-factor-0-5
[123.313443] Starting dynamic subtest: pipe-A
[123.635844] Dynamic subtest pipe-A: SUCCESS (0.329s)
[123.684209] Starting dynamic subtest: pipe-B
[124.046663] Dynamic subtest pipe-B: SUCCESS (0.410s)
[124.096069] Starting dynamic subtest: pipe-C
[124.129515] Dynamic subtest pipe-C: SUCCESS (0.080s)
[124.133562] Subtest planes-downscale-factor-0-5: SUCCESS (0.822s)
[124.615748] [010/133] (934s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[124.757104] Starting subtest: plane-lut1d-ctm3x4-lut1d
[126.047363] Subtest plane-lut1d-ctm3x4-lut1d: SKIP (1.286s)
[126.364933] [011/133] (933s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[126.513316] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[126.520584] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[126.663920] [012/133] (933s left) gem_ctx_persistence (heartbeat-stop)
[126.781428] Starting subtest: heartbeat-stop
[126.913615] Subtest heartbeat-stop: SUCCESS (0.132s)
[127.060371] [013/133] (932s left) syncobj_timeline (etime-multi-wait-all-submitted)
[127.174635] Starting subtest: etime-multi-wait-all-submitted
[127.277161] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[127.427787] [014/133] (932s left) gem_exec_reloc (basic-scanout)
[127.582086] Starting subtest: basic-scanout
[127.585349] Starting dynamic subtest: rcs0
[128.114035] Dynamic subtest rcs0: SUCCESS (0.534s)
[128.141334] Starting dynamic subtest: bcs0
[128.314160] Dynamic subtest bcs0: SUCCESS (0.197s)
[128.337819] Starting dynamic subtest: vcs0
[128.511179] Dynamic subtest vcs0: SUCCESS (0.196s)
[128.535624] Starting dynamic subtest: vecs0
[128.712584] Dynamic subtest vecs0: SUCCESS (0.197s)
[128.716899] Subtest basic-scanout: SUCCESS (1.134s)
[128.978228] [015/133] (931s left) gem_exec_balancer (bonded-pair)
[129.105847] Starting subtest: bonded-pair
[133.479817] Subtest bonded-pair: SUCCESS (4.371s)
[133.763634] [016/133] (926s left) syncobj_wait (invalid-reset-bad-pad)
[133.874548] Starting subtest: invalid-reset-bad-pad
[133.878046] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[134.027733] [017/133] (926s left) i915_drm_fdinfo (memory-info-resident)
[134.141340] Starting subtest: memory-info-resident
[134.145676] Starting dynamic subtest: smem0
[135.280310] Dynamic subtest smem0: SUCCESS (1.138s)
[135.284332] Subtest memory-info-resident: SUCCESS (1.139s)
[135.446582] [018/133] (924s left) api_intel_allocator (simple-allocator)
[135.554070] Starting subtest: simple-allocator
[135.557452] Starting dynamic subtest: basic
[135.562234] Dynamic subtest basic: SUCCESS (0.009s)
[135.585281] Starting dynamic subtest: parallel-one
[135.981715] Dynamic subtest parallel-one: SUCCESS (0.414s)
[136.018936] Starting dynamic subtest: print
[136.023757] Dynamic subtest print: SUCCESS (0.000s)
[136.050475] Starting dynamic subtest: reuse
[136.054983] Dynamic subtest reuse: SUCCESS (0.000s)
[136.079979] Starting dynamic subtest: reserve
[136.084245] Dynamic subtest reserve: SUCCESS (0.000s)
[136.108604] Starting dynamic subtest: fork-reopen-allocator
[136.112688] Dynamic subtest fork-reopen-allocator: SUCCESS (0.029s)
[136.117136] Subtest simple-allocator: SUCCESS (0.456s)
[136.213282] [019/133] (924s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[136.361491] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[136.369269] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[136.522691] [020/133] (924s left) kms_cursor_crc (cursor-random-32x10)
[136.673271] Starting subtest: cursor-random-32x10
[136.882105] Subtest cursor-random-32x10: SKIP (0.204s)
[137.047157] [021/133] (923s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[137.182183] Starting subtest: primary-y-tiled-reflect-x-180
[137.190290] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[137.328961] [022/133] (923s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[137.477047] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[137.481751] Starting dynamic subtest: pipe-A-HDMI-A-1
[138.043088] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.535s)
[138.066314] Starting dynamic subtest: pipe-A-HDMI-A-2
[138.478108] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.446s)
[138.522770] Starting dynamic subtest: pipe-B-HDMI-A-1
[138.961076] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.480s)
[138.988275] Starting dynamic subtest: pipe-B-HDMI-A-2
[139.384524] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.416s)
[139.436574] Starting dynamic subtest: pipe-C-HDMI-A-1
[139.471777] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[139.497187] Starting dynamic subtest: pipe-C-HDMI-A-2
[139.527878] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[139.530898] Subtest crc-primary-rotation-180-y-tiled-ccs: SUCCESS (1.901s)
[140.206400] [023/133] (920s left) gem_exec_whisper (basic-fds-priority)
[140.349054] Starting subtest: basic-fds-priority
[149.636662] Subtest basic-fds-priority: SUCCESS (9.290s)
[149.935554] [024/133] (911s left) kms_chamelium_frames (hdmi-crc-multiple)
[150.462076] Subtest hdmi-crc-multiple: SKIP (0.000s)
[150.821485] [025/133] (910s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[150.992847] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[150.999705] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[151.142557] [026/133] (910s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[151.282098] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[151.292234] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[151.425771] [027/133] (910s left) syncobj_wait (single-wait-signaled)
[151.534102] Starting subtest: single-wait-signaled
[151.539172] Subtest single-wait-signaled: SUCCESS (0.001s)
[151.690420] [028/133] (909s left) kms_cursor_crc (cursor-offscreen-64x21)
[151.833228] Starting subtest: cursor-offscreen-64x21
[151.879108] Starting dynamic subtest: pipe-A-HDMI-A-1
[154.709390] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.829s)
[154.826567] Starting dynamic subtest: pipe-C-HDMI-A-1
[157.354826] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.581s)
[157.358641] Subtest cursor-offscreen-64x21: SUCCESS (5.520s)
[157.724087] [029/133] (903s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[157.888986] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[157.896641] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[158.048624] [030/133] (903s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[158.214475] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[158.221725] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[158.383046] [031/133] (903s left) gem_pxp (reject-modify-context-protection-off-3)
[161.027829] Starting subtest: reject-modify-context-protection-off-3
[161.037931] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[161.170468] [032/133] (900s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[161.277740] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[161.382178] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.104s)
[161.537497] [033/133] (900s left) prime_mmap (test_correct)
[161.644924] Starting subtest: test_correct
[161.650063] Starting dynamic subtest: test_correct-smem
[161.653557] Dynamic subtest test_correct-smem: SUCCESS (0.010s)
[161.660423] Subtest test_correct: SUCCESS (0.011s)
[161.803554] [034/133] (900s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[161.965098] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[162.207410] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.238s)
[162.485533] [035/133] (899s left) kms_atomic (plane-cursor-legacy)
[162.614954] Starting subtest: plane-cursor-legacy
[162.620106] Starting dynamic subtest: pipe-A-HDMI-A-1
[163.157277] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.536s)
[163.161196] Subtest plane-cursor-legacy: SUCCESS (0.541s)
[163.513189] [036/133] (898s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[163.679283] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[163.693969] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.004s)
[163.845353] [037/133] (898s left) vgem_basic (create)
[163.969459] Starting subtest: create
[163.975143] Subtest create: SUCCESS (0.001s)
[164.093354] [038/133] (898s left) syncobj_wait (single-wait-all-for-submit-submitted)
[164.201602] Starting subtest: single-wait-all-for-submit-submitted
[164.300359] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.101s)
[164.461575] [039/133] (898s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[164.621135] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[167.891073] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SUCCESS (3.274s)
[168.138246] [040/133] (894s left) gem_spin_batch (engines)
[168.270625] Starting subtest: engines
[168.274299] Starting dynamic subtest: rcs0
[171.505893] Dynamic subtest rcs0: SUCCESS (3.230s)
[171.530172] Starting dynamic subtest: bcs0
[174.734621] Dynamic subtest bcs0: SUCCESS (3.232s)
[174.758696] Starting dynamic subtest: vcs0
[177.966827] Dynamic subtest vcs0: SUCCESS (3.229s)
[177.990923] Starting dynamic subtest: vecs0
[181.196791] Dynamic subtest vecs0: SUCCESS (3.227s)
[181.201284] Subtest engines: SUCCESS (12.924s)
[181.459402] [041/133] (881s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[181.575696] Starting subtest: tiled-to-untiled
[181.690509] Subtest tiled-to-untiled: SUCCESS (0.111s)
[181.839501] [042/133] (881s left) perf_pmu (init-wait)
[181.958096] Starting subtest: init-wait
[181.961290] Starting dynamic subtest: rcs0
[181.964755] Dynamic subtest rcs0: SUCCESS (0.003s)
[181.993960] Starting dynamic subtest: bcs0
[181.998396] Dynamic subtest bcs0: SUCCESS (0.002s)
[182.023121] Starting dynamic subtest: vcs0
[182.026481] Dynamic subtest vcs0: SUCCESS (0.002s)
[182.051237] Starting dynamic subtest: vecs0
[182.055388] Dynamic subtest vecs0: SUCCESS (0.002s)
[182.059503] Subtest init-wait: SUCCESS (0.015s)
[182.159978] [043/133] (880s left) gem_exec_await (wide-all)
[182.479143] Starting subtest: wide-all
[204.684498] Subtest wide-all: SUCCESS (22.207s)
[204.966984] [044/133] (858s left) kms_frontbuffer_tracking (fbc-tiling-4)
[205.324157] Starting subtest: fbc-tiling-4
[205.332294] Subtest fbc-tiling-4: SKIP (0.000s)
[205.475950] [045/133] (857s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[205.603126] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[205.607288] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[205.758084] [046/133] (857s left) drm_read (empty-nonblock)
[206.242255] Starting subtest: empty-nonblock
[206.245649] Subtest empty-nonblock: SUCCESS (0.000s)
[206.558417] [047/133] (857s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[206.726507] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[209.177998] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SUCCESS (2.458s)
[209.394625] [048/133] (854s left) kms_cursor_legacy (torture-bo)
[209.550836] Starting subtest: torture-bo
[209.558143] Starting dynamic subtest: pipe-A
[215.321393] Dynamic subtest pipe-A: SUCCESS (5.775s)
[215.355036] Starting dynamic subtest: pipe-B
[222.689762] Dynamic subtest pipe-B: SUCCESS (7.361s)
[222.719054] Starting dynamic subtest: pipe-C
[228.154173] Dynamic subtest pipe-C: SUCCESS (5.465s)
[228.181624] Starting dynamic subtest: all-pipes
[233.614588] Dynamic subtest all-pipes: SUCCESS (5.456s)
[233.619500] Subtest torture-bo: SUCCESS (24.072s)
[233.781077] [049/133] (830s left) dumb_buffer (map-valid)
[233.894838] Starting subtest: map-valid
[233.899684] Subtest map-valid: SUCCESS (0.000s)
[234.058607] [050/133] (829s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[234.213493] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[234.222063] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[234.376350] [051/133] (829s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[234.520119] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[234.526797] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[234.676500] [052/133] (829s left) gem_wait (await)
[234.821228] Starting subtest: await
[234.824960] Starting dynamic subtest: all-engines
[235.870137] Dynamic subtest all-engines: SUCCESS (1.047s)
[235.902550] Starting dynamic subtest: rcs0
[236.902617] Dynamic subtest rcs0: SUCCESS (1.031s)
[236.934676] Starting dynamic subtest: bcs0
[237.936563] Dynamic subtest bcs0: SUCCESS (1.031s)
[237.967057] Starting dynamic subtest: vcs0
[238.970142] Dynamic subtest vcs0: SUCCESS (1.034s)
[239.003449] Starting dynamic subtest: vecs0
[240.014073] Dynamic subtest vecs0: SUCCESS (1.039s)
[240.017846] Subtest await: SUCCESS (5.192s)
[240.308548] [053/133] (823s left) gem_mmap_offset (close-race)
[240.416122] Starting subtest: close-race
[260.485621] Subtest close-race: SUCCESS (20.074s)
[260.643184] [054/133] (803s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[261.316139] Starting subtest: syncobj-timeline-invalid-flags
[261.320410] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[261.595625] [055/133] (802s left) kms_prop_blob (blob-prop-lifetime)
[261.709115] Starting subtest: blob-prop-lifetime
[261.712382] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[261.858994] [056/133] (802s left) gem_eio (in-flight-suspend)
[261.994666] Starting subtest: in-flight-suspend