[88.911914] Initializing watchdogs
[88.912183]   /dev/watchdog0
[88.951846] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.967216] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.974831] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.980966] [001/133] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[89.196665] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[89.293270] [002/133] (960s left) syncobj_wait (wait-all-interrupted)
[89.398332] Starting subtest: wait-all-interrupted
[89.508278] Subtest wait-all-interrupted: SUCCESS (0.108s)
[89.652746] [003/133] (959s left) syncobj_wait (reset-unsignaled)
[89.759084] Starting subtest: reset-unsignaled
[89.760756] Subtest reset-unsignaled: SUCCESS (0.000s)
[89.887174] [004/133] (959s left) kms_busy (extended-modeset-hang-oldfb)
[90.091132] Starting subtest: extended-modeset-hang-oldfb
[90.095170] Starting dynamic subtest: pipe-A
[95.166939] Dynamic subtest pipe-A: SUCCESS (5.065s)
[95.198893] Starting dynamic subtest: pipe-C
[99.158809] Dynamic subtest pipe-C: SUCCESS (3.982s)
[99.163097] Subtest extended-modeset-hang-oldfb: SUCCESS (9.068s)
[99.499715] [005/133] (950s left) gem_exec_schedule (preemptive-hang)
[99.762102] Starting subtest: preemptive-hang
[99.764894] Starting dynamic subtest: rcs0
[106.123708] Dynamic subtest rcs0: SUCCESS (6.355s)
[106.150646] Starting dynamic subtest: bcs0
[112.081775] Dynamic subtest bcs0: SUCCESS (5.957s)
[112.113191] Starting dynamic subtest: vcs0
[118.090400] Dynamic subtest vcs0: SUCCESS (6.007s)
[118.119146] Starting dynamic subtest: vecs0
[124.120987] Dynamic subtest vecs0: SUCCESS (6.019s)
[124.126317] Subtest preemptive-hang: SUCCESS (24.343s)
[124.392343] [006/133] (925s left) gem_caching (read-writes)
[124.510058] Starting subtest: read-writes
[146.631572] Subtest read-writes: SUCCESS (22.121s)
[146.798447] [007/133] (903s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[146.997626] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[147.003507] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[147.138991] [008/133] (902s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[147.264142] Starting subtest: y-tiled-ccs-to-yf-tiled
[147.265794] Starting dynamic subtest: smem
[147.592651] Dynamic subtest smem: SUCCESS (0.328s)
[147.594388] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.328s)
[147.859195] [009/133] (902s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[148.055180] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[148.059120] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[148.191909] [010/133] (902s left) kms_pwrite_crc
[149.691533] [011/133] (900s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[149.890235] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[149.893850] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[150.021877] [012/133] (900s left) gem_exec_balancer (indices)
[150.149227] Starting subtest: indices
[154.464650] Subtest indices: SUCCESS (4.314s)
[154.725679] [013/133] (895s left) gem_create (create-ext-cpu-access-big)
[154.948660] Starting subtest: create-ext-cpu-access-big
[154.955898] Subtest create-ext-cpu-access-big: SKIP (0.002s)
[155.093396] [014/133] (895s left) gem_eio (banned)
[155.233052] Starting subtest: banned
[155.399365] Subtest banned: SUCCESS (0.167s)
[155.624137] [015/133] (895s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[155.838091] Starting subtest: invalid-modeset-force-ultra-joiner
[155.846117] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[155.974123] [016/133] (894s left) device_reset (cold-reset-bound)
[156.117161] Starting subtest: cold-reset-bound
[156.121215] Subtest cold-reset-bound: SKIP (0.000s)
[156.246884] [017/133] (894s left) i915_hangman (engine-engine-error)
[156.375217] Starting subtest: engine-engine-error
[156.378111] Starting dynamic subtest: rcs0
[156.449826] Dynamic subtest rcs0: SUCCESS (0.071s)
[156.470911] Starting dynamic subtest: bcs0
[156.478112] Dynamic subtest bcs0: SKIP (0.002s)
[156.506138] Starting dynamic subtest: vcs0
[156.521106] Dynamic subtest vcs0: SUCCESS (0.069s)
[156.542624] Starting dynamic subtest: vecs0
[156.591697] Dynamic subtest vecs0: SUCCESS (0.067s)
[156.593519] Subtest engine-engine-error: SUCCESS (0.216s)
[156.759378] [018/133] (894s left) gem_spin_batch (engines)
[156.881837] Starting subtest: engines
[156.884397] Starting dynamic subtest: rcs0
[160.118490] Dynamic subtest rcs0: SUCCESS (3.230s)
[160.136928] Starting dynamic subtest: bcs0
[163.347891] Dynamic subtest bcs0: SUCCESS (3.230s)
[163.366388] Starting dynamic subtest: vcs0
[166.575391] Dynamic subtest vcs0: SUCCESS (3.229s)
[166.594088] Starting dynamic subtest: vecs0
[169.802263] Dynamic subtest vecs0: SUCCESS (3.229s)
[169.804350] Subtest engines: SUCCESS (12.920s)
[170.063464] [019/133] (880s left) prime_mmap (test_correct)
[170.159495] Starting subtest: test_correct
[170.161111] Starting dynamic subtest: test_correct-smem
[170.168097] Dynamic subtest test_correct-smem: SUCCESS (0.009s)
[170.169673] Subtest test_correct: SUCCESS (0.010s)
[170.310540] [020/133] (880s left) kms_pm_rpm (legacy-planes)
[172.242441] Starting subtest: legacy-planes
[172.245161] Starting dynamic subtest: plane-45
[173.751777] Dynamic subtest plane-45: SUCCESS (1.510s)
[173.782634] Starting dynamic subtest: plane-54
[175.269370] Dynamic subtest plane-54: SUCCESS (1.515s)
[175.291055] Starting dynamic subtest: plane-63
[176.793960] Dynamic subtest plane-63: SUCCESS (1.524s)
[176.795625] Subtest legacy-planes: SUCCESS (4.556s)
[177.252303] [021/133] (873s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[177.456147] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[177.460839] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[177.592996] [022/133] (873s left) kms_cursor_crc (cursor-onscreen-256x85)
[177.790074] Starting subtest: cursor-onscreen-256x85
[177.856288] Starting dynamic subtest: pipe-A-HDMI-A-1
[180.592710] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.736s)
[180.730608] Starting dynamic subtest: pipe-C-HDMI-A-1
[183.080472] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.393s)
[183.082132] Subtest cursor-onscreen-256x85: SUCCESS (5.290s)
[183.430078] [023/133] (867s left) sw_sync (alloc_fence_invalid_timeline)
[183.532374] Starting subtest: alloc_fence_invalid_timeline
[183.535182] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[183.642421] [024/133] (867s left) api_intel_allocator (two-level-inception)
[183.746148] Starting subtest: two-level-inception
[189.270156] Subtest two-level-inception: SUCCESS (5.531s)
[189.414237] [025/133] (862s left) kms_big_fb (linear-64bpp-rotate-180)
[189.614106] Starting subtest: linear-64bpp-rotate-180
[195.097069] Subtest linear-64bpp-rotate-180: SUCCESS (5.477s)
[195.609324] [026/133] (855s left) kms_chamelium_frames (hdmi-crc-single)
[196.164366] Subtest hdmi-crc-single: SKIP (0.000s)
[196.446423] [027/133] (855s left) kms_plane_scaling (invalid-num-scalers)
[196.640503] Starting subtest: invalid-num-scalers
[196.681255] Starting dynamic subtest: pipe-A-HDMI-A-1-invalid-num-scalers
[196.795924] Dynamic subtest pipe-A-HDMI-A-1-invalid-num-scalers: SUCCESS (0.115s)
[196.881588] Starting dynamic subtest: pipe-A-HDMI-A-2-invalid-num-scalers
[196.976816] Dynamic subtest pipe-A-HDMI-A-2-invalid-num-scalers: SUCCESS (0.131s)
[197.060597] Starting dynamic subtest: pipe-B-HDMI-A-1-invalid-num-scalers
[197.155693] Dynamic subtest pipe-B-HDMI-A-1-invalid-num-scalers: SUCCESS (0.133s)
[197.241501] Starting dynamic subtest: pipe-B-HDMI-A-2-invalid-num-scalers
[197.331848] Dynamic subtest pipe-B-HDMI-A-2-invalid-num-scalers: SUCCESS (0.129s)
[197.417490] Starting dynamic subtest: pipe-C-HDMI-A-1-invalid-num-scalers
[197.569070] Dynamic subtest pipe-C-HDMI-A-1-invalid-num-scalers: SUCCESS (0.190s)
[197.646463] Starting dynamic subtest: pipe-C-HDMI-A-2-invalid-num-scalers
[197.795741] Dynamic subtest pipe-C-HDMI-A-2-invalid-num-scalers: SUCCESS (0.187s)
[197.797399] Subtest invalid-num-scalers: SUCCESS (1.164s)
[197.964411] [028/133] (853s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[198.160092] Starting subtest: primary-4-tiled-reflect-x-180
[198.164083] Subtest primary-4-tiled-reflect-x-180: SKIP (0.003s)
[198.300029] [029/133] (853s left) gen9_exec_parse (bb-start-cmd)
[198.427289] Starting subtest: bb-start-cmd
[198.434109] Subtest bb-start-cmd: SUCCESS (0.006s)
[198.694159] [030/133] (853s left) drm_read (short-buffer-block)
[199.121616] Starting subtest: short-buffer-block
[199.123375] Subtest short-buffer-block: SUCCESS (0.000s)
[199.415321] [031/133] (852s left) gem_exec_schedule (preempt-self)
[199.563488] Starting subtest: preempt-self
[199.566419] Starting dynamic subtest: rcs0
[199.596624] Dynamic subtest rcs0: SUCCESS (0.034s)
[199.646058] Starting dynamic subtest: bcs0
[199.655716] Dynamic subtest bcs0: SUCCESS (0.055s)
[199.685191] Starting dynamic subtest: vcs0
[199.701220] Dynamic subtest vcs0: SUCCESS (0.043s)
[199.740146] Starting dynamic subtest: vecs0
[199.749222] Dynamic subtest vecs0: SUCCESS (0.047s)
[199.753707] Subtest preempt-self: SUCCESS (0.191s)
[200.138288] [032/133] (851s left) gem_exec_params (no-vebox)
[200.246993] Starting subtest: no-vebox
[200.250927] Subtest no-vebox: SUCCESS (0.002s)
[200.429784] [033/133] (851s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[201.207943] Starting subtest: syncobj-timeline-invalid-flags
[201.209677] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.002s)
[201.472788] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[201.478510] [034/133] (850s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[201.689078] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[201.693116] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[201.842161] [035/133] (850s left) gem_ctx_param (basic-default)
[201.950321] Starting subtest: basic-default
[201.952057] Subtest basic-default: SUCCESS (0.000s)
[202.093695] [036/133] (850s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[202.197372] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[202.201146] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.002s)
[202.342550] [037/133] (850s left) gem_pxp (reject-modify-context-protection-off-2)
[204.978353] Starting subtest: reject-modify-context-protection-off-2
[204.984744] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[205.127425] [038/133] (847s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[205.323297] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[205.334490] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[206.014713] [039/133] (846s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[206.211016] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[206.212947] Starting dynamic subtest: pipe-A-HDMI-A-1
[206.234836] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[206.275386] Starting dynamic subtest: pipe-A-HDMI-A-2
[206.305617] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[206.346316] Starting dynamic subtest: pipe-B-HDMI-A-1
[206.376408] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[206.417311] Starting dynamic subtest: pipe-B-HDMI-A-2
[206.446216] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.003s)
[206.465220] Starting dynamic subtest: pipe-C-HDMI-A-1
[206.482450] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.005s)
[206.502315] Starting dynamic subtest: pipe-C-HDMI-A-2
[206.517885] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.004s)
[206.521588] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.054s)
[207.317772] [040/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[207.518505] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[207.523829] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[207.667379] [041/133] (845s left) gem_gtt_cpu_tlb
[207.937536] [042/133] (845s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[208.147079] Starting subtest: y-tiled-8bpp-rotate-90
[208.482791] Subtest y-tiled-8bpp-rotate-90: SKIP (0.334s)
[208.752470] [043/133] (844s left) kms_pm_lpsp (screens-disabled)
[209.941100] Starting subtest: screens-disabled
[209.945813] Subtest screens-disabled: SKIP (0.000s)
[210.083728] [044/133] (843s left) gem_exec_reloc (basic-cpu-wc)
[210.203593] Starting subtest: basic-cpu-wc
[210.223040] Subtest basic-cpu-wc: SUCCESS (0.020s)
[210.451360] [045/133] (842s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[210.648875] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[213.059289] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SUCCESS (2.412s)
[213.364444] [046/133] (840s left) gem_ctx_create (basic)
[213.499539] Starting subtest: basic
[213.502454] Subtest basic: SUCCESS (0.000s)
[213.753486] [047/133] (839s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[213.858983] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[213.963976] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.103s)
[214.102491] [048/133] (839s left) kms_universal_plane (universal-plane-pageflip-windowed)
[214.276349] Starting subtest: universal-plane-pageflip-windowed
[214.278241] Starting dynamic subtest: pipe-A-HDMI-A-1
[215.141950] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.863s)
[215.179023] Starting dynamic subtest: pipe-B-HDMI-A-2
[215.701923] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.552s)
[215.788065] Starting dynamic subtest: pipe-C-HDMI-A-1
[216.269135] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.562s)
[216.270949] Subtest universal-plane-pageflip-windowed: SUCCESS (1.995s)
[216.568608] [049/133] (837s left) i915_suspend (basic-s3-without-i915)
[216.669736] Starting subtest: basic-s3-without-i915