[86.915643] Initializing watchdogs
[86.915817]   /dev/watchdog0
[86.954821] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[86.969847] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.977823] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.983979] [001/133] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[87.200448] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[87.302829] [002/133] (960s left) syncobj_wait (wait-all-interrupted)
[87.411974] Starting subtest: wait-all-interrupted
[87.514232] Subtest wait-all-interrupted: SUCCESS (0.103s)
[87.658497] [003/133] (959s left) syncobj_wait (reset-unsignaled)
[87.758872] Starting subtest: reset-unsignaled
[87.760637] Subtest reset-unsignaled: SUCCESS (0.000s)
[87.895754] [004/133] (959s left) kms_busy (extended-modeset-hang-oldfb)
[88.100606] Starting subtest: extended-modeset-hang-oldfb
[88.105132] Starting dynamic subtest: pipe-A
[93.236672] Dynamic subtest pipe-A: SUCCESS (5.135s)
[93.268238] Starting dynamic subtest: pipe-C
[98.202293] Dynamic subtest pipe-C: SUCCESS (4.953s)
[98.205438] Subtest extended-modeset-hang-oldfb: SUCCESS (10.107s)
[98.658783] [005/133] (949s left) gem_exec_schedule (preemptive-hang)
[98.917666] Starting subtest: preemptive-hang
[98.920974] Starting dynamic subtest: rcs0
[105.097307] Dynamic subtest rcs0: SUCCESS (6.180s)
[105.127870] Starting dynamic subtest: bcs0
[111.135127] Dynamic subtest bcs0: SUCCESS (6.021s)
[111.157938] Starting dynamic subtest: vcs0
[117.135175] Dynamic subtest vcs0: SUCCESS (6.006s)
[117.152858] Starting dynamic subtest: vecs0
[123.096445] Dynamic subtest vecs0: SUCCESS (5.958s)
[123.098749] Subtest preemptive-hang: SUCCESS (24.169s)
[123.349226] [006/133] (924s left) gem_caching (read-writes)
[123.464189] Starting subtest: read-writes
[145.655297] Subtest read-writes: SUCCESS (22.193s)
[145.851421] [007/133] (902s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[146.049367] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[146.053274] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[146.193270] [008/133] (901s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[146.315092] Starting subtest: y-tiled-ccs-to-yf-tiled
[146.317162] Starting dynamic subtest: smem
[146.640286] Dynamic subtest smem: SUCCESS (0.325s)
[146.641936] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.325s)
[146.886430] [009/133] (901s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[147.078357] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[147.083108] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[147.215720] [010/133] (901s left) kms_pwrite_crc
[148.676247] [011/133] (899s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[148.888424] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[148.892287] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[149.028597] [012/133] (899s left) gem_exec_balancer (indices)
[149.152752] Starting subtest: indices
[153.462475] Subtest indices: SUCCESS (4.310s)
[153.732230] [013/133] (894s left) gem_create (create-ext-cpu-access-big)
[153.957171] Starting subtest: create-ext-cpu-access-big
[153.960615] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[154.111164] [014/133] (894s left) gem_eio (banned)
[154.238469] Starting subtest: banned
[154.441165] Subtest banned: SUCCESS (0.203s)
[154.664279] [015/133] (893s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[154.859366] Starting subtest: invalid-modeset-force-ultra-joiner
[154.862804] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[154.996704] [016/133] (893s left) device_reset (cold-reset-bound)
[155.141353] Starting subtest: cold-reset-bound
[155.145226] Subtest cold-reset-bound: SKIP (0.000s)
[155.266294] [017/133] (893s left) i915_hangman (engine-engine-error)
[155.398078] Starting subtest: engine-engine-error
[155.401072] Starting dynamic subtest: rcs0
[155.472895] Dynamic subtest rcs0: SUCCESS (0.078s)
[155.493474] Starting dynamic subtest: bcs0
[155.496440] Dynamic subtest bcs0: SKIP (0.003s)
[155.514970] Starting dynamic subtest: vcs0
[155.565662] Dynamic subtest vcs0: SUCCESS (0.081s)
[155.587019] Starting dynamic subtest: vecs0
[155.634899] Dynamic subtest vecs0: SUCCESS (0.071s)
[155.636523] Subtest engine-engine-error: SUCCESS (0.243s)
[155.780405] [018/133] (893s left) gem_spin_batch (engines)
[155.902130] Starting subtest: engines
[155.905122] Starting dynamic subtest: rcs0
[159.137547] Dynamic subtest rcs0: SUCCESS (3.230s)
[159.156611] Starting dynamic subtest: bcs0
[162.367173] Dynamic subtest bcs0: SUCCESS (3.230s)
[162.385939] Starting dynamic subtest: vcs0
[165.596924] Dynamic subtest vcs0: SUCCESS (3.229s)
[165.615788] Starting dynamic subtest: vecs0
[168.826226] Dynamic subtest vecs0: SUCCESS (3.229s)
[168.829276] Subtest engines: SUCCESS (12.922s)
[169.067209] [019/133] (879s left) prime_mmap (test_correct)
[169.171656] Starting subtest: test_correct
[169.173276] Starting dynamic subtest: test_correct-smem
[169.181049] Dynamic subtest test_correct-smem: SUCCESS (0.011s)
[169.182977] Subtest test_correct: SUCCESS (0.011s)
[169.362525] [020/133] (879s left) kms_pm_rpm (legacy-planes)
[171.298651] Starting subtest: legacy-planes
[171.301573] Starting dynamic subtest: plane-45
[173.097055] Dynamic subtest plane-45: SUCCESS (1.800s)
[173.118036] Starting dynamic subtest: plane-54
[174.682668] Dynamic subtest plane-54: SUCCESS (1.583s)
[174.713112] Starting dynamic subtest: plane-63
[176.220227] Dynamic subtest plane-63: SUCCESS (1.536s)
[176.224152] Subtest legacy-planes: SUCCESS (4.924s)
[176.659312] [021/133] (872s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[176.869835] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[176.872947] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[177.006154] [022/133] (872s left) kms_cursor_crc (cursor-onscreen-256x85)
[177.212072] Starting subtest: cursor-onscreen-256x85
[177.274689] Starting dynamic subtest: pipe-A-HDMI-A-1
[180.019223] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.741s)
[180.105939] Starting dynamic subtest: pipe-C-HDMI-A-1
[182.532168] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.437s)
[182.536094] Subtest cursor-onscreen-256x85: SUCCESS (5.317s)
[182.910311] [023/133] (866s left) sw_sync (alloc_fence_invalid_timeline)
[183.013209] Starting subtest: alloc_fence_invalid_timeline
[183.015303] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[183.125764] [024/133] (866s left) api_intel_allocator (two-level-inception)
[183.223169] Starting subtest: two-level-inception
[188.739402] Subtest two-level-inception: SUCCESS (5.515s)
[188.875766] [025/133] (860s left) kms_big_fb (linear-64bpp-rotate-180)
[189.086903] Starting subtest: linear-64bpp-rotate-180
[194.588349] Subtest linear-64bpp-rotate-180: SUCCESS (5.504s)
[195.092401] [026/133] (854s left) kms_chamelium_frames (hdmi-crc-single)
[195.700357] Subtest hdmi-crc-single: SKIP (0.000s)
[196.003120] [027/133] (853s left) kms_plane_scaling (invalid-num-scalers)
[196.193091] Starting subtest: invalid-num-scalers
[196.237197] Starting dynamic subtest: pipe-A-HDMI-A-1-invalid-num-scalers
[196.322456] Dynamic subtest pipe-A-HDMI-A-1-invalid-num-scalers: SUCCESS (0.081s)
[196.388613] Starting dynamic subtest: pipe-A-HDMI-A-2-invalid-num-scalers
[196.421203] Dynamic subtest pipe-A-HDMI-A-2-invalid-num-scalers: SUCCESS (0.071s)
[196.486624] Starting dynamic subtest: pipe-B-HDMI-A-1-invalid-num-scalers
[196.522314] Dynamic subtest pipe-B-HDMI-A-1-invalid-num-scalers: SUCCESS (0.072s)
[196.589252] Starting dynamic subtest: pipe-B-HDMI-A-2-invalid-num-scalers
[196.621195] Dynamic subtest pipe-B-HDMI-A-2-invalid-num-scalers: SUCCESS (0.069s)
[196.685085] Starting dynamic subtest: pipe-C-HDMI-A-1-invalid-num-scalers
[196.763450] Dynamic subtest pipe-C-HDMI-A-1-invalid-num-scalers: SUCCESS (0.120s)
[196.832311] Starting dynamic subtest: pipe-C-HDMI-A-2-invalid-num-scalers
[196.916217] Dynamic subtest pipe-C-HDMI-A-2-invalid-num-scalers: SUCCESS (0.119s)
[196.919462] Subtest invalid-num-scalers: SUCCESS (0.717s)
[197.070216] [028/133] (852s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[197.258284] Starting subtest: primary-4-tiled-reflect-x-180
[197.263592] Subtest primary-4-tiled-reflect-x-180: SKIP (0.003s)
[197.386725] [029/133] (852s left) gen9_exec_parse (bb-start-cmd)
[197.516450] Starting subtest: bb-start-cmd
[197.520279] Subtest bb-start-cmd: SUCCESS (0.005s)
[197.769797] [030/133] (852s left) drm_read (short-buffer-block)
[198.226598] Starting subtest: short-buffer-block
[198.228385] Subtest short-buffer-block: SUCCESS (0.001s)
[198.522214] [031/133] (851s left) gem_exec_schedule (preempt-self)
[198.665798] Starting subtest: preempt-self
[198.674124] Starting dynamic subtest: rcs0
[198.699667] Dynamic subtest rcs0: SUCCESS (0.029s)
[198.761980] Starting dynamic subtest: bcs0
[198.769364] Dynamic subtest bcs0: SUCCESS (0.026s)
[198.805795] Starting dynamic subtest: vcs0
[198.807353] Dynamic subtest vcs0: SUCCESS (0.026s)
[198.826376] Starting dynamic subtest: vecs0
[198.827958] Dynamic subtest vecs0: SUCCESS (0.027s)
[198.829502] Subtest preempt-self: SUCCESS (0.120s)
[199.171069] [032/133] (850s left) gem_exec_params (no-vebox)
[199.291800] Starting subtest: no-vebox
[199.294368] Subtest no-vebox: SUCCESS (0.002s)
[199.437227] [033/133] (850s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[200.215208] Starting subtest: syncobj-timeline-invalid-flags
[200.216872] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[200.497417] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[200.502535] [034/133] (849s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[200.710214] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[200.715294] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[200.864445] [035/133] (849s left) gem_ctx_param (basic-default)
[200.967079] Starting subtest: basic-default
[200.968810] Subtest basic-default: SUCCESS (0.000s)
[201.118554] [036/133] (849s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[201.227305] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[201.230127] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.003s)
[201.379496] [037/133] (849s left) gem_pxp (reject-modify-context-protection-off-2)
[204.022141] Starting subtest: reject-modify-context-protection-off-2
[204.026813] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[204.166747] [038/133] (846s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[204.350416] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[204.356253] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[205.148447] [039/133] (845s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[205.335044] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[205.336799] Starting dynamic subtest: pipe-A-HDMI-A-1
[205.358139] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[205.393053] Starting dynamic subtest: pipe-A-HDMI-A-2
[205.414086] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[205.435598] Starting dynamic subtest: pipe-B-HDMI-A-1
[205.483499] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[205.521179] Starting dynamic subtest: pipe-B-HDMI-A-2
[205.540902] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.003s)
[205.560402] Starting dynamic subtest: pipe-C-HDMI-A-1
[205.576038] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[205.595753] Starting dynamic subtest: pipe-C-HDMI-A-2
[205.610890] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.004s)
[205.613904] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.052s)
[206.362237] [040/133] (844s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[206.571187] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[206.576101] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[206.719417] [041/133] (844s left) gem_gtt_cpu_tlb
[206.985969] [042/133] (844s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[207.192063] Starting subtest: y-tiled-8bpp-rotate-90
[207.576272] Subtest y-tiled-8bpp-rotate-90: SKIP (0.382s)
[207.851803] [043/133] (843s left) kms_pm_lpsp (screens-disabled)
[209.051207] Starting subtest: screens-disabled
[209.054413] Subtest screens-disabled: SKIP (0.000s)
[209.198718] [044/133] (842s left) gem_exec_reloc (basic-cpu-wc)
[209.315197] Starting subtest: basic-cpu-wc
[209.338323] Subtest basic-cpu-wc: SUCCESS (0.022s)
[209.550388] [045/133] (841s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[209.747150] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[212.111338] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SUCCESS (2.366s)
[212.384653] [046/133] (839s left) gem_ctx_create (basic)
[212.512115] Starting subtest: basic
[212.514809] Subtest basic: SUCCESS (0.000s)
[212.769809] [047/133] (838s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[212.873448] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[212.979534] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[213.166356] [048/133] (838s left) kms_universal_plane (universal-plane-pageflip-windowed)
[213.352079] Starting subtest: universal-plane-pageflip-windowed
[213.354199] Starting dynamic subtest: pipe-A-HDMI-A-1
[214.207723] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.858s)
[214.234079] Starting dynamic subtest: pipe-B-HDMI-A-2
[214.792090] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.578s)
[214.819440] Starting dynamic subtest: pipe-C-HDMI-A-1
[215.337863] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.541s)
[215.340397] Subtest universal-plane-pageflip-windowed: SUCCESS (1.994s)
[215.629503] [049/133] (836s left) i915_suspend (basic-s3-without-i915)
[215.730336] Starting subtest: basic-s3-without-i915