[100.292960] Initializing watchdogs
[100.293289]   /dev/watchdog0
[100.335573] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[100.350631] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[100.358145] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[100.367689] [001/156] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[100.977994] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[101.323506] [002/156] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[101.562273] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[101.570136] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[101.703788] [003/156] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[101.909587] Starting subtest: short-busy-flip-before-cursor-toggle
[101.916439] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[102.036817] [004/156] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[102.245483] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[103.356425] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SUCCESS (1.104s)
[103.528112] [005/156] (957s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[103.743131] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[103.746643] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[114.567583] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.819s)
[114.593552] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[125.343043] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.768s)
[125.364599] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[136.088309] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.738s)
[136.094873] Subtest 2x-flip-vs-expired-vblank-interruptible: SUCCESS (32.344s)
[136.767339] [006/156] (924s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[136.987809] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[136.995824] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[137.135345] [007/156] (924s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[137.359621] Starting subtest: fbc-farfromfence-mmap-gtt
[140.832093] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.471s)
[141.084924] [008/156] (920s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[141.296449] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[141.303506] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[141.438683] [009/156] (920s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[141.654470] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[141.661526] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[141.804207] [010/156] (919s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[142.024997] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[142.031959] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[142.196675] [011/156] (919s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[142.418689] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[142.426545] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[142.571889] [012/156] (919s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[142.792236] Starting subtest: x-tiled-16bpp-rotate-90
[143.155349] Subtest x-tiled-16bpp-rotate-90: SKIP (0.361s)
[143.438134] [013/156] (918s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[143.634547] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[143.639218] Starting dynamic subtest: pipe-A
[144.527278] Dynamic subtest pipe-A: SUCCESS (0.893s)
[144.569978] Starting dynamic subtest: pipe-B
[145.472764] Dynamic subtest pipe-B: SUCCESS (0.944s)
[145.496153] Starting dynamic subtest: pipe-C
[146.335212] Dynamic subtest pipe-C: SUCCESS (0.861s)
[146.339644] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.702s)
[147.305150] [014/156] (915s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[147.796076] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[147.864110] Starting dynamic subtest: pipe-A-HDMI-A-1
[148.293628] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[148.314423] Starting dynamic subtest: pipe-A-HDMI-A-2
[148.345960] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[148.366587] Starting dynamic subtest: pipe-B-HDMI-A-1
[148.397653] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[148.418123] Starting dynamic subtest: pipe-B-HDMI-A-2
[148.452998] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[148.477346] Starting dynamic subtest: pipe-C-HDMI-A-1
[148.510100] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[148.535266] Starting dynamic subtest: pipe-C-HDMI-A-2
[148.573041] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[148.579282] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.029s)
[148.708576] [015/156] (913s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[148.934490] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[150.103982] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (1.170s)
[150.286751] [016/156] (912s left) gem_create (create-ext-set-pat)
[150.529579] Starting subtest: create-ext-set-pat
[150.536394] Subtest create-ext-set-pat: SKIP (0.000s)
[150.671096] [017/156] (912s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[150.794560] Starting subtest: etime-single-wait-for-submit-unsubmitted
[150.897438] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[151.038218] [018/156] (911s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[151.260176] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[151.268676] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[151.457426] [019/156] (911s left) gem_ctx_param (set-priority-invalid-size)
[151.588932] Starting subtest: set-priority-invalid-size
[151.593991] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[151.716435] [020/156] (911s left) kms_psr (psr2-primary-render)
[151.913979] Subtest psr2-primary-render: SKIP (0.000s)
[152.015765] [021/156] (911s left) gem_userptr_blits (create-destroy-sync)
[152.173861] Starting subtest: create-destroy-sync
[157.211232] Subtest create-destroy-sync: SUCCESS (5.036s)
[157.386595] [022/156] (905s left) i915_query (query-garbage)
[157.498615] Starting subtest: query-garbage
[157.501580] Subtest query-garbage: SUCCESS (0.000s)
[157.660224] [023/156] (905s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[157.784223] Starting subtest: y-tiled-to-vebox-x-tiled
[157.791872] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[158.036637] [024/156] (905s left) gem_exec_reloc (basic-gtt-wc)
[158.156701] Starting subtest: basic-gtt-wc
[158.182708] Subtest basic-gtt-wc: SUCCESS (0.024s)
[158.420806] [025/156] (905s left) i915_pm_rpm (gem-pread)
[160.357524] Starting subtest: gem-pread
[161.190438] Subtest gem-pread: SUCCESS (0.830s)
[161.555543] [026/156] (902s left) kms_psr (psr2-basic)
[161.750142] Subtest psr2-basic: SKIP (0.000s)
[161.864091] [027/156] (901s left) gem_tiled_partial_pwrite_pread (reads)
[162.001218] Starting subtest: reads
[163.455297] Subtest reads: SUCCESS (1.457s)
[163.606457] [028/156] (900s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[163.722612] Starting subtest: single-wait-for-submit-available-submitted
[163.725955] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[163.868289] [029/156] (900s left) syncobj_wait (multi-wait-submitted)
[163.979052] Starting subtest: multi-wait-submitted
[164.081078] Subtest multi-wait-submitted: SUCCESS (0.103s)
[164.250599] [030/156] (899s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[164.460283] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[164.466510] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[164.612774] [031/156] (899s left) gem_exec_parallel (basic)
[164.751079] Starting subtest: basic
[164.754959] Starting dynamic subtest: rcs0
[168.065888] Dynamic subtest rcs0: SUCCESS (3.309s)
[168.091786] Starting dynamic subtest: bcs0
[171.435837] Dynamic subtest bcs0: SUCCESS (3.373s)
[171.459618] Starting dynamic subtest: vcs0
[174.691903] Dynamic subtest vcs0: SUCCESS (3.255s)
[174.716363] Starting dynamic subtest: vecs0
[177.972948] Dynamic subtest vecs0: SUCCESS (3.276s)
[177.976963] Subtest basic: SUCCESS (13.221s)
[178.226188] [032/156] (886s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[178.434136] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[180.973622] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.546s)
[181.178734] [033/156] (883s left) syncobj_wait (single-wait-for-submit-submitted)
[181.295982] Starting subtest: single-wait-for-submit-submitted
[181.401096] Subtest single-wait-for-submit-submitted: SUCCESS (0.106s)
[181.579249] [034/156] (882s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[181.792763] Starting subtest: fbcpsr-rgb565-draw-blt
[181.799904] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[181.935621] [035/156] (882s left) perf_pmu (busy-accuracy-98)
[182.060522] Starting subtest: busy-accuracy-98
[182.063597] Starting dynamic subtest: rcs0
[185.116528] Dynamic subtest rcs0: SUCCESS (3.052s)
[185.141026] Starting dynamic subtest: bcs0
[188.175314] Dynamic subtest bcs0: SUCCESS (3.056s)
[188.199991] Starting dynamic subtest: vcs0
[191.227300] Dynamic subtest vcs0: SUCCESS (3.050s)
[191.253496] Starting dynamic subtest: vecs0
[194.281600] Dynamic subtest vecs0: SUCCESS (3.052s)
[194.284673] Subtest busy-accuracy-98: SUCCESS (12.218s)
[194.419700] [036/156] (870s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[194.619066] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[194.730142] [037/156] (869s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[194.930119] Starting subtest: cursorB-vs-flipB-toggle
[206.213323] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.287s)
[206.538048] [038/156] (858s left) syncobj_wait (wait-all-delayed-signal)
[206.658354] Starting subtest: wait-all-delayed-signal
[206.765462] Subtest wait-all-delayed-signal: SUCCESS (0.106s)
[206.916463] [039/156] (857s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[207.130591] Starting subtest: psr-rgb565-draw-mmap-wc
[207.137552] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.001s)
[207.286085] [040/156] (857s left) gem_wait (busy)
[207.435060] Starting subtest: busy
[207.438470] Starting dynamic subtest: all-engines
[207.966190] Dynamic subtest all-engines: SUCCESS (0.522s)
[207.990144] Starting dynamic subtest: rcs0
[208.475475] Dynamic subtest rcs0: SUCCESS (0.512s)
[208.500717] Starting dynamic subtest: bcs0
[208.997073] Dynamic subtest bcs0: SUCCESS (0.519s)
[209.022624] Starting dynamic subtest: vcs0
[209.516798] Dynamic subtest vcs0: SUCCESS (0.514s)
[209.540623] Starting dynamic subtest: vecs0
[210.030905] Dynamic subtest vecs0: SUCCESS (0.514s)
[210.034077] Subtest busy: SUCCESS (2.595s)
[210.350812] [041/156] (854s left) gem_close_race (basic-threads)
[210.496999] Starting subtest: basic-threads
[211.641765] Subtest basic-threads: SUCCESS (1.146s)
[211.901841] [042/156] (853s left) kms_lease (possible-crtcs-filtering)
[212.097155] Starting subtest: possible-crtcs-filtering
[212.135815] Subtest possible-crtcs-filtering: SUCCESS (0.039s)
[212.290110] [043/156] (853s left) prime_busy (hang)
[212.414222] Starting subtest: hang
[212.417486] Starting dynamic subtest: rcs0
[219.631278] Dynamic subtest rcs0: SUCCESS (7.212s)
[219.661224] Starting dynamic subtest: bcs0
[226.669953] Dynamic subtest bcs0: SUCCESS (7.037s)
[226.694953] Starting dynamic subtest: vcs0
[233.641927] Dynamic subtest vcs0: SUCCESS (6.971s)
[233.667041] Starting dynamic subtest: vecs0
[240.618122] Dynamic subtest vecs0: SUCCESS (6.972s)
[240.622635] Subtest hang: SUCCESS (28.205s)
[240.754531] [044/156] (824s left) kms_getfb (getfb2-handle-zero)
[240.936238] Starting subtest: getfb2-handle-zero
[240.939848] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[241.090696] [045/156] (824s left) gem_close (basic)
[241.216589] Starting subtest: basic
[241.221659] Subtest basic: SUCCESS (0.000s)
[241.369131] [046/156] (824s left) gem_ccs (ctrl-surf-copy)
[241.495523] Subtest ctrl-surf-copy: SKIP (0.000s)
[241.613412] [047/156] (824s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[241.828434] Starting subtest: fbc-indfb-scaledprimary
[243.159388] Subtest fbc-indfb-scaledprimary: SUCCESS (1.331s)
[243.472326] [048/156] (822s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[243.662584] Starting subtest: crc-primary-suspend-y-tiled-ccs
[243.666327] Starting dynamic subtest: pipe-A-HDMI-A-1