[88.570526] Initializing watchdogs
[88.570751]   /dev/watchdog0
[88.610837] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.625770] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.633255] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.638555] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[88.869990] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[88.875934] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[89.019386] [002/156] (960s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[89.234590] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[92.830070] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SUCCESS (3.591s)
[93.044507] [003/156] (956s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[93.237565] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[93.239872] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[93.370825] [004/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[93.578642] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[93.582591] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[93.720236] [005/156] (955s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[93.825998] Starting subtest: etime-single-wait-for-submit-submitted
[93.932038] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.104s)
[94.063330] [006/156] (955s left) gem_ctx_persistence (legacy-engines-cleanup)
[94.186673] Starting subtest: legacy-engines-cleanup
[94.187781] Starting dynamic subtest: render
[94.214124] Dynamic subtest render: SUCCESS (0.026s)
[94.243568] Starting dynamic subtest: bsd
[94.245146] Dynamic subtest bsd: SUCCESS (0.024s)
[94.268351] Starting dynamic subtest: blt
[94.271272] Dynamic subtest blt: SUCCESS (0.028s)
[94.293204] Starting dynamic subtest: vebox
[94.309526] Dynamic subtest vebox: SUCCESS (0.035s)
[94.310780] Subtest legacy-engines-cleanup: SUCCESS (0.125s)
[94.447324] [007/156] (955s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[94.651342] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[94.655388] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.001s)
[94.797349] [008/156] (954s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[94.987063] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[94.991103] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[95.121342] [009/156] (954s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[95.334265] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[95.337186] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[95.476554] [010/156] (954s left) sysfs_timeslice_duration (invalid)
[95.588816] Starting subtest: invalid
[95.597347] Starting dynamic subtest: vecs0
[95.599081] Dynamic subtest vecs0: SUCCESS (0.001s)
[95.632697] Starting dynamic subtest: vcs0
[95.633791] Dynamic subtest vcs0: SUCCESS (0.001s)
[95.654187] Starting dynamic subtest: bcs0
[95.656204] Dynamic subtest bcs0: SUCCESS (0.001s)
[95.673904] Starting dynamic subtest: rcs0
[95.675140] Dynamic subtest rcs0: SUCCESS (0.001s)
[95.676241] Subtest invalid: SUCCESS (0.014s)
[95.743846] [011/156] (954s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[95.923066] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[95.926113] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[96.043525] [012/156] (953s left) fbdev (unaligned-read)
[96.165492] Starting subtest: unaligned-read
[96.196204] Subtest unaligned-read: SUCCESS (0.029s)
[96.312118] [013/156] (953s left) kms_atomic_interruptible (legacy-setmode)
[96.504613] Starting subtest: legacy-setmode
[96.508177] Starting dynamic subtest: pipe-A-HDMI-A-1
[103.288099] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.784s)
[103.290352] Subtest legacy-setmode: SUCCESS (6.787s)
[103.647445] [014/156] (946s left) gem_exec_schedule (preemptive-hang)
[103.903226] Starting subtest: preemptive-hang
[103.906743] Starting dynamic subtest: rcs0
[110.093144] Dynamic subtest rcs0: SUCCESS (6.187s)
[110.120522] Starting dynamic subtest: bcs0
[116.121570] Dynamic subtest bcs0: SUCCESS (6.020s)
[116.148377] Starting dynamic subtest: vcs0
[122.130743] Dynamic subtest vcs0: SUCCESS (6.006s)
[122.155884] Starting dynamic subtest: vecs0
[128.085322] Dynamic subtest vecs0: SUCCESS (5.961s)
[128.093122] Subtest preemptive-hang: SUCCESS (24.178s)
[128.332461] [015/156] (921s left) kms_vblank (query-idle)
[128.506075] Starting subtest: query-idle
[128.511535] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.203789] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.693s)
[131.242042] Starting dynamic subtest: pipe-A-HDMI-A-2
[133.645632] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.435s)
[133.674542] Starting dynamic subtest: pipe-C-HDMI-A-1
[136.105862] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.444s)
[136.156996] Starting dynamic subtest: pipe-C-HDMI-A-2
[138.535555] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.420s)
[138.536418] Subtest query-idle: SUCCESS (10.030s)
[138.847396] [016/156] (911s left) gem_madvise (dontneed-before-pwrite)
[138.951667] Starting subtest: dontneed-before-pwrite
[138.954807] Subtest dontneed-before-pwrite: SUCCESS (0.007s)
[139.097911] [017/156] (911s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[139.301135] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[141.949783] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.649s)
[142.198671] [018/156] (908s left) kms_psr2_su (page_flip-nv12)
[142.305109] Subtest page_flip-NV12: SKIP (0.000s)
[142.398610] [019/156] (908s left) i915_query (query-topology-matches-eu-total)
[142.496944] Starting subtest: query-topology-matches-eu-total
[142.498515] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[142.632101] [020/156] (908s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[142.815060] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[142.904602] [021/156] (907s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-msflip-blt)
[143.104107] Starting subtest: psrhdr-1p-primscrn-indfb-msflip-blt
[143.108302] Subtest psrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[143.261242] [022/156] (907s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[143.476243] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[143.478841] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[143.612257] [023/156] (907s left) kms_setmode (invalid-clone-single-crtc-stealing)
[143.719457] Starting subtest: invalid-clone-single-crtc-stealing
[144.264060] Starting dynamic subtest: pipe-A-HDMI-A-1-HDMI-A-2
[144.778074] Dynamic subtest pipe-A-HDMI-A-1-HDMI-A-2: SUCCESS (0.512s)
[145.069884] Starting dynamic subtest: pipe-B-HDMI-A-1-HDMI-A-2
[145.513850] Dynamic subtest pipe-B-HDMI-A-1-HDMI-A-2: SUCCESS (0.457s)
[145.719670] Subtest invalid-clone-single-crtc-stealing: SUCCESS (1.998s)
[146.091857] [024/156] (904s left) prime_vgem (basic-blt)
[146.299428] Starting subtest: basic-blt
[146.362465] Subtest basic-blt: SUCCESS (0.062s)
[146.518413] [FACT prime_vgem (basic-blt)] new: kernel.kmod_is_loaded.vgem: true
[146.523646] [025/156] (904s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-wc)
[146.723054] Starting subtest: fbchdr-rgb565-draw-mmap-wc
[146.724806] Subtest fbchdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[146.854226] [026/156] (904s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-pgflip-blt)
[147.063659] Starting subtest: hdr-1p-primscrn-shrfb-pgflip-blt
[147.065503] Subtest hdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[147.203342] [027/156] (904s left) perf_pmu (faulting-read)
[147.321512] Starting subtest: faulting-read
[147.322711] Starting dynamic subtest: gtt
[147.324226] Dynamic subtest gtt: SUCCESS (0.006s)
[147.361904] Starting dynamic subtest: wb
[147.365464] Dynamic subtest wb: SUCCESS (0.003s)
[147.387202] Starting dynamic subtest: wc
[147.390219] Dynamic subtest wc: SUCCESS (0.001s)
[147.410133] Starting dynamic subtest: uc
[147.411224] Dynamic subtest uc: SUCCESS (0.001s)
[147.412149] Subtest faulting-read: SUCCESS (0.015s)
[147.493482] [028/156] (903s left) kms_addfb_basic (invalid-set-prop)
[147.600944] Starting subtest: invalid-set-prop
[147.602278] Subtest invalid-set-prop: SUCCESS (0.000s)
[147.838344] [029/156] (903s left) gem_vm_create (invalid-create)
[147.955781] Starting subtest: invalid-create
[147.956889] Subtest invalid-create: SUCCESS (0.001s)
[148.107329] [030/156] (903s left) kms_feature_discovery (chamelium)
[148.295789] Starting subtest: chamelium
[148.298327] Subtest chamelium: SKIP (0.001s)
[148.439746] [031/156] (903s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[148.547117] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[148.658853] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.109s)
[148.805489] [032/156] (902s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[149.012179] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[149.015885] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[149.172528] [033/156] (902s left) gem_exec_await (wide-contexts)
[149.483708] Starting subtest: wide-contexts
[171.760688] Subtest wide-contexts: SUCCESS (22.271s)
[172.022554] [034/156] (879s left) kms_chamelium_audio (hdmi-audio)
[172.728427] Subtest hdmi-audio: SKIP (0.000s)
[173.039139] [035/156] (878s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[173.234340] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[173.238088] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[173.416544] [036/156] (878s left) perf (global-sseu-config)
[173.699629] Starting subtest: global-sseu-config
[173.704488] Starting dynamic subtest: 0-rcs0
[173.763320] Dynamic subtest 0-rcs0: SUCCESS (0.064s)
[173.764383] Subtest global-sseu-config: SUCCESS (0.065s)
[173.870108] [037/156] (878s left) kms_frontbuffer_tracking (fbcpsrhdr-modesetfrombusy)
[174.066441] Starting subtest: fbcpsrhdr-modesetfrombusy
[174.070840] Subtest fbcpsrhdr-modesetfrombusy: SKIP (0.000s)
[174.221534] [038/156] (878s left) syncobj_timeline (multi-wait-available-signaled)
[174.326571] Starting subtest: multi-wait-available-signaled
[174.331830] Subtest multi-wait-available-signaled: SUCCESS (0.003s)
[174.478569] [039/156] (877s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[174.679150] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[174.683914] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[174.836401] [040/156] (877s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[175.043794] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[175.047608] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[175.191716] [041/156] (877s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[175.400403] Starting subtest: yf-tiled-8bpp-rotate-180
[175.404187] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[175.539831] [042/156] (877s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[175.742967] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[175.745782] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[175.907683] [043/156] (876s left) gem_basic (create-close)
[176.014198] Starting subtest: create-close
[176.017171] Subtest create-close: SUCCESS (0.000s)
[176.186488] [044/156] (876s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[176.390699] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[176.394595] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[176.539955] [045/156] (876s left) kms_busy (extended-modeset-hang-oldfb)
[176.734463] Starting subtest: extended-modeset-hang-oldfb
[176.736631] Starting dynamic subtest: pipe-A
[181.330266] Dynamic subtest pipe-A: SUCCESS (4.599s)
[181.379262] Starting dynamic subtest: pipe-C
[186.179820] Dynamic subtest pipe-C: SUCCESS (4.834s)
[186.180736] Subtest extended-modeset-hang-oldfb: SUCCESS (9.451s)
[186.545946] [046/156] (866s left) kms_frontbuffer_tracking (psrhdr-farfromfence-mmap-gtt)
[186.746286] Starting subtest: psrhdr-farfromfence-mmap-gtt
[186.749950] Subtest psrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[186.921733] [047/156] (866s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[187.113209] Starting subtest: compare-crc-sanitycheck-xr24
[187.116729] Starting dynamic subtest: pipe-A-HDMI-A-1
[187.986988] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.868s)
[188.037397] Starting dynamic subtest: pipe-B-HDMI-A-2
[188.599662] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.605s)
[188.628465] Starting dynamic subtest: pipe-C-HDMI-A-1
[189.211132] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.604s)
[189.215322] Subtest compare-crc-sanitycheck-xr24: SUCCESS (2.099s)
[189.554123] [048/156] (863s left) gem_mmap_gtt (fault-concurrent-x)
[189.660292] Starting subtest: fault-concurrent-X
[192.949091] Subtest fault-concurrent-X: SUCCESS (3.287s)
[193.109564] [049/156] (860s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[193.297316] Starting subtest: cursor-tearing-framebuffer-change
[193.302209] Starting dynamic subtest: pipe-A-HDMI-A-1
[194.156287] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.852s)
[194.204463] Starting dynamic subtest: pipe-C-HDMI-A-1
[194.718952] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.558s)
[194.719980] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.422s)
[195.107883] [050/156] (858s left) i915_pm_rps (thresholds-park)
[195.233739] Starting subtest: thresholds-park
[195.236556] Starting dynamic subtest: gt0
[210.020061] Dynamic subtest gt0: SUCCESS (14.785s)
[210.021944] Subtest thresholds-park: SUCCESS (14.788s)
[210.171539] [051/156] (843s left) gem_exec_schedule (u-lateslice)
[210.302639] Starting subtest: u-lateslice
[210.303577] Starting dynamic subtest: rcs0
[210.333677] Dynamic subtest rcs0: SUCCESS (0.032s)
[210.363660] Starting dynamic subtest: bcs0
[210.371999] Dynamic subtest bcs0: SUCCESS (0.036s)
[210.400421] Starting dynamic subtest: vcs0
[210.405064] Dynamic subtest vcs0: SUCCESS (0.030s)
[210.432646] Starting dynamic subtest: vecs0
[210.438983] Dynamic subtest vecs0: SUCCESS (0.031s)
[210.442449] Subtest u-lateslice: SUCCESS (0.140s)
[210.841868] [052/156] (842s left) gem_exec_basic (basic)
[210.952118] Starting subtest: basic
[210.953300] Starting dynamic subtest: rcs0-smem
[210.955131] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[210.986886] Starting dynamic subtest: bcs0-smem
[210.988094] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[211.011289] Starting dynamic subtest: vcs0-smem
[211.012311] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[211.034651] Starting dynamic subtest: vecs0-smem
[211.035755] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[211.036937] Subtest basic: SUCCESS (0.021s)
[211.240469] [053/156] (842s left) gem_fence_thrash (bo-write-verify-threaded-none)
[211.337301] Starting subtest: bo-write-verify-threaded-none
[216.573480] Subtest bo-write-verify-threaded-none: SUCCESS (5.233s)
[216.727900] [054/156] (837s left) gem_madvise (dontneed-before-exec)
[216.824210] Starting subtest: dontneed-before-exec
[216.835141] Subtest dontneed-before-exec: SUCCESS (0.010s)
[216.990750] [055/156] (836s left) syncobj_timeline (wait-all-delayed-signal)
[217.095220] Starting subtest: wait-all-delayed-signal
[217.199776] Subtest wait-all-delayed-signal: SUCCESS (0.105s)
[217.342399] [056/156] (836s left) gem_workarounds (suspend-resume-context)
[217.476164] Starting subtest: suspend-resume-context