[91.425550] Initializing watchdogs
[91.425728]   /dev/watchdog0
[91.467977] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.482977] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.489894] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.498363] [001/155] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[92.141872] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[92.483545] [002/155] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[92.701444] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[92.709291] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[92.848895] [003/155] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[93.048627] Starting subtest: short-busy-flip-before-cursor-toggle
[93.056518] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[93.181611] [004/155] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[93.395252] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[93.403205] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[93.532981] [005/155] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[93.735538] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[93.744221] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[104.628434] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.887s)
[104.655425] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[115.393187] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.758s)
[115.419369] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[126.110529] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.711s)
[126.115897] Subtest 2x-flip-vs-expired-vblank-interruptible: SUCCESS (32.376s)
[126.766316] [006/155] (925s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[126.981523] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[126.989181] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[127.129130] [007/155] (925s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[127.343874] Starting subtest: fbc-farfromfence-mmap-gtt
[130.324878] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (2.985s)
[130.595396] [008/155] (922s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[130.813421] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[130.820734] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[130.966875] [009/155] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[131.188916] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[131.196853] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[131.330522] [010/155] (921s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[131.543996] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[131.550564] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[131.679636] [011/155] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[131.893644] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[131.901473] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[132.031557] [012/155] (921s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[132.243208] Starting subtest: x-tiled-16bpp-rotate-90
[132.750709] Subtest x-tiled-16bpp-rotate-90: SKIP (0.318s)
[133.289958] [013/155] (920s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[133.780726] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[133.855544] Starting dynamic subtest: pipe-A
[134.454038] Dynamic subtest pipe-A: SUCCESS (0.834s)
[134.478245] Starting dynamic subtest: pipe-B
[135.352419] Dynamic subtest pipe-B: SUCCESS (0.895s)
[135.377827] Starting dynamic subtest: pipe-C
[136.214435] Dynamic subtest pipe-C: SUCCESS (0.861s)
[136.219615] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.593s)
[136.738332] [014/155] (916s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[136.923124] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[136.927068] Starting dynamic subtest: pipe-A-HDMI-A-1
[136.975145] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[137.021465] Starting dynamic subtest: pipe-A-HDMI-A-2
[137.069085] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[137.090865] Starting dynamic subtest: pipe-B-HDMI-A-1
[137.124466] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[137.144894] Starting dynamic subtest: pipe-B-HDMI-A-2
[137.177428] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[137.197520] Starting dynamic subtest: pipe-C-HDMI-A-1
[137.232466] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[137.253693] Starting dynamic subtest: pipe-C-HDMI-A-2
[137.286046] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[137.292561] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.028s)
[137.967708] [015/155] (915s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[138.184885] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[138.191305] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[138.335075] [016/155] (915s left) gem_create (create-ext-set-pat)
[138.568353] Starting subtest: create-ext-set-pat
[138.575778] Subtest create-ext-set-pat: SKIP (0.000s)
[138.713359] [017/155] (915s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[138.822820] Starting subtest: etime-single-wait-for-submit-unsubmitted
[138.928157] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[139.063963] [018/155] (914s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[139.277304] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[139.283695] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[139.416218] [019/155] (914s left) gem_ctx_param (set-priority-invalid-size)
[139.533702] Starting subtest: set-priority-invalid-size
[139.538011] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[139.663454] [020/155] (914s left) kms_psr (psr2-primary-render)
[139.851976] Subtest psr2-primary-render: SKIP (0.000s)
[139.958421] [021/155] (914s left) gem_userptr_blits (create-destroy-sync)
[140.118176] Starting subtest: create-destroy-sync
[145.203556] Subtest create-destroy-sync: SUCCESS (5.079s)
[145.365978] [022/155] (908s left) i915_query (query-garbage)
[145.469951] Starting subtest: query-garbage
[145.473387] Subtest query-garbage: SUCCESS (0.000s)
[145.645484] [023/155] (908s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[145.768125] Starting subtest: y-tiled-to-vebox-x-tiled
[145.776553] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[146.016155] [024/155] (908s left) gem_exec_reloc (basic-gtt-wc)
[146.138252] Starting subtest: basic-gtt-wc
[146.165920] Subtest basic-gtt-wc: SUCCESS (0.025s)
[146.394076] [025/155] (908s left) i915_pm_rpm (gem-pread)
[148.232239] Starting subtest: gem-pread
[148.963384] Subtest gem-pread: SUCCESS (0.733s)
[149.345817] [026/155] (905s left) kms_psr (psr2-basic)
[149.552956] Subtest psr2-basic: SKIP (0.000s)
[149.661856] [027/155] (905s left) gem_tiled_partial_pwrite_pread (reads)
[149.797272] Starting subtest: reads
[151.238396] Subtest reads: SUCCESS (1.447s)
[151.381248] [028/155] (903s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[151.495484] Starting subtest: single-wait-for-submit-available-submitted
[151.499095] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.002s)
[151.660554] [029/155] (903s left) syncobj_wait (multi-wait-submitted)
[151.774678] Starting subtest: multi-wait-submitted
[151.880140] Subtest multi-wait-submitted: SUCCESS (0.106s)
[152.016596] [030/155] (903s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[152.220245] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[152.252487] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[152.353635] [031/155] (902s left) gem_exec_parallel (basic)
[152.485177] Starting subtest: basic
[152.489524] Starting dynamic subtest: rcs0
[155.689482] Dynamic subtest rcs0: SUCCESS (3.200s)
[155.712385] Starting dynamic subtest: bcs0
[159.100516] Dynamic subtest bcs0: SUCCESS (3.412s)
[159.123842] Starting dynamic subtest: vcs0
[162.847234] Dynamic subtest vcs0: SUCCESS (3.746s)
[162.874621] Starting dynamic subtest: vecs0
[166.047386] Dynamic subtest vecs0: SUCCESS (3.199s)
[166.051997] Subtest basic: SUCCESS (13.565s)
[166.328627] [032/155] (888s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[166.542068] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[168.573426] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.041s)
[168.811097] [033/155] (886s left) syncobj_wait (single-wait-for-submit-submitted)
[168.922705] Starting subtest: single-wait-for-submit-submitted
[169.024965] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s)
[169.192515] [034/155] (886s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[169.412693] Starting subtest: fbcpsr-rgb565-draw-blt
[169.419260] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[169.548198] [035/155] (886s left) perf_pmu (busy-accuracy-98)
[169.674222] Starting subtest: busy-accuracy-98
[169.679645] Starting dynamic subtest: rcs0
[172.728248] Dynamic subtest rcs0: SUCCESS (3.058s)
[172.754566] Starting dynamic subtest: bcs0
[175.789324] Dynamic subtest bcs0: SUCCESS (3.059s)
[175.813706] Starting dynamic subtest: vcs0
[178.839663] Dynamic subtest vcs0: SUCCESS (3.051s)
[178.864880] Starting dynamic subtest: vecs0
[181.896105] Dynamic subtest vecs0: SUCCESS (3.054s)
[181.899734] Subtest busy-accuracy-98: SUCCESS (12.227s)
[182.033738] [036/155] (873s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[182.221590] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[182.329452] [037/155] (873s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[182.535139] Starting subtest: cursorB-vs-flipB-toggle
[193.828188] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.296s)
[194.178208] [038/155] (861s left) syncobj_wait (wait-all-delayed-signal)
[194.309377] Starting subtest: wait-all-delayed-signal
[194.412753] Subtest wait-all-delayed-signal: SUCCESS (0.105s)
[194.574486] [039/155] (861s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[194.785473] Starting subtest: psr-rgb565-draw-mmap-wc
[194.791837] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.001s)
[194.927751] [040/155] (861s left) gem_wait (busy)
[195.075882] Starting subtest: busy
[195.080376] Starting dynamic subtest: all-engines
[195.598317] Dynamic subtest all-engines: SUCCESS (0.524s)
[195.627329] Starting dynamic subtest: rcs0
[196.118951] Dynamic subtest rcs0: SUCCESS (0.513s)
[196.142049] Starting dynamic subtest: bcs0
[196.637807] Dynamic subtest bcs0: SUCCESS (0.515s)
[196.661473] Starting dynamic subtest: vcs0
[197.147135] Dynamic subtest vcs0: SUCCESS (0.509s)
[197.171661] Starting dynamic subtest: vecs0
[197.657463] Dynamic subtest vecs0: SUCCESS (0.510s)
[197.663268] Subtest busy: SUCCESS (2.580s)
[197.956082] [041/155] (858s left) gem_close_race (basic-threads)
[198.103463] Starting subtest: basic-threads
[199.300192] Subtest basic-threads: SUCCESS (1.202s)
[199.572407] [042/155] (856s left) kms_lease (possible-crtcs-filtering)
[199.768317] Starting subtest: possible-crtcs-filtering
[199.771497] Subtest possible-crtcs-filtering: SUCCESS (0.008s)
[199.918963] [043/155] (856s left) prime_busy (hang)
[200.046174] Starting subtest: hang
[200.051145] Starting dynamic subtest: rcs0
[206.637335] Dynamic subtest rcs0: SUCCESS (6.585s)
[206.661223] Starting dynamic subtest: bcs0
[213.673734] Dynamic subtest bcs0: SUCCESS (7.036s)
[213.700782] Starting dynamic subtest: vcs0
[220.654878] Dynamic subtest vcs0: SUCCESS (6.974s)
[220.678259] Starting dynamic subtest: vecs0
[227.624967] Dynamic subtest vecs0: SUCCESS (6.974s)
[227.629255] Subtest hang: SUCCESS (27.583s)
[227.787759] [044/155] (828s left) kms_getfb (getfb2-handle-zero)
[227.970239] Starting subtest: getfb2-handle-zero
[227.974657] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[228.132864] [045/155] (828s left) gem_close (basic)
[228.256582] Starting subtest: basic
[228.261572] Subtest basic: SUCCESS (0.000s)
[228.404798] [046/155] (828s left) gem_ccs (ctrl-surf-copy)
[228.531105] Subtest ctrl-surf-copy: SKIP (0.000s)
[228.637826] [047/155] (828s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[228.852491] Starting subtest: fbc-indfb-scaledprimary
[230.100419] Subtest fbc-indfb-scaledprimary: SUCCESS (1.256s)
[230.427814] [048/155] (826s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[230.631563] Starting subtest: crc-primary-suspend-y-tiled-ccs
[230.636318] Starting dynamic subtest: pipe-A-HDMI-A-1
[247.931519] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.875s)
[247.973646] Starting dynamic subtest: pipe-A-HDMI-A-2