[87.892151] Initializing watchdogs
[87.892360]   /dev/watchdog0
[87.932549] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[87.947390] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.954840] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.963204] [001/133] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[88.201940] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[88.288421] [002/133] (960s left) syncobj_wait (wait-all-interrupted)
[88.401796] Starting subtest: wait-all-interrupted
[88.511398] Subtest wait-all-interrupted: SUCCESS (0.107s)
[88.637036] [003/133] (960s left) syncobj_wait (reset-unsignaled)
[88.747804] Starting subtest: reset-unsignaled
[88.753115] Subtest reset-unsignaled: SUCCESS (0.000s)
[88.884086] [004/133] (959s left) kms_busy (extended-modeset-hang-oldfb)
[89.083522] Starting subtest: extended-modeset-hang-oldfb
[89.089809] Starting dynamic subtest: pipe-A
[94.190416] Dynamic subtest pipe-A: SUCCESS (5.098s)
[94.216356] Starting dynamic subtest: pipe-C
[98.162316] Dynamic subtest pipe-C: SUCCESS (3.961s)
[98.165667] Subtest extended-modeset-hang-oldfb: SUCCESS (9.076s)
[98.523526] [005/133] (950s left) gem_exec_schedule (preemptive-hang)
[98.784225] Starting subtest: preemptive-hang
[98.789983] Starting dynamic subtest: rcs0
[105.102689] Dynamic subtest rcs0: SUCCESS (6.315s)
[105.133691] Starting dynamic subtest: bcs0
[111.115097] Dynamic subtest bcs0: SUCCESS (6.012s)
[111.146634] Starting dynamic subtest: vcs0
[117.138826] Dynamic subtest vcs0: SUCCESS (6.023s)
[117.171847] Starting dynamic subtest: vecs0
[123.099034] Dynamic subtest vecs0: SUCCESS (5.951s)
[123.106240] Subtest preemptive-hang: SUCCESS (24.305s)
[123.353951] [006/133] (925s left) gem_caching (read-writes)
[123.473437] Starting subtest: read-writes
[145.553623] Subtest read-writes: SUCCESS (22.077s)
[145.725067] [007/133] (903s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[145.935906] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[145.942499] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[146.086758] [008/133] (903s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[146.205408] Starting subtest: y-tiled-ccs-to-yf-tiled
[146.208670] Starting dynamic subtest: smem
[146.578302] Dynamic subtest smem: SUCCESS (0.324s)
[146.646679] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.325s)
[147.168248] [009/133] (902s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[147.839979] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[147.848637] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[147.933316] [010/133] (901s left) kms_pwrite_crc
[149.344922] [011/133] (900s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[149.584073] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[149.591116] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[149.727633] [012/133] (900s left) gem_exec_balancer (indices)
[149.863263] Starting subtest: indices
[154.171486] Subtest indices: SUCCESS (4.311s)
[154.457990] [013/133] (895s left) gem_create (create-ext-cpu-access-big)
[154.688095] Starting subtest: create-ext-cpu-access-big
[154.694653] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[154.825306] [014/133] (895s left) gem_eio (banned)
[154.972980] Starting subtest: banned
[155.152062] Subtest banned: SUCCESS (0.178s)
[155.380578] [015/133] (894s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[155.575082] Starting subtest: invalid-modeset-force-ultra-joiner
[155.583100] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[155.713258] [016/133] (894s left) device_reset (cold-reset-bound)
[155.860653] Starting subtest: cold-reset-bound
[155.871559] Subtest cold-reset-bound: SKIP (0.000s)
[155.992195] [017/133] (894s left) i915_hangman (engine-engine-error)
[156.124972] Starting subtest: engine-engine-error
[156.130684] Starting dynamic subtest: rcs0
[156.203603] Dynamic subtest rcs0: SUCCESS (0.078s)
[156.226802] Starting dynamic subtest: bcs0
[156.236322] Dynamic subtest bcs0: SKIP (0.003s)
[156.268361] Starting dynamic subtest: vcs0
[156.277618] Dynamic subtest vcs0: SUCCESS (0.065s)
[156.299738] Starting dynamic subtest: vecs0
[156.338995] Dynamic subtest vecs0: SUCCESS (0.062s)
[156.342548] Subtest engine-engine-error: SUCCESS (0.215s)
[156.491448] [018/133] (893s left) gem_spin_batch (engines)
[156.621252] Starting subtest: engines
[156.625304] Starting dynamic subtest: rcs0
[159.852740] Dynamic subtest rcs0: SUCCESS (3.228s)
[159.873341] Starting dynamic subtest: bcs0
[163.086735] Dynamic subtest bcs0: SUCCESS (3.228s)
[163.107349] Starting dynamic subtest: vcs0
[166.312179] Dynamic subtest vcs0: SUCCESS (3.228s)
[166.332931] Starting dynamic subtest: vecs0
[169.539325] Dynamic subtest vecs0: SUCCESS (3.227s)
[169.543426] Subtest engines: SUCCESS (12.914s)
[169.777061] [019/133] (880s left) prime_mmap (test_correct)
[169.889070] Starting subtest: test_correct
[169.892319] Starting dynamic subtest: test_correct-smem
[169.895665] Dynamic subtest test_correct-smem: SUCCESS (0.010s)
[169.899953] Subtest test_correct: SUCCESS (0.011s)
[170.042180] [020/133] (880s left) kms_pm_rpm (legacy-planes)
[171.955983] Starting subtest: legacy-planes
[171.960101] Starting dynamic subtest: plane-45
[173.477934] Dynamic subtest plane-45: SUCCESS (1.524s)
[173.502831] Starting dynamic subtest: plane-54
[174.975375] Dynamic subtest plane-54: SUCCESS (1.494s)
[175.008083] Starting dynamic subtest: plane-63
[176.479414] Dynamic subtest plane-63: SUCCESS (1.499s)
[176.486374] Subtest legacy-planes: SUCCESS (4.527s)
[176.842232] [021/133] (873s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[177.060185] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[177.067085] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[177.208345] [022/133] (873s left) kms_cursor_crc (cursor-onscreen-256x85)
[177.418402] Starting subtest: cursor-onscreen-256x85
[177.473640] Starting dynamic subtest: pipe-A-HDMI-A-1
[180.171238] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.692s)
[180.274551] Starting dynamic subtest: pipe-C-HDMI-A-1
[182.625201] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.386s)
[182.629139] Subtest cursor-onscreen-256x85: SUCCESS (5.202s)
[182.927330] [023/133] (867s left) sw_sync (alloc_fence_invalid_timeline)
[183.035080] Starting subtest: alloc_fence_invalid_timeline
[183.038723] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[183.153364] [024/133] (867s left) api_intel_allocator (two-level-inception)
[183.275288] Starting subtest: two-level-inception
[188.777615] Subtest two-level-inception: SUCCESS (5.521s)
[188.921848] [025/133] (862s left) kms_big_fb (linear-64bpp-rotate-180)
[189.133760] Starting subtest: linear-64bpp-rotate-180
[194.599085] Subtest linear-64bpp-rotate-180: SUCCESS (5.466s)
[195.107409] [026/133] (856s left) kms_chamelium_frames (hdmi-crc-single)
[195.684935] Subtest hdmi-crc-single: SKIP (0.000s)
[195.970569] [027/133] (855s left) kms_plane_scaling (invalid-num-scalers)
[196.175217] Starting subtest: invalid-num-scalers
[196.209424] Starting dynamic subtest: pipe-A-HDMI-A-1-invalid-num-scalers
[196.309685] Dynamic subtest pipe-A-HDMI-A-1-invalid-num-scalers: SUCCESS (0.101s)
[196.388319] Starting dynamic subtest: pipe-A-HDMI-A-2-invalid-num-scalers
[196.467465] Dynamic subtest pipe-A-HDMI-A-2-invalid-num-scalers: SUCCESS (0.114s)
[196.538258] Starting dynamic subtest: pipe-B-HDMI-A-1-invalid-num-scalers
[196.631472] Dynamic subtest pipe-B-HDMI-A-1-invalid-num-scalers: SUCCESS (0.115s)
[196.685173] Starting dynamic subtest: pipe-B-HDMI-A-2-invalid-num-scalers
[196.751350] Dynamic subtest pipe-B-HDMI-A-2-invalid-num-scalers: SUCCESS (0.101s)
[196.826315] Starting dynamic subtest: pipe-C-HDMI-A-1-invalid-num-scalers
[196.962272] Dynamic subtest pipe-C-HDMI-A-1-invalid-num-scalers: SUCCESS (0.176s)
[197.037392] Starting dynamic subtest: pipe-C-HDMI-A-2-invalid-num-scalers
[197.180113] Dynamic subtest pipe-C-HDMI-A-2-invalid-num-scalers: SUCCESS (0.181s)
[197.184170] Subtest invalid-num-scalers: SUCCESS (1.012s)
[197.366992] [028/133] (853s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[197.553853] Starting subtest: primary-4-tiled-reflect-x-180
[197.567708] Subtest primary-4-tiled-reflect-x-180: SKIP (0.006s)
[197.700358] [029/133] (853s left) gen9_exec_parse (bb-start-cmd)
[197.834330] Starting subtest: bb-start-cmd
[197.842125] Subtest bb-start-cmd: SUCCESS (0.006s)
[198.101837] [030/133] (853s left) drm_read (short-buffer-block)
[198.551825] Starting subtest: short-buffer-block
[198.555097] Subtest short-buffer-block: SUCCESS (0.000s)
[198.849882] [031/133] (852s left) gem_exec_schedule (preempt-self)
[199.004086] Starting subtest: preempt-self
[199.008331] Starting dynamic subtest: rcs0
[199.035548] Dynamic subtest rcs0: SUCCESS (0.036s)
[199.086734] Starting dynamic subtest: bcs0
[199.096398] Dynamic subtest bcs0: SUCCESS (0.053s)
[199.145372] Starting dynamic subtest: vcs0
[199.155644] Dynamic subtest vcs0: SUCCESS (0.051s)
[199.199051] Starting dynamic subtest: vecs0
[199.211717] Dynamic subtest vecs0: SUCCESS (0.049s)
[199.221515] Subtest preempt-self: SUCCESS (0.222s)
[199.616523] [032/133] (852s left) gem_exec_params (no-vebox)
[199.735102] Starting subtest: no-vebox
[199.738735] Subtest no-vebox: SUCCESS (0.003s)
[199.892540] [033/133] (851s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[200.676783] Starting subtest: syncobj-timeline-invalid-flags
[200.681550] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[200.935725] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[200.942881] [034/133] (850s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[201.154174] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[201.162224] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[201.295828] [035/133] (850s left) gem_ctx_param (basic-default)
[201.412653] Starting subtest: basic-default
[201.416734] Subtest basic-default: SUCCESS (0.000s)
[201.558471] [036/133] (850s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[201.668719] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[201.672777] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.003s)
[201.825525] [037/133] (850s left) gem_pxp (reject-modify-context-protection-off-2)
[204.484993] Starting subtest: reject-modify-context-protection-off-2
[204.496667] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[204.625436] [038/133] (847s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[204.805070] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[204.819130] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[205.527639] [039/133] (846s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[205.713457] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[205.717080] Starting dynamic subtest: pipe-A-HDMI-A-1
[205.758573] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[205.803472] Starting dynamic subtest: pipe-A-HDMI-A-2
[205.848550] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[205.869708] Starting dynamic subtest: pipe-B-HDMI-A-1
[205.901795] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[205.923479] Starting dynamic subtest: pipe-B-HDMI-A-2
[205.955592] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[205.976741] Starting dynamic subtest: pipe-C-HDMI-A-1
[206.005896] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[206.027582] Starting dynamic subtest: pipe-C-HDMI-A-2
[206.057800] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[206.063820] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.034s)
[207.162386] [040/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[207.866680] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[208.087364] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[208.184803] [041/133] (844s left) gem_gtt_cpu_tlb
[208.469344] [042/133] (844s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[208.675290] Starting subtest: y-tiled-8bpp-rotate-90
[209.002928] Subtest y-tiled-8bpp-rotate-90: SKIP (0.320s)
[209.258898] [043/133] (843s left) kms_pm_lpsp (screens-disabled)
[210.467389] Starting subtest: screens-disabled
[210.477089] Subtest screens-disabled: SKIP (0.000s)
[210.610513] [044/133] (842s left) gem_exec_reloc (basic-cpu-wc)
[210.738876] Starting subtest: basic-cpu-wc
[210.751105] Subtest basic-cpu-wc: SUCCESS (0.017s)
[210.973451] [045/133] (842s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[211.185113] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[213.574088] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SUCCESS (2.393s)
[213.873585] [046/133] (839s left) gem_ctx_create (basic)
[214.015536] Starting subtest: basic
[214.019708] Subtest basic: SUCCESS (0.000s)
[214.271635] [047/133] (839s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[214.384540] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[214.491211] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.111s)
[214.653778] [048/133] (839s left) kms_universal_plane (universal-plane-pageflip-windowed)
[214.849104] Starting subtest: universal-plane-pageflip-windowed
[214.853224] Starting dynamic subtest: pipe-A-HDMI-A-1
[215.674230] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.823s)
[215.723484] Starting dynamic subtest: pipe-B-HDMI-A-2
[216.218627] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.540s)
[216.255304] Starting dynamic subtest: pipe-C-HDMI-A-1
[216.781362] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.557s)
[216.787370] Subtest universal-plane-pageflip-windowed: SUCCESS (1.936s)
[217.130206] [049/133] (836s left) i915_suspend (basic-s3-without-i915)
[217.238041] Starting subtest: basic-s3-without-i915