[89.415160] Initializing watchdogs
[89.415368]   /dev/watchdog0
[89.453751] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.468504] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.475613] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.480822] [001/133] (960s left) kms_selftest (drm_plane_helper)
[89.602385] Starting subtest: drm_plane_helper
[89.616578] Starting dynamic subtest: drm_test_check_plane_state
[89.680198] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.063s)
[89.722334] Starting dynamic subtest: drm_test_check_invalid_plane_state
[89.740142] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.062s)
[89.745318] Subtest drm_plane_helper: SUCCESS (0.142s)
[89.907913] [002/133] (960s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[90.091858] Starting subtest: 2x-plain-flip-ts-check-interruptible
[90.095589] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[101.152306] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.053s)
[101.172755] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[112.202579] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.046s)
[112.220573] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[123.179076] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.971s)
[123.183079] Subtest 2x-plain-flip-ts-check-interruptible: SUCCESS (33.086s)
[123.804228] [003/133] (926s left) gem_userptr_blits (map-fixed-invalidate-busy)
[123.952343] Starting subtest: map-fixed-invalidate-busy
[123.953314] Starting dynamic subtest: gtt
[127.424045] Dynamic subtest gtt: SUCCESS (3.469s)
[127.441873] Starting dynamic subtest: wb
[130.748399] Dynamic subtest wb: SUCCESS (3.325s)
[130.765332] Starting dynamic subtest: wc
[134.068620] Dynamic subtest wc: SUCCESS (3.321s)
[134.089144] Starting dynamic subtest: uc
[137.387054] Dynamic subtest uc: SUCCESS (3.312s)
[137.388911] Subtest map-fixed-invalidate-busy: SUCCESS (13.432s)
[137.556088] [004/133] (912s left) gem_eio (in-flight-10ms)
[137.687555] Starting subtest: in-flight-10ms
[138.816695] Subtest in-flight-10ms: SUCCESS (1.126s)
[139.058601] [FACT gem_eio (in-flight-10ms)] new: kernel.kmod_is_loaded.vgem: true
[139.061860] [005/133] (911s left) gem_flink_race (flink_name)
[139.159355] Starting subtest: flink_name
[144.534336] Subtest flink_name: SUCCESS (5.376s)
[144.681284] [006/133] (905s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[144.870211] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[144.877068] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[145.013463] [007/133] (905s left) gen7_exec_parse (basic-offset)
[145.124090] Subtest basic-offset: SKIP (0.000s)
[145.239277] [008/133] (905s left) syncobj_wait (signal)
[145.344583] Starting subtest: signal
[145.347447] Subtest signal: SUCCESS (0.002s)
[145.480254] [009/133] (905s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[145.691149] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[147.411531] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.720s)
[147.666839] [010/133] (903s left) i915_fb_tiling (basic-x-tiling)
[147.767349] Starting subtest: basic-x-tiling
[147.770309] Subtest basic-x-tiling: SUCCESS (0.001s)
[147.915428] [011/133] (902s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[148.105545] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[148.107052] Starting dynamic subtest: pipe-A-valid-mode
[149.006515] Dynamic subtest pipe-A-valid-mode: SKIP (0.896s)
[149.008121] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.902s)
[149.486111] [012/133] (901s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[149.607342] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[149.609324] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[149.872191] [013/133] (901s left) i915_drm_fdinfo (memory-info-resident)
[149.987268] Starting subtest: memory-info-resident
[149.988938] Starting dynamic subtest: smem0
[151.096872] Dynamic subtest smem0: SUCCESS (1.113s)
[151.099767] Subtest memory-info-resident: SUCCESS (1.116s)
[151.234367] [014/133] (899s left) kms_feature_discovery (display-2x)
[151.415367] Starting subtest: display-2x
[151.416491] Subtest display-2x: SUCCESS (0.000s)
[151.551494] [015/133] (899s left) syncobj_timeline (invalid-transfer-bad-pad)
[151.655238] Starting subtest: invalid-transfer-bad-pad
[151.657371] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[151.786298] [016/133] (899s left) gem_create (create-ext-cpu-access-big)
[152.002744] Starting subtest: create-ext-cpu-access-big
[152.007642] Subtest create-ext-cpu-access-big: SKIP (0.003s)
[152.136421] [017/133] (899s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[152.332930] Starting subtest: x-tiled-16bpp-rotate-270
[152.688184] Subtest x-tiled-16bpp-rotate-270: SKIP (0.355s)
[152.951469] [018/133] (898s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[153.156237] Starting subtest: fbc-farfromfence-mmap-gtt
[156.179704] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.021s)
[156.433900] [019/133] (895s left) gem_barrier_race (remote-request)
[156.555695] Starting subtest: remote-request
[156.560137] Starting dynamic subtest: rcs0
[161.650933] Dynamic subtest rcs0: SUCCESS (5.097s)
[161.651817] Subtest remote-request: SUCCESS (5.098s)
[161.786799] [020/133] (889s left) kms_psr (fbc-psr2-cursor-plane-move)
[161.975176] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[162.073278] [021/133] (889s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[162.271792] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[164.731985] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SUCCESS (2.460s)
[164.951408] [022/133] (886s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[165.555204] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[165.889087] [023/133] (886s left) syncobj_wait (invalid-single-wait-unsubmitted)
[165.993339] Starting subtest: invalid-single-wait-unsubmitted
[165.995706] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.001s)
[166.144470] [024/133] (885s left) gem_exec_schedule (noreorder-priority)
[166.407758] Starting subtest: noreorder-priority
[166.409109] Starting dynamic subtest: rcs0
[167.527048] Dynamic subtest rcs0: SUCCESS (1.120s)
[167.546720] Starting dynamic subtest: bcs0
[168.650085] Dynamic subtest bcs0: SUCCESS (1.121s)
[168.670503] Starting dynamic subtest: vcs0
[169.773198] Dynamic subtest vcs0: SUCCESS (1.119s)
[169.794846] Starting dynamic subtest: vecs0
[170.887113] Dynamic subtest vecs0: SUCCESS (1.114s)
[170.888345] Subtest noreorder-priority: SUCCESS (4.481s)
[171.174093] [025/133] (880s left) kms_plane_multiple (2x-tiling-4)
[171.359235] Starting subtest: 2x-tiling-4
[171.362927] Subtest 2x-tiling-4: SKIP (0.000s)
[171.494334] [026/133] (880s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[171.686065] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[171.688709] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[171.826829] [027/133] (880s left) gem_pxp (create-valid-protected-context)
[174.463061] Starting subtest: create-valid-protected-context
[174.466467] Subtest create-valid-protected-context: SKIP (0.000s)
[174.613341] [028/133] (877s left) gem_exec_schedule (semaphore-noskip)
[174.747264] Starting subtest: semaphore-noskip
[174.976711] Subtest semaphore-noskip: SUCCESS (0.228s)
[175.379191] [029/133] (877s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[175.562113] Starting subtest: short-flip-before-cursor-atomic-transitions
[176.874506] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (1.318s)
[177.172011] [030/133] (875s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[177.364211] Starting subtest: flip-vs-cursor-crc-legacy
[178.287413] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.921s)
[178.583678] [031/133] (874s left) perf (mi-rpc)
[178.788886] Starting subtest: mi-rpc
[178.812933] Subtest mi-rpc: SUCCESS (0.026s)
[178.919432] [032/133] (873s left) kms_atomic_transition (modeset-transition-nonblocking)
[179.107103] Starting subtest: modeset-transition-nonblocking
[179.108795] Starting dynamic subtest: 1x-outputs
[182.638689] Dynamic subtest 1x-outputs: SUCCESS (3.532s)
[182.661203] Starting dynamic subtest: 2x-outputs
[189.998952] Dynamic subtest 2x-outputs: SUCCESS (7.360s)
[189.999955] Subtest modeset-transition-nonblocking: SUCCESS (10.894s)
[190.297599] [033/133] (862s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[190.487398] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[190.490657] Starting dynamic subtest: pipe-A
[190.823943] Dynamic subtest pipe-A: SUCCESS (0.341s)
[190.848346] Starting dynamic subtest: pipe-B
[191.237986] Dynamic subtest pipe-B: SUCCESS (0.413s)
[191.261347] Starting dynamic subtest: pipe-C
[191.311119] Dynamic subtest pipe-C: SUCCESS (0.074s)
[191.312023] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (0.831s)
[191.791903] [034/133] (861s left) kms_chamelium_frames (hdmi-crc-fast)
[192.398040] Subtest hdmi-crc-fast: SKIP (0.000s)
[192.690145] [035/133] (860s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[192.880705] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[192.885877] Starting dynamic subtest: pipe-A-HDMI-A-1
[193.734984] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.841s)
[193.757065] Starting dynamic subtest: pipe-A-HDMI-A-2
[194.585627] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.850s)
[194.605157] Starting dynamic subtest: pipe-B-HDMI-A-1
[195.582231] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.994s)
[195.604039] Starting dynamic subtest: pipe-B-HDMI-A-2
[196.416973] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.831s)
[196.435910] Starting dynamic subtest: pipe-C-HDMI-A-1
[196.479251] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.011s)
[196.514824] Starting dynamic subtest: pipe-C-HDMI-A-2
[196.552182] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.011s)
[196.553712] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SUCCESS (3.562s)
[197.221728] [036/133] (855s left) kms_cursor_crc (cursor-onscreen-32x10)
[197.423234] Starting subtest: cursor-onscreen-32x10
[197.671253] Subtest cursor-onscreen-32x10: SKIP (0.244s)
[197.837476] [037/133] (855s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[198.045821] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[198.050381] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[198.187862] [038/133] (855s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[198.391834] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[198.395395] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[198.538180] [039/133] (854s left) gem_exec_reloc (basic-write-wc)
[198.660098] Starting subtest: basic-write-wc
[198.673222] Subtest basic-write-wc: SUCCESS (0.018s)
[198.904450] [040/133] (854s left) gem_fence_thrash (bo-write-verify-threaded-none)
[198.998896] Starting subtest: bo-write-verify-threaded-none
[203.579891] Subtest bo-write-verify-threaded-none: SUCCESS (4.581s)
[203.725133] [041/133] (849s left) api_intel_bb (offset-control)
[203.823522] Starting subtest: offset-control
[203.838871] Subtest offset-control: SUCCESS (0.016s)
[204.010197] [042/133] (849s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[204.220296] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[204.223077] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[204.372983] [043/133] (849s left) prime_mmap (test_dup)
[204.473169] Starting subtest: test_dup
[204.476063] Starting dynamic subtest: test_dup-smem
[204.478222] Dynamic subtest test_dup-smem: SUCCESS (0.008s)
[204.481160] Subtest test_dup: SUCCESS (0.009s)
[204.623720] [044/133] (849s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[204.725454] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[204.727458] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[204.876191] [045/133] (849s left) kms_frontbuffer_tracking (fbc-suspend)
[205.077605] Starting subtest: fbc-suspend