[93.758797] Initializing watchdogs
[93.758972]   /dev/watchdog0
[93.800230] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[93.815498] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.823308] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[93.831322] [001/132] (960s left) kms_feature_discovery (chamelium)
[94.043167] Starting subtest: chamelium
[94.053327] Subtest chamelium: SKIP (0.000s)
[94.194393] [002/132] (960s left) gem_exec_schedule (submit-late-slice)
[94.349624] Starting subtest: submit-late-slice
[94.353470] Starting dynamic subtest: rcs0
[94.418432] Dynamic subtest rcs0: SUCCESS (0.063s)
[94.447789] Starting dynamic subtest: bcs0
[94.481714] Dynamic subtest bcs0: SUCCESS (0.059s)
[94.504166] Starting dynamic subtest: vcs0
[94.531198] Dynamic subtest vcs0: SUCCESS (0.055s)
[94.553119] Starting dynamic subtest: vecs0
[94.582990] Dynamic subtest vecs0: SUCCESS (0.054s)
[94.586940] Subtest submit-late-slice: SUCCESS (0.236s)
[94.992893] [003/132] (959s left) i915_query (query-topology-known-pci-ids)
[95.105321] Starting subtest: query-topology-known-pci-ids
[95.112664] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[95.231056] [004/132] (959s left) sw_sync (sync_expired_merge)
[95.341980] Starting subtest: sync_expired_merge
[96.415420] Subtest sync_expired_merge: SUCCESS (1.074s)
[96.532874] [005/132] (958s left) kms_pm_rpm (dpms-non-lpsp)
[98.543497] Starting subtest: dpms-non-lpsp
[98.704040] Subtest dpms-non-lpsp: SUCCESS (0.150s)
[99.060388] [006/132] (955s left) perf_pmu (invalid-open)
[99.181275] Starting subtest: invalid-open
[99.185139] Subtest invalid-open: SUCCESS (0.001s)
[99.320064] [007/132] (955s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[99.891147] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[100.229860] [008/132] (954s left) gem_ctx_exec (basic-close-race)
[100.353148] Starting subtest: basic-close-race
[105.789404] Subtest basic-close-race: SUCCESS (5.437s)
[105.927916] [009/132] (949s left) kms_psr (psr-sprite-plane-move)
[106.122081] Subtest psr-sprite-plane-move: SKIP (0.000s)
[106.232961] [010/132] (948s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[106.433084] Starting subtest: plane-upscale-20x20-with-pixel-format
[106.436206] Starting dynamic subtest: pipe-A
[107.468250] Dynamic subtest pipe-A: SUCCESS (1.040s)
[107.496515] Starting dynamic subtest: pipe-B
[108.580900] Dynamic subtest pipe-B: SUCCESS (1.115s)
[108.621102] Starting dynamic subtest: pipe-C
[109.046348] Dynamic subtest pipe-C: SUCCESS (0.461s)
[109.054085] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (2.617s)
[109.599183] [011/132] (945s left) gem_exec_reloc (basic-wc)
[109.728401] Starting subtest: basic-wc
[109.744695] Subtest basic-wc: SUCCESS (0.018s)
[109.980875] [012/132] (945s left) gem_mmap_wc (copy)
[110.091271] Starting subtest: copy
[110.309158] Subtest copy: SUCCESS (0.220s)
[110.448124] [013/132] (945s left) kms_chamelium_edid (dp-edid-resolution-list)
[111.026113] Subtest dp-edid-resolution-list: SKIP (0.000s)
[111.344378] [014/132] (944s left) kms_getfb (getfb-handle-zero)
[111.530397] Starting subtest: getfb-handle-zero
[111.535126] Subtest getfb-handle-zero: SUCCESS (0.000s)
[111.681087] [015/132] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[111.894076] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[111.903068] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[112.030884] [016/132] (943s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[112.230183] Starting subtest: cursorB-vs-flipA-atomic
[123.543714] Subtest cursorB-vs-flipA-atomic: SUCCESS (11.318s)
[123.853665] [017/132] (932s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[123.981125] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[123.984454] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[124.117331] [018/132] (931s left) prime_mmap_coherency (write)
[124.239822] Starting subtest: write
[129.640760] Subtest write: SUCCESS (5.403s)
[129.804665] [019/132] (926s left) gem_exec_balancer (bonded-semaphore)
[129.930813] Starting subtest: bonded-semaphore
[134.330797] Subtest bonded-semaphore: SUCCESS (4.401s)
[134.619194] [020/132] (921s left) gen3_render_mixed_blits
[134.859350] [021/132] (921s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[135.063329] Starting subtest: fbcpsr-rgb101010-draw-render
[135.071879] Subtest fbcpsr-rgb101010-draw-render: SKIP (0.000s)
[135.221779] [022/132] (921s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[135.405954] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[135.416179] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[135.553706] [023/132] (920s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[135.772191] Starting subtest: fbc-modesetfrombusy
[137.372229] Subtest fbc-modesetfrombusy: SUCCESS (1.607s)
[137.612930] [024/132] (918s left) gem_exec_balancer (parallel-contexts)
[137.866193] Subtest parallel-contexts: SKIP (0.000s)
[138.012204] [025/132] (918s left) api_intel_allocator (reserve-simple)
[138.129187] Starting subtest: reserve-simple
[138.132687] Subtest reserve-simple: SUCCESS (0.003s)
[138.279532] [026/132] (918s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[138.486714] Starting subtest: psr-rgb565-draw-mmap-wc
[138.492692] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[138.630081] [027/132] (918s left) gem_userptr_blits (sd-probe)
[138.762944] Starting subtest: sd-probe
[138.769059] Subtest sd-probe: SUCCESS (0.001s)
[138.946053] [028/132] (917s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[139.058131] Starting subtest: multi-wait-all-for-submit-signaled
[139.061390] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[139.197477] [029/132] (917s left) kms_vrr (flip-dpms)
[139.379075] Starting subtest: flip-dpms
[139.393995] Subtest flip-dpms: SKIP (0.000s)
[139.512707] [030/132] (917s left) syncobj_wait (multi-wait-submitted)
[139.629524] Starting subtest: multi-wait-submitted
[139.735735] Subtest multi-wait-submitted: SUCCESS (0.107s)
[139.877842] [031/132] (917s left) gem_render_copy_redux (flink)
[140.002068] Starting subtest: flink
[143.078398] Subtest flink: SUCCESS (3.074s)
[143.238446] [032/132] (914s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[143.422116] Starting subtest: crc-primary-basic-yf-tiled-ccs
[143.428706] Starting dynamic subtest: pipe-A-HDMI-A-1
[143.965379] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.520s)
[143.996985] Starting dynamic subtest: pipe-A-HDMI-A-2
[144.407552] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.446s)
[144.451678] Starting dynamic subtest: pipe-B-HDMI-A-1
[144.885075] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.473s)
[144.913265] Starting dynamic subtest: pipe-B-HDMI-A-2
[145.306507] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.416s)
[145.350880] Starting dynamic subtest: pipe-C-HDMI-A-1
[145.404096] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[145.427154] Starting dynamic subtest: pipe-C-HDMI-A-2
[145.470162] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[145.474103] Subtest crc-primary-basic-yf-tiled-ccs: SUCCESS (1.880s)
[146.080856] [033/132] (911s left) kms_atomic (crtc-invalid-params-fence)
[146.274113] Starting subtest: crtc-invalid-params-fence
[146.279101] Starting dynamic subtest: pipe-A-HDMI-A-1
[146.934163] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.661s)
[146.939389] Subtest crtc-invalid-params-fence: SUCCESS (0.666s)
[147.271816] [034/132] (910s left) kms_psr (fbc-psr-primary-render)
[147.483873] Subtest fbc-psr-primary-render: SKIP (0.000s)
[147.582769] [035/132] (909s left) kms_getfb (getfb2-handle-closed)
[147.769323] Starting subtest: getfb2-handle-closed
[147.773125] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[147.919764] [036/132] (909s left) kms_psr2_su (frontbuffer-xrgb8888)
[148.035609] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[148.144506] [037/132] (909s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[148.332467] Starting subtest: cursor-vs-flip-legacy
[189.181265] Subtest cursor-vs-flip-legacy: SUCCESS (40.846s)
[189.529356] [038/132] (868s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[189.743065] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[190.797579] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.056s)
[191.124637] [039/132] (866s left) gem_mmap_offset (pf-nonblock)
[191.239279] Starting subtest: pf-nonblock
[191.247837] Subtest pf-nonblock: SUCCESS (0.013s)
[191.405543] [040/132] (866s left) kms_psr (fbc-pr-primary-page-flip)
[191.598071] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[191.701322] [041/132] (866s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[192.484746] Starting subtest: syncobj-timeline-invalid-wait
[192.488973] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[192.760451] [FACT gem_exec_fence (syncobj-timeline-invalid-wait)] new: kernel.kmod_is_loaded.vgem: true
[192.767434] [042/132] (865s left) sysfs_heartbeat_interval (precise)
[192.891183] Starting subtest: precise
[192.894566] Starting dynamic subtest: vecs0
[194.355189] Dynamic subtest vecs0: SUCCESS (1.459s)
[194.387621] Starting dynamic subtest: vcs0
[195.813139] Dynamic subtest vcs0: SUCCESS (1.457s)
[195.838635] Starting dynamic subtest: bcs0
[197.267601] Dynamic subtest bcs0: SUCCESS (1.455s)
[197.295528] Starting dynamic subtest: rcs0
[198.723593] Dynamic subtest rcs0: SUCCESS (1.454s)
[198.728564] Subtest precise: SUCCESS (5.834s)
[198.868478] [043/132] (859s left) kms_prop_blob (basic)
[198.983109] Starting subtest: basic
[198.986743] Subtest basic: SUCCESS (0.000s)
[199.135658] [044/132] (859s left) gem_pxp (reject-modify-context-protection-on)
[201.786858] Starting subtest: reject-modify-context-protection-on
[201.793698] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[201.935388] [045/132] (856s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[202.160198] Starting subtest: cursor-rapid-movement-max-size
[202.170228] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[202.318541] [046/132] (856s left) kms_addfb_basic (basic-x-tiled-legacy)
[202.428621] Starting subtest: basic-x-tiled-legacy
[202.435060] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[202.669077] [047/132] (856s left) i915_pm_rps (thresholds)
[202.798062] Starting subtest: thresholds
[202.801943] Starting dynamic subtest: gt0
[212.895618] Dynamic subtest gt0: SUCCESS (10.098s)
[212.899649] Subtest thresholds: SUCCESS (10.103s)
[213.068935] [048/132] (845s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[213.285258] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[215.128076] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.830s)
[215.404494] [049/132] (843s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[215.618086] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[215.625872] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[215.770263] [050/132] (843s left) kms_pipe_crc_basic (suspend-read-crc)
[215.965997] Starting subtest: suspend-read-crc
[215.969305] Starting dynamic subtest: pipe-A-HDMI-A-1