[96.865139] Initializing watchdogs
[96.865304]   /dev/watchdog0
[96.907376] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[96.922166] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[96.928746] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[96.936710] [001/155] (960s left) sw_sync (sync_busy_fork_unixsocket)
[97.066262] Starting subtest: sync_busy_fork_unixsocket
[97.091437] Subtest sync_busy_fork_unixsocket: SUCCESS (0.025s)
[97.232228] [002/155] (960s left) gem_ctx_persistence (engines-persistence)
[97.362923] Starting subtest: engines-persistence
[97.367241] Starting dynamic subtest: rcs0
[98.112910] Dynamic subtest rcs0: SUCCESS (0.746s)
[98.135973] Starting dynamic subtest: bcs0
[98.874240] Dynamic subtest bcs0: SUCCESS (0.763s)
[98.899840] Starting dynamic subtest: vcs0
[99.640417] Dynamic subtest vcs0: SUCCESS (0.762s)
[99.677642] Starting dynamic subtest: vecs0
[100.413830] Dynamic subtest vecs0: SUCCESS (0.776s)
[100.419661] Subtest engines-persistence: SUCCESS (3.053s)
[100.571082] [003/155] (957s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[100.792666] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc
[101.898967] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (1.103s)
[102.098179] [004/155] (955s left) syncobj_timeline (invalid-query-one-illegal-handle)
[102.229097] Starting subtest: invalid-query-one-illegal-handle
[102.233800] Subtest invalid-query-one-illegal-handle: SUCCESS (0.002s)
[102.393773] [005/155] (955s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[102.608201] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[105.467020] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (2.861s)
[105.788770] [006/155] (952s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[106.004091] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[106.225763] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.219s)
[106.497023] [007/155] (951s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[106.709406] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[106.715905] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[106.861415] [008/155] (951s left) gem_create (create-ext-placement-all)
[107.094467] Starting subtest: create-ext-placement-all
[107.097921] Subtest create-ext-placement-all: SUCCESS (0.000s)
[107.242281] [009/155] (950s left) gem_exec_reloc (basic-write-gtt)
[107.366253] Starting subtest: basic-write-gtt
[107.389553] Subtest basic-write-gtt: SUCCESS (0.024s)
[107.625495] [010/155] (950s left) gem_ctx_shared (q-smoketest-all)
[107.760287] Starting subtest: Q-smoketest-all
[140.036348] Subtest Q-smoketest-all: SUCCESS (32.271s)
[140.319203] [011/155] (918s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[140.538220] Starting subtest: fbcpsr-slowdraw
[140.544710] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[140.677579] [012/155] (917s left) api_intel_allocator (gem-pool)
[140.792966] Starting subtest: gem-pool
[140.802103] Subtest gem-pool: SUCCESS (0.017s)
[140.990279] [013/155] (917s left) kms_sequence (queue-busy)
[141.166157] Starting subtest: queue-busy
[141.171106] Starting dynamic subtest: pipe-A-HDMI-A-1
[144.071617] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.899s)
[144.118492] Starting dynamic subtest: pipe-A-HDMI-A-2
[146.702230] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.626s)
[146.731880] Starting dynamic subtest: pipe-B-HDMI-A-1
[149.357386] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.652s)
[149.391990] Starting dynamic subtest: pipe-B-HDMI-A-2
[152.016741] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.656s)
[152.043477] Starting dynamic subtest: pipe-C-HDMI-A-1
[154.658551] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.641s)
[154.685041] Starting dynamic subtest: pipe-C-HDMI-A-2
[157.264364] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.600s)
[157.268368] Subtest queue-busy: SUCCESS (16.094s)
[157.607242] [014/155] (901s left) gem_partial_pwrite_pread (write)
[157.734717] Starting subtest: write
[171.958584] Subtest write: SUCCESS (14.224s)
[172.105207] [015/155] (886s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[172.306973] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[172.313617] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[172.457602] [016/155] (886s left) kms_chamelium_hpd (hdmi-hpd)
[173.024150] Subtest hdmi-hpd: SKIP (0.000s)
[173.347281] [017/155] (885s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[173.480742] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[173.487228] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[173.736809] [018/155] (885s left) gem_exec_schedule (pi-shared-iova)
[174.007130] Starting subtest: pi-shared-iova
[174.010344] Starting dynamic subtest: rcs0
[174.073832] Dynamic subtest rcs0: SUCCESS (0.065s)
[174.097137] Starting dynamic subtest: bcs0
[174.150632] Dynamic subtest bcs0: SUCCESS (0.071s)
[174.186238] Starting dynamic subtest: vcs0
[174.209162] Dynamic subtest vcs0: SUCCESS (0.059s)
[174.241422] Starting dynamic subtest: vecs0
[174.268637] Dynamic subtest vecs0: SUCCESS (0.061s)
[174.272496] Subtest pi-shared-iova: SUCCESS (0.265s)
[174.544560] [019/155] (884s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[174.755319] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[174.764068] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[174.917792] [020/155] (884s left) gem_mmap_offset (mmap-boundaries)
[175.033363] Starting subtest: mmap-boundaries
[175.036780] Starting dynamic subtest: smem0
[175.938529] Dynamic subtest smem0: SUCCESS (0.901s)
[175.944140] Subtest mmap-boundaries: SUCCESS (0.902s)
[176.109322] [021/155] (883s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[176.316265] Starting subtest: y-tiled-32bpp-rotate-0
[181.918223] Subtest y-tiled-32bpp-rotate-0: SUCCESS (5.603s)
[182.344340] [022/155] (877s left) core_setmaster_vs_auth
[182.592908] [023/155] (876s left) perf_pmu (busy-idle-no-semaphores)
[182.708052] Starting subtest: busy-idle-no-semaphores
[182.712276] Starting dynamic subtest: rcs0
[183.347152] Dynamic subtest rcs0: SUCCESS (0.634s)
[183.374586] Starting dynamic subtest: bcs0
[183.977493] Dynamic subtest bcs0: SUCCESS (0.631s)
[184.003867] Starting dynamic subtest: vcs0
[184.612089] Dynamic subtest vcs0: SUCCESS (0.632s)
[184.637574] Starting dynamic subtest: vecs0
[185.242312] Dynamic subtest vecs0: SUCCESS (0.630s)
[185.245581] Subtest busy-idle-no-semaphores: SUCCESS (2.533s)
[185.420741] [024/155] (874s left) gem_exec_reloc (basic-cpu-wc)
[185.539186] Starting subtest: basic-cpu-wc
[185.559113] Subtest basic-cpu-wc: SUCCESS (0.021s)
[185.780609] [025/155] (873s left) kms_cdclk (plane-scaling)
[185.905458] Subtest plane-scaling: SKIP (0.000s)
[186.003376] [026/155] (873s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[186.196377] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[186.200499] Starting dynamic subtest: pipe-A-valid-mode
[187.184357] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.993s)
[187.188634] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (0.997s)
[187.543124] [027/155] (872s left) perf (i915-ref-count)
[187.726756] Starting subtest: i915-ref-count
[187.788863] Subtest i915-ref-count: SUCCESS (0.065s)
[187.920328] [028/155] (872s left) api_intel_bb (render)
[188.034405] Starting subtest: render
[188.037710] Starting dynamic subtest: render-none-512
[188.142347] Dynamic subtest render-none-512: SUCCESS (0.107s)
[188.168512] Starting dynamic subtest: render-none-reloc-512
[188.247403] Dynamic subtest render-none-reloc-512: SUCCESS (0.104s)
[188.272114] Starting dynamic subtest: render-none-1024
[188.627388] Dynamic subtest render-none-1024: SUCCESS (0.379s)
[188.653546] Starting dynamic subtest: render-none-reloc-1024
[189.006886] Dynamic subtest render-none-reloc-1024: SUCCESS (0.373s)
[189.032459] Starting dynamic subtest: render-x-512
[189.094622] Dynamic subtest render-x-512: SUCCESS (0.090s)
[189.122989] Starting dynamic subtest: render-x-reloc-512
[189.180649] Dynamic subtest render-x-reloc-512: SUCCESS (0.085s)
[189.207644] Starting dynamic subtest: render-x-1024
[189.506228] Dynamic subtest render-x-1024: SUCCESS (0.323s)
[189.532085] Starting dynamic subtest: render-x-reloc-1024
[189.836782] Dynamic subtest render-x-reloc-1024: SUCCESS (0.328s)
[189.860936] Starting dynamic subtest: render-y-512
[189.929896] Dynamic subtest render-y-512: SUCCESS (0.093s)
[189.957581] Starting dynamic subtest: render-y-reloc-512
[190.015779] Dynamic subtest render-y-reloc-512: SUCCESS (0.085s)
[190.040380] Starting dynamic subtest: render-y-1024
[190.335855] Dynamic subtest render-y-1024: SUCCESS (0.321s)
[190.361252] Starting dynamic subtest: render-y-reloc-1024
[190.668504] Dynamic subtest render-y-reloc-1024: SUCCESS (0.328s)
[190.672557] Subtest render: SUCCESS (2.634s)
[190.821553] [029/155] (869s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[191.026319] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[191.034113] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[191.184592] [030/155] (869s left) kms_async_flips (alternate-sync-async-flip-atomic)
[191.376558] Starting subtest: alternate-sync-async-flip-atomic
[191.950608] Starting dynamic subtest: pipe-A-HDMI-A-1
[194.153900] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.204s)
[194.504777] Starting dynamic subtest: pipe-A-HDMI-A-2
[196.662185] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.187s)
[197.031328] Starting dynamic subtest: pipe-B-HDMI-A-1
[199.194722] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.189s)
[199.537463] Starting dynamic subtest: pipe-B-HDMI-A-2
[201.725552] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.217s)
[202.070830] Starting dynamic subtest: pipe-C-HDMI-A-1
[204.073575] Dynamic subtest pipe-C-HDMI-A-1: FAIL (1.054s)
[204.098340] Starting dynamic subtest: pipe-C-HDMI-A-2
[205.618254] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.202s)
[205.623278] Subtest alternate-sync-async-flip-atomic: FAIL (14.249s)
[206.258626] [031/155] (854s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[206.475207] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[207.473984] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (0.994s)
[207.691754] [032/155] (852s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[207.922024] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-wc
[208.703099] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (0.781s)
[208.992321] [033/155] (851s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[209.212135] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[212.398732] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SUCCESS (3.191s)
[212.710723] [034/155] (847s left) kms_pm_backlight (bad-brightness)
[212.905528] Subtest bad-brightness: SKIP (0.000s)
[213.019790] [035/155] (847s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[213.247113] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[213.254187] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[213.398437] [036/155] (847s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[213.608795] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[215.665363] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.062s)
[215.876454] [037/155] (845s left) gem_softpin (noreloc-interruptible)
[216.006942] Starting subtest: noreloc-interruptible
[221.540403] Subtest noreloc-interruptible: SUCCESS (5.534s)
[221.707499] [038/155] (839s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-blt)
[221.917700] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-blt
[221.925613] Subtest psrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[222.062831] [039/155] (839s left) kms_cursor_crc (cursor-sliding-512x170)
[222.285230] Starting subtest: cursor-sliding-512x170
[222.292279] Subtest cursor-sliding-512x170: SKIP (0.002s)
[222.446109] [040/155] (838s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt)
[222.666349] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt
[222.672758] Subtest fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[222.811190] [041/155] (838s left) kms_plane_multiple (tiling-yf)
[222.995584] Starting subtest: tiling-yf
[222.999558] Starting dynamic subtest: pipe-A-HDMI-A-1
[225.193099] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.188s)
[225.231082] Starting dynamic subtest: pipe-A-HDMI-A-2
[227.153150] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.962s)
[227.185820] Starting dynamic subtest: pipe-B-HDMI-A-1
[229.033317] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.871s)
[229.068628] Starting dynamic subtest: pipe-B-HDMI-A-2
[230.988889] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.946s)
[231.025671] Starting dynamic subtest: pipe-C-HDMI-A-1
[232.926281] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.937s)
[232.963543] Starting dynamic subtest: pipe-C-HDMI-A-2
[234.848759] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.915s)
[234.852206] Subtest tiling-yf: SUCCESS (11.853s)
[235.227401] [042/155] (826s left) gem_exec_schedule (u-submit-golden-slice)
[235.387572] Starting subtest: u-submit-golden-slice
[235.391158] Starting dynamic subtest: rcs0
[235.441237] Dynamic subtest rcs0: SUCCESS (0.052s)
[235.472656] Starting dynamic subtest: bcs0
[235.495779] Dynamic subtest bcs0: SUCCESS (0.048s)
[235.522910] Starting dynamic subtest: vcs0
[235.548875] Dynamic subtest vcs0: SUCCESS (0.051s)
[235.588050] Starting dynamic subtest: vecs0
[235.596034] Dynamic subtest vecs0: SUCCESS (0.038s)
[235.599434] Subtest u-submit-golden-slice: SUCCESS (0.207s)
[236.024856] [043/155] (825s left) i915_suspend (debugfs-reader)
[236.139019] Starting subtest: debugfs-reader