[87.649809] Initializing watchdogs
[87.650121]   /dev/watchdog0
[87.688535] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[87.702583] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.708748] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.714076] [001/133] (960s left) kms_chamelium_frames (dp-crc-single)
[88.277577] Subtest dp-crc-single: SKIP (0.000s)
[88.515445] [002/133] (959s left) kms_chamelium_frames (vga-frame-dump)
[89.049095] Subtest vga-frame-dump: SKIP (0.000s)
[89.275889] [003/133] (959s left) syncobj_wait (multi-wait-all-submitted-signaled)
[89.374140] Starting subtest: multi-wait-all-submitted-signaled
[89.480769] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[89.601061] [004/133] (958s left) gem_exec_balancer (sequential)
[89.724816] Starting subtest: sequential
[93.917779] Subtest sequential: SUCCESS (4.193s)
[94.167771] [005/133] (954s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[94.284727] Starting subtest: x-tiled-to-vebox-y-tiled
[94.286529] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[94.517196] [006/133] (954s left) kms_color (invalid-gamma-lut-sizes)
[94.682710] Starting subtest: invalid-gamma-lut-sizes
[94.683725] Starting dynamic subtest: pipe-A
[95.038980] Dynamic subtest pipe-A: SUCCESS (0.356s)
[95.054500] Starting dynamic subtest: pipe-B
[95.055526] Dynamic subtest pipe-B: SUCCESS (0.001s)
[95.072531] Starting dynamic subtest: pipe-C
[95.073567] Dynamic subtest pipe-C: SUCCESS (0.001s)
[95.074545] Subtest invalid-gamma-lut-sizes: SUCCESS (0.360s)
[95.294757] [007/133] (953s left) gem_readwrite (read-bad-handle)
[95.390371] Starting subtest: read-bad-handle
[95.392340] Subtest read-bad-handle: SUCCESS (0.000s)
[95.527136] [008/133] (953s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[95.695895] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[95.697872] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[95.810932] [009/133] (953s left) kms_vblank (invalid)
[95.979686] Starting subtest: invalid
[95.980633] Starting dynamic subtest: pipe-A-HDMI-A-1
[96.620289] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.641s)
[96.621245] Subtest invalid: SUCCESS (0.644s)
[96.899463] [010/133] (951s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[97.066211] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[97.067205] Starting dynamic subtest: pipe-A-HDMI-A-1
[97.070924] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[97.088662] Starting dynamic subtest: pipe-A-HDMI-A-2
[97.102706] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[97.119509] Starting dynamic subtest: pipe-B-HDMI-A-1
[97.131735] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[97.148502] Starting dynamic subtest: pipe-B-HDMI-A-2
[97.160826] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[97.179537] Starting dynamic subtest: pipe-C-HDMI-A-1
[97.183221] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[97.197790] Starting dynamic subtest: pipe-C-HDMI-A-2
[97.201027] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[97.202749] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.018s)
[97.938736] [011/133] (951s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[98.118679] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[98.120452] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[98.257843] [012/133] (950s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[98.426340] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[98.513147] [013/133] (950s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[98.682680] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[98.685119] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[98.805341] [014/133] (950s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[98.986393] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[98.988441] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[99.121698] [015/133] (950s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[99.300874] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[99.302674] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[99.441940] [016/133] (949s left) sw_sync (sync_expired_merge)
[99.534805] Starting subtest: sync_expired_merge
[100.608161] Subtest sync_expired_merge: SUCCESS (1.074s)
[100.707876] [017/133] (948s left) gem_create (busy-create)
[100.817681] Starting subtest: busy-create
[100.818801] Starting dynamic subtest: smem0
[133.082779] Dynamic subtest smem0: SUCCESS (32.265s)
[133.083933] Subtest busy-create: SUCCESS (32.267s)
[133.360187] [018/133] (916s left) api_intel_bb (add-remove-objects)
[133.459375] Starting subtest: add-remove-objects
[133.474927] Subtest add-remove-objects: SUCCESS (0.016s)
[133.615314] [019/133] (915s left) syncobj_timeline (multi-wait-submitted-signaled)
[133.711730] Starting subtest: multi-wait-submitted-signaled
[133.714251] Subtest multi-wait-submitted-signaled: SUCCESS (0.003s)
[133.840966] [020/133] (915s left) gem_exec_create (basic)
[133.955418] Starting subtest: basic
[133.956527] Starting dynamic subtest: smem
[136.012970] Dynamic subtest smem: SUCCESS (2.056s)
[136.014043] Subtest basic: SUCCESS (2.057s)
[136.242857] [021/133] (913s left) gem_mmap_gtt (basic-small-copy)
[136.335486] Starting subtest: basic-small-copy
[138.693811] Subtest basic-small-copy: SUCCESS (2.358s)
[138.858266] [022/133] (910s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[139.146950] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[139.148898] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[139.290347] [023/133] (910s left) kms_vrr (seamless-rr-switch-drrs)
[139.456846] Starting subtest: seamless-rr-switch-drrs
[139.460402] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[139.574274] [024/133] (910s left) kms_psr2_su (frontbuffer-xrgb8888)
[139.666221] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[139.750458] [025/133] (910s left) perf_pmu (busy-idle)
[139.855212] Starting subtest: busy-idle
[139.856062] Starting dynamic subtest: rcs0
[140.502074] Dynamic subtest rcs0: SUCCESS (0.647s)
[140.520238] Starting dynamic subtest: bcs0
[141.148304] Dynamic subtest bcs0: SUCCESS (0.644s)
[141.166322] Starting dynamic subtest: vcs0
[141.793277] Dynamic subtest vcs0: SUCCESS (0.644s)
[141.811815] Starting dynamic subtest: vecs0
[142.437065] Dynamic subtest vecs0: SUCCESS (0.640s)
[142.438243] Subtest busy-idle: SUCCESS (2.580s)
[142.558989] [026/133] (907s left) gem_mmap_wc (bad-object)
[142.654369] Starting subtest: bad-object
[142.655312] Subtest bad-object: SUCCESS (0.000s)
[142.810713] [027/133] (907s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[142.976787] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[142.979867] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SKIP (0.000s)
[143.093282] [028/133] (907s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[143.271890] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[146.165882] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.893s)
[146.343816] [029/133] (904s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[146.510499] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[146.512379] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[146.624251] [030/133] (903s left) kms_psr (psr2-cursor-blt)
[146.791033] Subtest psr2-cursor-blt: SKIP (0.000s)
[146.883559] [031/133] (903s left) kms_psr (fbc-psr-basic)
[147.049241] Subtest fbc-psr-basic: SKIP (0.000s)
[147.135062] [032/133] (903s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[147.862052] Starting subtest: syncobj-timeline-multiple-ext-nodes
[147.865723] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.004s)
[148.132381] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[148.136251] [033/133] (902s left) i915_pm_rpm (system-suspend-execbuf)
[150.058500] Starting subtest: system-suspend-execbuf
[173.593071] Subtest system-suspend-execbuf: SUCCESS (8.161s)
[174.956444] [034/133] (876s left) gem_ccs (block-multicopy-compressed)
[175.068224] Subtest block-multicopy-compressed: SKIP (0.000s)
[175.172314] [035/133] (876s left) sw_sync (timeline_closed)
[175.268097] Starting subtest: timeline_closed
[175.269099] Subtest timeline_closed: SUCCESS (0.000s)
[175.391113] [036/133] (876s left) gem_exec_balancer (bonded-dual)
[175.505347] Starting subtest: bonded-dual
[179.580026] Subtest bonded-dual: SUCCESS (4.176s)
[179.830299] [037/133] (872s left) kms_addfb_basic (addfb25-bad-modifier)
[179.999028] Starting subtest: addfb25-bad-modifier
[180.000555] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[180.141083] [038/133] (871s left) kms_cursor_crc (cursor-sliding-128x128)
[180.312843] Starting subtest: cursor-sliding-128x128
[180.349607] Starting dynamic subtest: pipe-A-HDMI-A-1
[184.320632] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.014s)
[184.391876] Starting dynamic subtest: pipe-C-HDMI-A-1
[188.063643] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.712s)
[188.064828] Subtest cursor-sliding-128x128: SUCCESS (7.824s)
[188.356122] [039/133] (863s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[188.523807] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[188.524676] Starting dynamic subtest: pipe-A
[188.827791] Dynamic subtest pipe-A: SUCCESS (0.306s)
[188.849871] Starting dynamic subtest: pipe-B
[189.198810] Dynamic subtest pipe-B: SUCCESS (0.374s)
[189.221257] Starting dynamic subtest: pipe-C
[189.249358] Dynamic subtest pipe-C: SUCCESS (0.049s)
[189.250358] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (0.732s)
[189.682375] [040/133] (862s left) gem_exec_suspend (basic-s4-devices)
[189.795014] Starting subtest: basic-S4-devices
[189.796061] Starting dynamic subtest: smem
[200.346008] Dynamic subtest smem: SUCCESS (10.581s)
[200.347310] Subtest basic-S4-devices: SUCCESS (10.583s)
[207.617229] [041/133] (851s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[207.802475] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[207.804272] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[207.960683] [042/133] (851s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[208.161985] Starting subtest: dp-mst-type-1-suspend-resume
[208.168413] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[208.307349] [043/133] (851s left) dmabuf_sync_file (import-multiple-read-write)
[208.422384] Starting subtest: import-multiple-read-write
[208.435182] Subtest import-multiple-read-write: SUCCESS (0.014s)
[208.557552] [044/133] (851s left) gem_ctx_persistence (heartbeat-hostile)
[208.662268] Starting subtest: heartbeat-hostile
[209.850317] Subtest heartbeat-hostile: SUCCESS (1.190s)
[209.989960] [045/133] (849s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[210.170149] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[210.171960] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[210.340804] [046/133] (849s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[210.529693] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[210.531588] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[210.688686] [047/133] (849s left) i915_drm_fdinfo (most-busy-idle-check-all)
[210.790578] Starting subtest: most-busy-idle-check-all
[210.791650] Starting dynamic subtest: rcs0
[211.429519] Dynamic subtest rcs0: SUCCESS (0.638s)
[211.455583] Starting dynamic subtest: bcs0
[212.060560] Dynamic subtest bcs0: SUCCESS (0.630s)
[212.084401] Starting dynamic subtest: vcs0
[212.701700] Dynamic subtest vcs0: SUCCESS (0.640s)
[212.726752] Starting dynamic subtest: vecs0
[213.335927] Dynamic subtest vecs0: SUCCESS (0.633s)
[213.336997] Subtest most-busy-idle-check-all: SUCCESS (2.548s)
[213.472193] [048/133] (846s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[213.655036] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[213.656715] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[213.805364] [049/133] (846s left) gem_workarounds (suspend-resume-context)
[213.931597] Starting subtest: suspend-resume-context