[101.188526] Initializing watchdogs
[101.188746]   /dev/watchdog0
[101.229501] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[101.244415] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[101.252232] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[101.257644] [001/155] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[101.880296] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[102.259795] [002/155] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[102.481933] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[102.486368] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[102.625936] [003/155] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[102.828360] Starting subtest: short-busy-flip-before-cursor-toggle
[102.832932] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[102.974219] [004/155] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[103.185140] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[103.188368] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[103.325821] [005/155] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[103.538469] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[103.545741] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[114.413107] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.867s)
[114.435522] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[125.186127] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.769s)
[125.210807] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[135.918475] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.719s)
[135.924276] Subtest 2x-flip-vs-expired-vblank-interruptible: SUCCESS (32.374s)
[136.599551] [006/155] (925s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[136.809747] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[136.813244] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[136.955282] [007/155] (925s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[137.157123] Starting subtest: fbc-farfromfence-mmap-gtt
[140.165516] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.002s)
[140.463095] [008/155] (921s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[140.668362] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[140.671761] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[140.810783] [009/155] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[141.024842] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[141.029833] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[141.159778] [010/155] (921s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[141.376276] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[141.379700] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[141.509774] [011/155] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[141.723398] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[141.727210] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[141.861927] [012/155] (920s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[142.079249] Starting subtest: x-tiled-16bpp-rotate-90
[142.445109] Subtest x-tiled-16bpp-rotate-90: SKIP (0.363s)
[142.730879] [013/155] (920s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[142.925396] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[142.930647] Starting dynamic subtest: pipe-A
[143.840854] Dynamic subtest pipe-A: SUCCESS (0.922s)
[143.864567] Starting dynamic subtest: pipe-B
[144.724120] Dynamic subtest pipe-B: SUCCESS (0.881s)
[144.746406] Starting dynamic subtest: pipe-C
[145.620936] Dynamic subtest pipe-C: SUCCESS (0.898s)
[145.626426] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.705s)
[146.240481] [014/155] (916s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[146.428257] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[146.431539] Starting dynamic subtest: pipe-A-HDMI-A-1
[146.450752] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[146.489160] Starting dynamic subtest: pipe-A-HDMI-A-2
[146.524185] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[146.556340] Starting dynamic subtest: pipe-B-HDMI-A-1
[146.586407] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[146.621624] Starting dynamic subtest: pipe-B-HDMI-A-2
[146.636938] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.003s)
[146.656343] Starting dynamic subtest: pipe-C-HDMI-A-1
[146.672427] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.005s)
[146.693245] Starting dynamic subtest: pipe-C-HDMI-A-2
[146.709016] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.005s)
[146.712126] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.039s)
[147.583530] [015/155] (915s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[147.786078] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[147.789919] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[147.934893] [016/155] (915s left) gem_create (create-ext-set-pat)
[148.167133] Starting subtest: create-ext-set-pat
[148.171653] Subtest create-ext-set-pat: SKIP (0.001s)
[148.303112] [017/155] (914s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[148.416379] Starting subtest: etime-single-wait-for-submit-unsubmitted
[148.516070] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[148.651565] [018/155] (914s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[148.854104] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[148.860466] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[148.999183] [019/155] (914s left) gem_ctx_param (set-priority-invalid-size)
[149.111088] Starting subtest: set-priority-invalid-size
[149.113388] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[149.252502] [020/155] (914s left) kms_psr (psr2-primary-render)
[149.450627] Subtest psr2-primary-render: SKIP (0.000s)
[149.553040] [021/155] (913s left) gem_userptr_blits (create-destroy-sync)
[149.708189] Starting subtest: create-destroy-sync
[154.737045] Subtest create-destroy-sync: SUCCESS (5.028s)
[154.919645] [022/155] (908s left) i915_query (query-garbage)
[155.025832] Starting subtest: query-garbage
[155.029138] Subtest query-garbage: SUCCESS (0.000s)
[155.151316] [023/155] (908s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[155.269119] Starting subtest: y-tiled-to-vebox-x-tiled
[155.272278] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[155.532630] [024/155] (908s left) gem_exec_reloc (basic-gtt-wc)
[155.651362] Starting subtest: basic-gtt-wc
[155.671993] Subtest basic-gtt-wc: SUCCESS (0.021s)
[155.885966] [025/155] (907s left) i915_pm_rpm (gem-pread)
[157.724281] Starting subtest: gem-pread
[158.463794] Subtest gem-pread: SUCCESS (0.730s)
[158.791138] [026/155] (905s left) kms_psr (psr2-basic)
[158.978321] Subtest psr2-basic: SKIP (0.000s)
[159.084151] [027/155] (904s left) gem_tiled_partial_pwrite_pread (reads)
[159.202185] Starting subtest: reads
[160.627655] Subtest reads: SUCCESS (1.423s)
[160.781261] [028/155] (903s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[160.889288] Starting subtest: single-wait-for-submit-available-submitted
[160.890970] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[161.063549] [029/155] (903s left) syncobj_wait (multi-wait-submitted)
[161.171174] Starting subtest: multi-wait-submitted
[161.277965] Subtest multi-wait-submitted: SUCCESS (0.106s)
[161.445502] [030/155] (902s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[161.657231] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[161.661735] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[161.797159] [031/155] (902s left) gem_exec_parallel (basic)
[161.927175] Starting subtest: basic
[161.929581] Starting dynamic subtest: rcs0
[165.171147] Dynamic subtest rcs0: SUCCESS (3.244s)
[165.198247] Starting dynamic subtest: bcs0
[168.559897] Dynamic subtest bcs0: SUCCESS (3.388s)
[168.584539] Starting dynamic subtest: vcs0
[171.816288] Dynamic subtest vcs0: SUCCESS (3.251s)
[171.840578] Starting dynamic subtest: vecs0
[174.997331] Dynamic subtest vecs0: SUCCESS (3.181s)
[174.999035] Subtest basic: SUCCESS (13.072s)
[175.268149] [032/155] (889s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[175.461802] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[177.509290] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.048s)
[177.716524] [033/155] (886s left) syncobj_wait (single-wait-for-submit-submitted)
[177.823708] Starting subtest: single-wait-for-submit-submitted
[177.934516] Subtest single-wait-for-submit-submitted: SUCCESS (0.108s)
[178.063456] [034/155] (886s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[178.276656] Starting subtest: fbcpsr-rgb565-draw-blt
[178.282714] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[178.459804] [035/155] (886s left) perf_pmu (busy-accuracy-98)
[178.584078] Starting subtest: busy-accuracy-98
[178.588059] Starting dynamic subtest: rcs0
[181.640166] Dynamic subtest rcs0: SUCCESS (3.056s)
[181.664425] Starting dynamic subtest: bcs0
[184.696275] Dynamic subtest bcs0: SUCCESS (3.055s)
[184.719721] Starting dynamic subtest: vcs0
[187.748362] Dynamic subtest vcs0: SUCCESS (3.049s)
[187.770347] Starting dynamic subtest: vecs0
[190.803183] Dynamic subtest vecs0: SUCCESS (3.052s)
[190.804783] Subtest busy-accuracy-98: SUCCESS (12.219s)
[190.948790] [036/155] (873s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[191.128087] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[191.238287] [037/155] (873s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[191.429079] Starting subtest: cursorB-vs-flipB-toggle
[202.746679] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.316s)
[203.139271] [038/155] (861s left) syncobj_wait (wait-all-delayed-signal)
[203.254160] Starting subtest: wait-all-delayed-signal
[203.358864] Subtest wait-all-delayed-signal: SUCCESS (0.105s)
[203.526074] [039/155] (861s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[203.736707] Starting subtest: psr-rgb565-draw-mmap-wc
[203.740228] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.001s)
[203.878422] [040/155] (861s left) gem_wait (busy)
[204.017138] Starting subtest: busy
[204.020598] Starting dynamic subtest: all-engines
[204.543783] Dynamic subtest all-engines: SUCCESS (0.528s)
[204.576746] Starting dynamic subtest: rcs0
[205.068768] Dynamic subtest rcs0: SUCCESS (0.519s)
[205.096681] Starting dynamic subtest: bcs0
[205.585373] Dynamic subtest bcs0: SUCCESS (0.516s)
[205.613827] Starting dynamic subtest: vcs0
[206.100572] Dynamic subtest vcs0: SUCCESS (0.511s)
[206.124738] Starting dynamic subtest: vecs0
[206.614637] Dynamic subtest vecs0: SUCCESS (0.514s)
[206.617516] Subtest busy: SUCCESS (2.600s)
[206.898820] [041/155] (858s left) gem_close_race (basic-threads)
[207.036073] Starting subtest: basic-threads
[208.184698] Subtest basic-threads: SUCCESS (1.151s)
[208.446715] [042/155] (856s left) kms_lease (possible-crtcs-filtering)
[208.642840] Starting subtest: possible-crtcs-filtering
[208.652597] Subtest possible-crtcs-filtering: SUCCESS (0.011s)
[208.795423] [043/155] (856s left) prime_busy (hang)
[208.914532] Starting subtest: hang
[208.917536] Starting dynamic subtest: rcs0
[215.656257] Dynamic subtest rcs0: SUCCESS (6.740s)
[215.686617] Starting dynamic subtest: bcs0
[222.632262] Dynamic subtest bcs0: SUCCESS (6.968s)
[222.662169] Starting dynamic subtest: vcs0
[229.609080] Dynamic subtest vcs0: SUCCESS (6.974s)
[229.639715] Starting dynamic subtest: vecs0
[236.644408] Dynamic subtest vecs0: SUCCESS (7.038s)
[236.646249] Subtest hang: SUCCESS (27.731s)
[236.779347] [044/155] (828s left) kms_getfb (getfb2-handle-zero)
[236.968319] Starting subtest: getfb2-handle-zero
[236.971293] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[237.137765] [045/155] (828s left) gem_close (basic)
[237.250480] Starting subtest: basic
[237.252634] Subtest basic: SUCCESS (0.000s)
[237.422077] [046/155] (828s left) gem_ccs (ctrl-surf-copy)
[237.537382] Subtest ctrl-surf-copy: SKIP (0.000s)
[237.648871] [047/155] (828s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[237.867386] Starting subtest: fbc-indfb-scaledprimary
[239.170982] Subtest fbc-indfb-scaledprimary: SUCCESS (1.305s)
[239.460475] [048/155] (826s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[239.649298] Starting subtest: crc-primary-suspend-y-tiled-ccs
[239.653541] Starting dynamic subtest: pipe-A-HDMI-A-1
[257.992607] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.720s)
[258.079587] Starting dynamic subtest: pipe-A-HDMI-A-2