[88.668546] Initializing watchdogs
[88.668733]   /dev/watchdog0
[88.709183] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.724200] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.731251] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.737063] [001/132] (960s left) gem_exec_schedule (reorder-wide)
[89.017391] Starting subtest: reorder-wide
[89.020897] Starting dynamic subtest: rcs0
[89.248910] Dynamic subtest rcs0: SUCCESS (0.225s)
[89.270821] Starting dynamic subtest: bcs0
[89.582965] Dynamic subtest bcs0: SUCCESS (0.336s)
[89.628400] Starting dynamic subtest: vcs0
[89.747501] Dynamic subtest vcs0: SUCCESS (0.159s)
[89.770225] Starting dynamic subtest: vecs0
[89.914914] Dynamic subtest vecs0: SUCCESS (0.168s)
[89.916867] Subtest reorder-wide: SUCCESS (0.896s)
[90.203708] [FACT gem_exec_schedule (reorder-wide)] new: kernel.kmod_is_loaded.vgem: true
[90.209499] [002/132] (959s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[90.395079] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[90.400305] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[92.191866] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.790s)
[92.218266] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[93.856167] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.658s)
[93.880619] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[95.483073] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.620s)
[95.487058] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SUCCESS (5.084s)
[96.126955] [003/132] (953s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[96.242165] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[96.349763] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.105s)
[96.502178] [004/132] (953s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[96.686064] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[96.689612] Starting dynamic subtest: pipe-A-valid-mode
[97.677537] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.989s)
[97.679138] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SUCCESS (0.993s)
[97.991888] [005/132] (951s left) kms_busy (extended-modeset-hang-oldfb)
[98.197660] Starting subtest: extended-modeset-hang-oldfb
[98.207584] Starting dynamic subtest: pipe-A
[103.200602] Dynamic subtest pipe-A: SUCCESS (4.996s)
[103.221934] Starting dynamic subtest: pipe-C
[107.209219] Dynamic subtest pipe-C: SUCCESS (4.002s)
[107.210982] Subtest extended-modeset-hang-oldfb: SUCCESS (9.014s)
[107.554571] [006/132] (942s left) kms_psr (fbc-psr2-primary-blt)
[107.747180] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[107.845392] [007/132] (941s left) gem_lmem_swapping (heavy-verify-multi)
[107.964208] Subtest heavy-verify-multi: SKIP (0.000s)
[108.075783] [008/132] (941s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[108.276324] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[108.279828] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[108.420333] [009/132] (941s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[108.601129] Starting subtest: cursorA-vs-flipA-legacy
[119.874054] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.272s)
[120.164749] [010/132] (929s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[120.356052] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[120.359509] Starting dynamic subtest: pipe-A-valid-mode
[120.952208] Dynamic subtest pipe-A-valid-mode: SKIP (0.596s)
[120.955818] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.601s)
[121.427329] [011/132] (928s left) kms_dsc (dsc-fractional-bpp)
[121.621793] Subtest dsc-fractional-bpp: SKIP (0.000s)
[121.731917] [012/132] (928s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[121.915100] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[128.389593] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (6.470s)
[128.743311] [013/132] (921s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[128.962090] Starting subtest: yf-tiled-16bpp-rotate-90
[129.317238] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.356s)
[129.598290] [014/132] (920s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[129.795228] Starting subtest: flip-vs-cursor-busy-crc-legacy
[130.854241] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (1.056s)
[131.205205] [015/132] (919s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[131.406212] Starting subtest: yf-tiled-64bpp-rotate-270
[131.410117] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[131.545341] [016/132] (919s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[131.760174] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[133.547033] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.789s)
[133.841220] [017/132] (916s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[134.019077] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[134.020685] Starting dynamic subtest: pipe-A-HDMI-A-1
[134.040084] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[134.082551] Starting dynamic subtest: pipe-A-HDMI-A-2
[134.124066] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[134.158155] Starting dynamic subtest: pipe-B-HDMI-A-1
[134.173351] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[134.193177] Starting dynamic subtest: pipe-B-HDMI-A-2
[134.208144] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[134.227073] Starting dynamic subtest: pipe-C-HDMI-A-1
[134.241896] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[134.260284] Starting dynamic subtest: pipe-C-HDMI-A-2
[134.275884] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[134.278953] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.036s)
[135.081579] [018/132] (915s left) gem_exec_reloc (basic-wc-cpu)
[135.204703] Starting subtest: basic-wc-cpu
[135.220079] Subtest basic-wc-cpu: SUCCESS (0.018s)
[135.452357] [019/132] (915s left) perf_pmu (semaphore-wait)
[135.577885] Starting subtest: semaphore-wait
[135.582674] Starting dynamic subtest: rcs0
[136.094654] Dynamic subtest rcs0: SUCCESS (0.518s)
[136.117724] Starting dynamic subtest: bcs0
[136.616523] Dynamic subtest bcs0: SUCCESS (0.517s)
[136.637389] Starting dynamic subtest: vcs0
[137.132062] Dynamic subtest vcs0: SUCCESS (0.514s)
[137.154724] Starting dynamic subtest: vecs0
[137.649463] Dynamic subtest vecs0: SUCCESS (0.514s)
[137.654117] Subtest semaphore-wait: SUCCESS (2.077s)
[137.821765] [020/132] (913s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[138.015231] Starting subtest: fbc-rgb565-draw-pwrite
[141.076483] Subtest fbc-rgb565-draw-pwrite: SUCCESS (3.059s)
[141.369644] [021/132] (909s left) gem_exec_basic (basic)
[141.491216] Starting subtest: basic
[141.494907] Starting dynamic subtest: rcs0-smem
[141.499231] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[141.522393] Starting dynamic subtest: bcs0-smem
[141.524880] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[141.545431] Starting dynamic subtest: vcs0-smem
[141.547724] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[141.568590] Starting dynamic subtest: vecs0-smem
[141.571357] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[141.573636] Subtest basic: SUCCESS (0.015s)
[141.778533] [022/132] (909s left) kms_properties (get_properties-sanity-non-atomic)
[141.999099] Starting subtest: get_properties-sanity-non-atomic
[142.005083] Subtest get_properties-sanity-non-atomic: SUCCESS (0.010s)
[142.216484] [023/132] (909s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[142.429384] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[144.959160] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SUCCESS (2.528s)
[145.202803] [024/132] (906s left) gem_eio (banned)
[145.345171] Starting subtest: banned
[145.518973] Subtest banned: SUCCESS (0.175s)
[145.766694] [025/132] (905s left) kms_pm_rpm (basic-pci-d3-state)
[147.672574] Starting subtest: basic-pci-d3-state
[148.009659] Subtest basic-pci-d3-state: SUCCESS (0.325s)
[148.380789] [026/132] (903s left) kms_cursor_legacy (torture-move)
[148.562068] Starting subtest: torture-move
[148.569099] Starting dynamic subtest: pipe-A
[155.007042] Dynamic subtest pipe-A: SUCCESS (6.440s)
[155.049059] Starting dynamic subtest: pipe-B
[160.620076] Dynamic subtest pipe-B: SUCCESS (5.612s)
[160.663973] Starting dynamic subtest: pipe-C
[166.168729] Dynamic subtest pipe-C: SUCCESS (5.546s)
[166.190138] Starting dynamic subtest: all-pipes
[171.889954] Dynamic subtest all-pipes: SUCCESS (5.720s)
[171.891704] Subtest torture-move: SUCCESS (23.329s)
[172.083087] [027/132] (879s left) core_hotunplug (unbind-rebind)
[172.277995] Starting subtest: unbind-rebind
[176.616269] Subtest unbind-rebind: SUCCESS (4.338s)
[176.717944] [028/132] (875s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[176.913167] Starting subtest: plane-upscale-20x20-with-modifiers
[176.914875] Starting dynamic subtest: pipe-A
[177.831241] Dynamic subtest pipe-A: SUCCESS (0.918s)
[177.854119] Starting dynamic subtest: pipe-B
[178.699121] Dynamic subtest pipe-B: SUCCESS (0.864s)
[178.747246] Starting dynamic subtest: pipe-C
[179.548961] Dynamic subtest pipe-C: SUCCESS (0.845s)
[179.552039] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (2.634s)
[180.108118] [029/132] (871s left) kms_psr (psr-cursor-plane-move)
[180.297242] Subtest psr-cursor-plane-move: SKIP (0.000s)
[180.414121] [030/132] (871s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[180.624269] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[182.726744] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.102s)
[182.941460] [031/132] (869s left) sw_sync (sync_busy_fork_unixsocket)
[183.048135] Starting subtest: sync_busy_fork_unixsocket
[183.073125] Subtest sync_busy_fork_unixsocket: SUCCESS (0.025s)
[183.206899] [032/132] (869s left) gem_pipe_control_store_loop (reused-buffer)
[183.326814] Starting subtest: reused-buffer
[185.478334] Subtest reused-buffer: SUCCESS (2.153s)
[185.653185] [033/132] (866s left) kms_cursor_crc (cursor-onscreen-256x256)
[185.844689] Starting subtest: cursor-onscreen-256x256
[185.893126] Starting dynamic subtest: pipe-A-HDMI-A-1
[188.619968] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.729s)
[188.748295] Starting dynamic subtest: pipe-C-HDMI-A-1
[191.145696] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.450s)
[191.147466] Subtest cursor-onscreen-256x256: SUCCESS (5.302s)
[191.487078] [034/132] (860s left) gem_userptr_blits (readonly-pwrite-unsync)
[191.612804] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[191.836738] [035/132] (860s left) kms_content_protection (uevent)
[192.042084] Starting subtest: uevent
[192.070587] Subtest uevent: SKIP (0.019s)
[192.217955] [036/132] (860s left) gem_mmap_wc (set-cache-level)
[192.326534] Starting subtest: set-cache-level
[192.329461] Subtest set-cache-level: SUCCESS (0.006s)
[192.487675] [037/132] (860s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[192.670265] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[192.673066] Starting dynamic subtest: pipe-A-HDMI-A-1
[192.700246] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[192.749098] Starting dynamic subtest: pipe-A-HDMI-A-2
[192.794227] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[192.820977] Starting dynamic subtest: pipe-B-HDMI-A-1
[192.836719] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[192.859190] Starting dynamic subtest: pipe-B-HDMI-A-2
[192.874351] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[192.896673] Starting dynamic subtest: pipe-C-HDMI-A-1
[192.911601] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[192.933286] Starting dynamic subtest: pipe-C-HDMI-A-2
[192.948437] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[192.951505] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.033s)
[193.668685] [038/132] (859s left) gem_flink_race (flink_close)
[193.769935] Starting subtest: flink_close
[198.790056] Subtest flink_close: SUCCESS (5.016s)
[198.954456] [039/132] (854s left) gem_ctx_persistence (heartbeat-close)
[199.069215] Starting subtest: heartbeat-close
[199.208621] Subtest heartbeat-close: SUCCESS (0.140s)
[199.359978] [040/132] (853s left) gem_eio (wait-wedge-1us)
[199.504119] Starting subtest: wait-wedge-1us
[199.591383] Subtest wait-wedge-1us: SUCCESS (0.107s)
[199.851356] [041/132] (853s left) perf_pmu (rc6-suspend)
[199.963517] Starting subtest: rc6-suspend