[87.591742] Initializing watchdogs
[87.591943]   /dev/watchdog0
[87.630969] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[87.646177] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.652899] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.658415] [001/133] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[87.870130] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[87.955411] [002/133] (960s left) syncobj_wait (wait-all-interrupted)
[88.057138] Starting subtest: wait-all-interrupted
[88.162094] Subtest wait-all-interrupted: SUCCESS (0.102s)
[88.293716] [003/133] (960s left) syncobj_wait (reset-unsignaled)
[88.392352] Starting subtest: reset-unsignaled
[88.393338] Subtest reset-unsignaled: SUCCESS (0.000s)
[88.516833] [004/133] (959s left) kms_busy (extended-modeset-hang-oldfb)
[88.718110] Starting subtest: extended-modeset-hang-oldfb
[88.722294] Starting dynamic subtest: pipe-A
[93.268276] Dynamic subtest pipe-A: SUCCESS (4.539s)
[93.307076] Starting dynamic subtest: pipe-C
[98.169652] Dynamic subtest pipe-C: SUCCESS (4.898s)
[98.176232] Subtest extended-modeset-hang-oldfb: SUCCESS (9.454s)
[98.552181] [005/133] (949s left) gem_exec_schedule (preemptive-hang)
[98.822277] Starting subtest: preemptive-hang
[98.823367] Starting dynamic subtest: rcs0
[105.100867] Dynamic subtest rcs0: SUCCESS (6.274s)
[105.133843] Starting dynamic subtest: bcs0
[111.112649] Dynamic subtest bcs0: SUCCESS (6.017s)
[111.147358] Starting dynamic subtest: vcs0
[117.140064] Dynamic subtest vcs0: SUCCESS (6.007s)
[117.168089] Starting dynamic subtest: vecs0
[123.094221] Dynamic subtest vecs0: SUCCESS (5.962s)
[123.102290] Subtest preemptive-hang: SUCCESS (24.267s)
[123.344148] [006/133] (925s left) gem_caching (read-writes)
[123.458673] Starting subtest: read-writes
[146.102583] Subtest read-writes: SUCCESS (22.640s)
[146.266056] [007/133] (902s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[146.452199] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[146.455488] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[146.587664] [008/133] (902s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[146.710281] Starting subtest: y-tiled-ccs-to-yf-tiled
[146.711630] Starting dynamic subtest: smem
[147.034170] Dynamic subtest smem: SUCCESS (0.324s)
[147.035340] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.324s)
[147.281231] [009/133] (901s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[147.483865] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[147.488795] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[147.627760] [010/133] (901s left) kms_pwrite_crc
[149.104399] [011/133] (899s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[149.310235] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[149.313737] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[149.452539] [012/133] (899s left) gem_exec_balancer (indices)
[149.579178] Starting subtest: indices
[153.939660] Subtest indices: SUCCESS (4.362s)
[154.189187] [013/133] (894s left) gem_create (create-ext-cpu-access-big)
[154.410435] Starting subtest: create-ext-cpu-access-big
[154.413261] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[154.540312] [014/133] (894s left) gem_eio (banned)
[154.668112] Starting subtest: banned
[154.826084] Subtest banned: SUCCESS (0.155s)
[155.060633] [015/133] (894s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[155.245539] Starting subtest: invalid-modeset-force-ultra-joiner
[155.248593] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[155.406444] [016/133] (893s left) device_reset (cold-reset-bound)
[155.546873] Starting subtest: cold-reset-bound
[155.549221] Subtest cold-reset-bound: SKIP (0.000s)
[155.679254] [017/133] (893s left) i915_hangman (engine-engine-error)
[155.809045] Starting subtest: engine-engine-error
[155.812059] Starting dynamic subtest: rcs0
[155.876406] Dynamic subtest rcs0: SUCCESS (0.068s)
[155.897378] Starting dynamic subtest: bcs0
[155.899283] Dynamic subtest bcs0: SKIP (0.003s)
[155.917846] Starting dynamic subtest: vcs0
[155.954099] Dynamic subtest vcs0: SUCCESS (0.072s)
[155.973670] Starting dynamic subtest: vecs0
[156.024158] Dynamic subtest vecs0: SUCCESS (0.068s)
[156.026140] Subtest engine-engine-error: SUCCESS (0.219s)
[156.219366] [018/133] (893s left) gem_spin_batch (engines)
[156.345847] Starting subtest: engines
[156.346916] Starting dynamic subtest: rcs0
[159.578054] Dynamic subtest rcs0: SUCCESS (3.231s)
[159.596909] Starting dynamic subtest: bcs0
[162.809061] Dynamic subtest bcs0: SUCCESS (3.229s)
[162.827448] Starting dynamic subtest: vcs0
[166.037043] Dynamic subtest vcs0: SUCCESS (3.228s)
[166.056833] Starting dynamic subtest: vecs0
[169.266367] Dynamic subtest vecs0: SUCCESS (3.230s)
[169.267316] Subtest engines: SUCCESS (12.923s)
[169.506531] [019/133] (880s left) prime_mmap (test_correct)
[169.608113] Starting subtest: test_correct
[169.609075] Starting dynamic subtest: test_correct-smem
[169.617284] Dynamic subtest test_correct-smem: SUCCESS (0.010s)
[169.620577] Subtest test_correct: SUCCESS (0.011s)
[169.757515] [020/133] (879s left) kms_pm_rpm (legacy-planes)
[171.690294] Starting subtest: legacy-planes
[171.691739] Starting dynamic subtest: plane-45
[173.247060] Dynamic subtest plane-45: SUCCESS (1.556s)
[173.274086] Starting dynamic subtest: plane-54
[174.738744] Dynamic subtest plane-54: SUCCESS (1.491s)
[174.759449] Starting dynamic subtest: plane-63
[176.292186] Dynamic subtest plane-63: SUCCESS (1.550s)
[176.293345] Subtest legacy-planes: SUCCESS (4.604s)
[176.655258] [021/133] (873s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[176.865305] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[176.868208] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[177.009627] [022/133] (872s left) kms_cursor_crc (cursor-onscreen-256x85)
[177.209973] Starting subtest: cursor-onscreen-256x85
[177.271132] Starting dynamic subtest: pipe-A-HDMI-A-1
[179.983145] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.710s)
[180.096238] Starting dynamic subtest: pipe-C-HDMI-A-1
[182.519919] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.447s)
[182.521064] Subtest cursor-onscreen-256x85: SUCCESS (5.308s)
[182.899717] [023/133] (867s left) sw_sync (alloc_fence_invalid_timeline)
[183.002155] Starting subtest: alloc_fence_invalid_timeline
[183.002996] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[183.113310] [024/133] (866s left) api_intel_allocator (two-level-inception)
[183.211077] Starting subtest: two-level-inception
[188.739424] Subtest two-level-inception: SUCCESS (5.528s)
[188.865274] [025/133] (861s left) kms_big_fb (linear-64bpp-rotate-180)
[189.062186] Starting subtest: linear-64bpp-rotate-180
[194.516642] Subtest linear-64bpp-rotate-180: SUCCESS (5.457s)
[195.028084] [026/133] (855s left) kms_chamelium_frames (hdmi-crc-single)
[195.593882] Subtest hdmi-crc-single: SKIP (0.000s)
[195.924490] [027/133] (854s left) kms_plane_scaling (invalid-num-scalers)
[196.107241] Starting subtest: invalid-num-scalers
[196.125668] Starting dynamic subtest: pipe-A-HDMI-A-1-invalid-num-scalers
[196.199089] Dynamic subtest pipe-A-HDMI-A-1-invalid-num-scalers: SUCCESS (0.071s)
[196.282214] Starting dynamic subtest: pipe-A-HDMI-A-2-invalid-num-scalers
[196.378185] Dynamic subtest pipe-A-HDMI-A-2-invalid-num-scalers: SUCCESS (0.134s)
[196.463499] Starting dynamic subtest: pipe-B-HDMI-A-1-invalid-num-scalers
[196.560059] Dynamic subtest pipe-B-HDMI-A-1-invalid-num-scalers: SUCCESS (0.134s)
[196.644451] Starting dynamic subtest: pipe-B-HDMI-A-2-invalid-num-scalers
[196.735100] Dynamic subtest pipe-B-HDMI-A-2-invalid-num-scalers: SUCCESS (0.129s)
[196.822501] Starting dynamic subtest: pipe-C-HDMI-A-1-invalid-num-scalers
[196.996351] Dynamic subtest pipe-C-HDMI-A-1-invalid-num-scalers: SUCCESS (0.212s)
[197.080393] Starting dynamic subtest: pipe-C-HDMI-A-2-invalid-num-scalers
[197.264620] Dynamic subtest pipe-C-HDMI-A-2-invalid-num-scalers: SUCCESS (0.220s)
[197.266589] Subtest invalid-num-scalers: SUCCESS (1.165s)
[197.426218] [028/133] (852s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[197.621844] Starting subtest: primary-4-tiled-reflect-x-180
[197.627277] Subtest primary-4-tiled-reflect-x-180: SKIP (0.005s)
[197.762430] [029/133] (852s left) gen9_exec_parse (bb-start-cmd)
[197.890683] Starting subtest: bb-start-cmd
[197.892471] Subtest bb-start-cmd: SUCCESS (0.005s)
[198.144246] [030/133] (852s left) drm_read (short-buffer-block)
[198.625461] Starting subtest: short-buffer-block
[198.626469] Subtest short-buffer-block: SUCCESS (0.000s)
[198.876711] [031/133] (851s left) gem_exec_schedule (preempt-self)
[199.010165] Starting subtest: preempt-self
[199.011685] Starting dynamic subtest: rcs0
[199.036476] Dynamic subtest rcs0: SUCCESS (0.027s)
[199.089670] Starting dynamic subtest: bcs0
[199.091745] Dynamic subtest bcs0: SUCCESS (0.030s)
[199.142628] Starting dynamic subtest: vcs0
[199.144086] Dynamic subtest vcs0: SUCCESS (0.027s)
[199.162045] Starting dynamic subtest: vecs0
[199.162896] Dynamic subtest vecs0: SUCCESS (0.025s)
[199.163750] Subtest preempt-self: SUCCESS (0.116s)
[199.512749] [032/133] (851s left) gem_exec_params (no-vebox)
[199.626022] Starting subtest: no-vebox
[199.628757] Subtest no-vebox: SUCCESS (0.003s)
[199.777075] [033/133] (850s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[200.558308] Starting subtest: syncobj-timeline-invalid-flags
[200.559592] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.002s)
[200.849398] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[200.853683] [034/133] (849s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[201.043111] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[201.049116] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[201.188638] [035/133] (849s left) gem_ctx_param (basic-default)
[201.293303] Starting subtest: basic-default
[201.296312] Subtest basic-default: SUCCESS (0.000s)
[201.436944] [036/133] (849s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[201.539286] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[201.541248] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.002s)
[201.672862] [037/133] (849s left) gem_pxp (reject-modify-context-protection-off-2)
[204.316493] Starting subtest: reject-modify-context-protection-off-2
[204.320058] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[204.453736] [038/133] (846s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[204.639436] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[204.650286] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[205.410099] [039/133] (845s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[205.603523] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[205.604510] Starting dynamic subtest: pipe-A-HDMI-A-1
[205.617740] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[205.656259] Starting dynamic subtest: pipe-A-HDMI-A-2
[205.680250] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[205.716270] Starting dynamic subtest: pipe-B-HDMI-A-1
[205.746333] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.004s)
[205.782360] Starting dynamic subtest: pipe-B-HDMI-A-2
[205.812608] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.005s)
[205.850254] Starting dynamic subtest: pipe-C-HDMI-A-1
[205.858818] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.005s)
[205.876834] Starting dynamic subtest: pipe-C-HDMI-A-2
[205.886097] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.005s)
[205.887852] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.059s)
[206.794720] [040/133] (844s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[206.998066] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[207.001365] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[207.144250] [041/133] (844s left) gem_gtt_cpu_tlb
[207.395188] [042/133] (844s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[207.591069] Starting subtest: y-tiled-8bpp-rotate-90
[207.915475] Subtest y-tiled-8bpp-rotate-90: SKIP (0.323s)
[208.177999] [043/133] (843s left) kms_pm_lpsp (screens-disabled)
[209.371269] Starting subtest: screens-disabled
[209.376080] Subtest screens-disabled: SKIP (0.000s)
[209.509606] [044/133] (842s left) gem_exec_reloc (basic-cpu-wc)
[209.624756] Starting subtest: basic-cpu-wc
[209.640896] Subtest basic-cpu-wc: SUCCESS (0.019s)
[209.860293] [045/133] (841s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[210.073129] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[212.473747] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SUCCESS (2.403s)
[212.776921] [046/133] (839s left) gem_ctx_create (basic)
[212.901515] Starting subtest: basic
[212.902452] Subtest basic: SUCCESS (0.000s)
[213.162542] [047/133] (838s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[213.269292] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[213.376953] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.106s)
[213.510848] [048/133] (838s left) kms_universal_plane (universal-plane-pageflip-windowed)
[213.697255] Starting subtest: universal-plane-pageflip-windowed
[213.700167] Starting dynamic subtest: pipe-A-HDMI-A-1
[214.575083] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.874s)
[214.617358] Starting dynamic subtest: pipe-B-HDMI-A-2
[215.134339] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.557s)
[215.167966] Starting dynamic subtest: pipe-C-HDMI-A-1
[215.686655] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.547s)
[215.688164] Subtest universal-plane-pageflip-windowed: SUCCESS (1.996s)
[216.028985] [049/133] (836s left) i915_suspend (basic-s3-without-i915)
[216.131533] Starting subtest: basic-s3-without-i915