[90.046235] Initializing watchdogs
[90.046412]   /dev/watchdog0
[90.089192] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[90.104158] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[90.111297] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[90.119278] [001/133] (960s left) gem_userptr_blits (nohangcheck)
[90.303537] Starting subtest: nohangcheck
[90.618309] Subtest nohangcheck: SUCCESS (0.314s)
[90.793250] [002/133] (959s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[91.008555] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[91.015275] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[91.163615] [003/133] (959s left) kms_setmode (invalid-clone-exclusive-crtc)
[91.301540] Starting subtest: invalid-clone-exclusive-crtc
[91.887828] Subtest invalid-clone-exclusive-crtc: SKIP (0.612s)
[92.234547] [004/133] (958s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[92.470538] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[92.485110] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[93.115503] [005/133] (957s left) api_intel_bb (object-reloc-keep-cache)
[93.229061] Starting subtest: object-reloc-keep-cache
[93.248595] Subtest object-reloc-keep-cache: SUCCESS (0.020s)
[93.430885] [006/133] (957s left) kms_psr (fbc-psr2-sprite-blt)
[93.627291] Subtest fbc-psr2-sprite-blt: SKIP (0.000s)
[93.727831] [007/133] (957s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[93.924361] Starting subtest: addfb25-x-tiled-legacy
[93.927743] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[94.097797] [008/133] (957s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[94.210463] Starting subtest: multi-wait-available-unsubmitted-signaled
[94.214771] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.002s)
[94.380147] [009/133] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[94.586202] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[94.592950] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[94.765570] [010/133] (956s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[94.981931] Starting subtest: x-tiled-32bpp-rotate-270
[95.301130] Subtest x-tiled-32bpp-rotate-270: SKIP (0.314s)
[95.564570] [011/133] (955s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[95.769845] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[95.777495] Starting dynamic subtest: A-HDMI-A1
[101.701237] Dynamic subtest A-HDMI-A1: SUCCESS (5.924s)
[101.721823] Starting dynamic subtest: B-HDMI-A1
[107.354413] Dynamic subtest B-HDMI-A1: SUCCESS (5.646s)
[107.373198] Starting dynamic subtest: C-HDMI-A1
[112.998715] Dynamic subtest C-HDMI-A1: SUCCESS (5.644s)
[113.018311] Starting dynamic subtest: A-HDMI-A2
[118.658880] Dynamic subtest A-HDMI-A2: SUCCESS (5.658s)
[118.678186] Starting dynamic subtest: B-HDMI-A2
[124.312515] Dynamic subtest B-HDMI-A2: SUCCESS (5.650s)
[124.332785] Starting dynamic subtest: C-HDMI-A2
[129.952827] Dynamic subtest C-HDMI-A2: SUCCESS (5.636s)
[129.956611] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (34.177s)
[130.486240] [012/133] (921s left) gem_exec_parallel (fds)
[130.609534] Starting subtest: fds
[130.613715] Starting dynamic subtest: rcs0
[135.525639] Dynamic subtest rcs0: SUCCESS (4.914s)
[135.548860] Starting dynamic subtest: bcs0
[143.170212] Dynamic subtest bcs0: SUCCESS (7.645s)
[143.190552] Starting dynamic subtest: vcs0
[149.906061] Dynamic subtest vcs0: SUCCESS (6.734s)
[149.927867] Starting dynamic subtest: vecs0
[156.657237] Dynamic subtest vecs0: SUCCESS (6.746s)
[156.661287] Subtest fds: SUCCESS (26.045s)
[156.955220] [013/133] (894s left) dmabuf_sync_file (export-wait-after-attach)
[157.194775] Starting subtest: export-wait-after-attach
[157.198572] Subtest export-wait-after-attach: SUCCESS (0.004s)
[157.340401] [FACT dmabuf_sync_file (export-wait-after-attach)] new: kernel.kmod_is_loaded.vgem: true
[157.347147] [014/133] (894s left) perf_pmu (init-sema)
[157.455122] Starting subtest: init-sema
[157.458370] Starting dynamic subtest: rcs0
[157.461892] Dynamic subtest rcs0: SUCCESS (0.003s)
[157.482894] Starting dynamic subtest: bcs0
[157.489062] Dynamic subtest bcs0: SUCCESS (0.002s)
[157.508945] Starting dynamic subtest: vcs0
[157.512831] Dynamic subtest vcs0: SUCCESS (0.002s)
[157.533467] Starting dynamic subtest: vecs0
[157.537542] Dynamic subtest vecs0: SUCCESS (0.001s)
[157.541206] Subtest init-sema: SUCCESS (0.011s)
[157.648198] [015/133] (894s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[157.755602] Starting subtest: single-wait-for-submit-available-submitted
[157.759071] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[157.928530] [016/133] (894s left) kms_frontbuffer_tracking (fbc-argb161616f-draw-blt)
[158.123062] Starting subtest: fbc-argb161616f-draw-blt
[158.129641] Subtest fbc-argb161616f-draw-blt: SKIP (0.000s)
[158.312375] [017/133] (893s left) gem_ctx_shared (disjoint-timelines)
[158.437955] Starting subtest: disjoint-timelines
[158.458991] Subtest disjoint-timelines: SUCCESS (0.022s)
[158.762429] [018/133] (893s left) kms_content_protection (uevent)
[158.964671] Starting subtest: uevent
[159.007491] Subtest uevent: SKIP (0.034s)
[159.178728] [019/133] (893s left) gem_mmap_gtt (hang)
[159.283428] Starting subtest: hang
[164.722907] Subtest hang: SUCCESS (5.435s)
[164.984344] [020/133] (887s left) kms_flip (dpms-vs-vblank-race-interruptible)
[165.445039] Starting subtest: dpms-vs-vblank-race-interruptible
[165.510811] Starting dynamic subtest: A-HDMI-A1
[168.097044] Dynamic subtest A-HDMI-A1: SUCCESS (2.762s)
[168.149844] Starting dynamic subtest: B-HDMI-A1
[170.531915] Dynamic subtest B-HDMI-A1: SUCCESS (2.444s)
[170.607458] Starting dynamic subtest: C-HDMI-A1
[172.933351] Dynamic subtest C-HDMI-A1: SUCCESS (2.388s)
[172.960388] Starting dynamic subtest: A-HDMI-A2
[175.278323] Dynamic subtest A-HDMI-A2: SUCCESS (2.340s)
[175.311125] Starting dynamic subtest: B-HDMI-A2
[177.510324] Dynamic subtest B-HDMI-A2: SUCCESS (2.229s)
[177.530568] Starting dynamic subtest: C-HDMI-A2
[179.770069] Dynamic subtest C-HDMI-A2: SUCCESS (2.253s)
[179.778328] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (14.448s)
[180.158176] [021/133] (872s left) perf_pmu (render-node-busy)
[180.305951] Starting subtest: render-node-busy
[180.309715] Starting dynamic subtest: rcs0
[180.836431] Dynamic subtest rcs0: SUCCESS (0.529s)
[180.861842] Starting dynamic subtest: bcs0
[181.385739] Dynamic subtest bcs0: SUCCESS (0.540s)
[181.408074] Starting dynamic subtest: vcs0
[181.916316] Dynamic subtest vcs0: SUCCESS (0.528s)
[181.945999] Starting dynamic subtest: vecs0
[182.448360] Dynamic subtest vecs0: SUCCESS (0.539s)
[182.452517] Subtest render-node-busy: SUCCESS (2.143s)
[182.640481] [022/133] (870s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[182.823656] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[182.875053] Starting dynamic subtest: pipe-A-valid-mode
[183.818546] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.986s)
[183.879370] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SUCCESS (0.992s)
[184.161924] [023/133] (868s left) gem_softpin (softpin)
[184.293663] Starting subtest: softpin
[216.633876] Subtest softpin: SUCCESS (32.342s)
[216.824643] [024/133] (836s left) gem_bad_reloc (negative-reloc-lut)
[216.943234] Starting subtest: negative-reloc-lut
[216.953564] Subtest negative-reloc-lut: SUCCESS (0.002s)
[217.142876] [025/133] (835s left) syncobj_basic (illegal-fd-to-handle)
[217.250350] Starting subtest: illegal-fd-to-handle
[217.254969] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[217.426257] [026/133] (835s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[217.630368] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[220.348864] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SUCCESS (2.722s)
[220.591793] [027/133] (832s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[220.805824] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[220.809456] Starting dynamic subtest: pipe-A-HDMI-A-1
[220.823435] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[220.929434] Starting dynamic subtest: pipe-A-HDMI-A-2
[220.970700] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[220.992702] Starting dynamic subtest: pipe-B-HDMI-A-1
[221.005586] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[221.027238] Starting dynamic subtest: pipe-B-HDMI-A-2
[221.040598] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[221.062361] Starting dynamic subtest: pipe-C-HDMI-A-1
[221.074906] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[221.096658] Starting dynamic subtest: pipe-C-HDMI-A-2
[221.109399] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[221.115684] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SKIP (0.048s)
[221.826610] [028/133] (831s left) gem_exec_schedule (preemptive-hang)
[222.087282] Starting subtest: preemptive-hang
[222.090596] Starting dynamic subtest: rcs0
[228.121976] Dynamic subtest rcs0: SUCCESS (6.030s)
[228.151714] Starting dynamic subtest: bcs0
[234.138730] Dynamic subtest bcs0: SUCCESS (6.012s)
[234.177544] Starting dynamic subtest: vcs0
[240.088457] Dynamic subtest vcs0: SUCCESS (5.945s)
[240.111319] Starting dynamic subtest: vecs0
[246.100340] Dynamic subtest vecs0: SUCCESS (6.005s)
[246.103565] Subtest preemptive-hang: SUCCESS (24.010s)
[246.415864] [029/133] (807s left) gem_ppgtt (flink-and-close-vma-leak)
[246.543584] Starting subtest: flink-and-close-vma-leak
[246.556132] Subtest flink-and-close-vma-leak: SUCCESS (0.012s)
[246.741582] [030/133] (806s left) kms_color_pipeline (plane-lut1d)
[246.925558] Starting subtest: plane-lut1d
[248.031624] Subtest plane-lut1d: SKIP (1.044s)
[248.299025] [031/133] (805s left) gem_pxp (verify-pxp-stale-buf-execution)
[250.951982] Starting subtest: verify-pxp-stale-buf-execution
[250.960154] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[251.129709] [032/133] (802s left) kms_chamelium_frames (dp-frame-dump)
[251.710267] Subtest dp-frame-dump: SKIP (0.000s)
[252.011512] [033/133] (801s left) kms_cursor_legacy (single-move)
[252.209126] Starting subtest: single-move
[252.211963] Starting dynamic subtest: pipe-A
[257.618600] Dynamic subtest pipe-A: SUCCESS (5.401s)
[257.655217] Starting dynamic subtest: pipe-B
[263.043643] Dynamic subtest pipe-B: SUCCESS (5.426s)
[263.069869] Starting dynamic subtest: pipe-C
[268.456566] Dynamic subtest pipe-C: SUCCESS (5.413s)
[268.479583] Starting dynamic subtest: all-pipes
[273.879600] Dynamic subtest all-pipes: SUCCESS (5.417s)
[273.886115] Subtest single-move: SUCCESS (21.670s)
[274.070510] [034/133] (779s left) gem_exec_schedule (submit-late-slice)
[274.211275] Starting subtest: submit-late-slice
[274.214481] Starting dynamic subtest: rcs0
[274.262542] Dynamic subtest rcs0: SUCCESS (0.054s)
[274.285568] Starting dynamic subtest: bcs0
[274.318599] Dynamic subtest bcs0: SUCCESS (0.055s)
[274.349694] Starting dynamic subtest: vcs0
[274.373130] Dynamic subtest vcs0: SUCCESS (0.049s)
[274.396123] Starting dynamic subtest: vecs0
[274.433062] Dynamic subtest vecs0: SUCCESS (0.057s)
[274.436523] Subtest submit-late-slice: SUCCESS (0.229s)
[274.873717] [035/133] (779s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[275.084468] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[277.624165] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (2.548s)
[277.885586] [036/133] (776s left) gem_set_tiling_vs_gtt
[278.471911] [037/133] (775s left) gem_exec_reloc (basic-wc-noreloc)
[278.592114] Starting subtest: basic-wc-noreloc
[278.602134] Subtest basic-wc-noreloc: SUCCESS (0.009s)
[278.857224] [038/133] (775s left) gem_exec_reloc (basic-gtt-active)
[278.978932] Starting subtest: basic-gtt-active
[279.120292] Subtest basic-gtt-active: SUCCESS (0.142s)
[279.387809] [039/133] (775s left) sw_sync (timeline_closed_signaled)
[279.494125] Starting subtest: timeline_closed_signaled
[279.499640] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[279.654750] [040/133] (774s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[279.875145] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[282.376071] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.508s)
[282.606155] [041/133] (772s left) kms_atomic (plane-cursor-legacy)
[282.792883] Starting subtest: plane-cursor-legacy
[282.796363] Starting dynamic subtest: pipe-A-HDMI-A-1
[283.335725] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.535s)
[283.339684] Subtest plane-cursor-legacy: SUCCESS (0.541s)
[283.705225] [042/133] (771s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[283.823928] Starting subtest: multi-wait-all-for-submit-signaled
[283.827393] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.002s)
[284.003179] [043/133] (770s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[284.208628] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[285.253775] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.045s)
[285.604921] [044/133] (769s left) kms_frontbuffer_tracking (psr-argb161616f-draw-mmap-cpu)
[285.816149] Starting subtest: psr-argb161616f-draw-mmap-cpu
[285.822647] Subtest psr-argb161616f-draw-mmap-cpu: SKIP (0.000s)
[286.017463] [045/133] (769s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[286.229622] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[286.238106] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[286.420740] [046/133] (768s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[286.640424] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[289.041356] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SUCCESS (2.407s)
[289.286912] [047/133] (766s left) gem_exec_params (no-blt)
[289.404812] Starting subtest: no-blt
[289.414607] Subtest no-blt: SUCCESS (0.003s)
[289.602444] [048/133] (765s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[289.819980] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[289.921807] [049/133] (765s left) syncobj_wait (multi-wait-submitted-signaled)
[290.042337] Starting subtest: multi-wait-submitted-signaled
[290.045629] Subtest multi-wait-submitted-signaled: SUCCESS (0.002s)
[290.219431] [050/133] (765s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[290.420981] Starting subtest: y-tiled-8bpp-rotate-0
[293.388504] Subtest y-tiled-8bpp-rotate-0: SUCCESS (2.967s)
[293.719978] [051/133] (762s left) i915_pm_rps (thresholds)
[293.844569] Starting subtest: thresholds
[293.847741] Starting dynamic subtest: gt0
[303.944069] Dynamic subtest gt0: SUCCESS (10.097s)
[303.947133] Subtest thresholds: SUCCESS (10.102s)
[304.120505] [052/133] (751s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[304.331330] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[304.338895] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[304.520180] [053/133] (751s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[304.723985] Starting subtest: psr-rgb565-draw-pwrite
[304.730384] Subtest psr-rgb565-draw-pwrite: SKIP (0.000s)
[304.906286] [054/133] (751s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[305.130250] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[305.138272] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[305.319084] [055/133] (750s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[305.524588] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[308.536531] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (3.016s)
[308.869896] [056/133] (747s left) gem_exec_fence (syncobj-timeline-chain-engines)
[309.549791] Starting subtest: syncobj-timeline-chain-engines
[309.672512] Subtest syncobj-timeline-chain-engines: SUCCESS (0.121s)
[309.985899] [057/133] (746s left) kms_vblank (ts-continuation-dpms-suspend)
[310.184112] Starting subtest: ts-continuation-dpms-suspend
[310.187306] Starting dynamic subtest: pipe-A-HDMI-A-1
[328.933255] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.017s)
[329.018468] Starting dynamic subtest: pipe-A-HDMI-A-2