[21.202580] Initializing watchdogs
[21.202629]   /dev/watchdog0
[21.206656] [001/143] (960s left) kms_async_flips (async-flip-with-page-flip-events)
[21.346310] Starting subtest: async-flip-with-page-flip-events
[21.447301] Starting dynamic subtest: pipe-A-HDMI-A-1-y-rc-ccs
[21.601808] Dynamic subtest pipe-A-HDMI-A-1-y-rc-ccs: SKIP (0.153s)
[21.605276] Starting dynamic subtest: pipe-A-HDMI-A-1-y-rc-ccs-cc
[21.701046] Dynamic subtest pipe-A-HDMI-A-1-y-rc-ccs-cc: SKIP (0.099s)
[21.704407] Starting dynamic subtest: pipe-A-HDMI-A-1-y
[23.777683] Dynamic subtest pipe-A-HDMI-A-1-y: SUCCESS (2.069s)
[23.783621] Starting dynamic subtest: pipe-A-HDMI-A-1-x
[25.819090] Dynamic subtest pipe-A-HDMI-A-1-x: SUCCESS (2.042s)
[25.822317] Starting dynamic subtest: pipe-A-HDMI-A-1-linear
[27.852923] Dynamic subtest pipe-A-HDMI-A-1-linear: SUCCESS (2.033s)
[27.959693] Starting dynamic subtest: pipe-B-HDMI-A-1-y-rc-ccs
[28.097101] Dynamic subtest pipe-B-HDMI-A-1-y-rc-ccs: SKIP (0.139s)
[28.101643] Starting dynamic subtest: pipe-B-HDMI-A-1-y-rc-ccs-cc
[28.180704] Dynamic subtest pipe-B-HDMI-A-1-y-rc-ccs-cc: SKIP (0.083s)
[28.184221] Starting dynamic subtest: pipe-B-HDMI-A-1-y
[30.257620] Dynamic subtest pipe-B-HDMI-A-1-y: SUCCESS (2.069s)
[30.263572] Starting dynamic subtest: pipe-B-HDMI-A-1-x
[32.290858] Dynamic subtest pipe-B-HDMI-A-1-x: SUCCESS (2.033s)
[32.294278] Starting dynamic subtest: pipe-B-HDMI-A-1-linear
[34.316120] Dynamic subtest pipe-B-HDMI-A-1-linear: SUCCESS (2.025s)
[34.411305] Starting dynamic subtest: pipe-C-HDMI-A-1-y-rc-ccs
[34.554262] Dynamic subtest pipe-C-HDMI-A-1-y-rc-ccs: SKIP (0.145s)
[34.558047] Starting dynamic subtest: pipe-C-HDMI-A-1-y-rc-ccs-cc
[34.645614] Dynamic subtest pipe-C-HDMI-A-1-y-rc-ccs-cc: SKIP (0.091s)
[34.649341] Starting dynamic subtest: pipe-C-HDMI-A-1-y
[36.724140] Dynamic subtest pipe-C-HDMI-A-1-y: SUCCESS (2.070s)
[36.731675] Starting dynamic subtest: pipe-C-HDMI-A-1-x
[38.764554] Dynamic subtest pipe-C-HDMI-A-1-x: SUCCESS (2.042s)
[38.769169] Starting dynamic subtest: pipe-C-HDMI-A-1-linear
[40.789674] Dynamic subtest pipe-C-HDMI-A-1-linear: SUCCESS (2.025s)
[40.881889] Starting dynamic subtest: pipe-D-HDMI-A-1-y-rc-ccs
[41.021293] Dynamic subtest pipe-D-HDMI-A-1-y-rc-ccs: SKIP (0.138s)
[41.025222] Starting dynamic subtest: pipe-D-HDMI-A-1-y-rc-ccs-cc
[41.108282] Dynamic subtest pipe-D-HDMI-A-1-y-rc-ccs-cc: SKIP (0.090s)
[41.111807] Starting dynamic subtest: pipe-D-HDMI-A-1-y
[43.187529] Dynamic subtest pipe-D-HDMI-A-1-y: SUCCESS (2.071s)
[43.194102] Starting dynamic subtest: pipe-D-HDMI-A-1-x
[45.227699] Dynamic subtest pipe-D-HDMI-A-1-x: SUCCESS (2.041s)
[45.232231] Starting dynamic subtest: pipe-D-HDMI-A-1-linear
[47.253013] Dynamic subtest pipe-D-HDMI-A-1-linear: SUCCESS (2.025s)
[47.253920] Subtest async-flip-with-page-flip-events: SUCCESS (25.899s)
[47.419795] [002/143] (934s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[47.497907] Starting subtest: modeset-atomic-cursor-hotspot
[47.499505] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[47.534350] [003/143] (934s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[47.616063] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[47.617645] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[47.672081] [004/143] (934s left) kms_ccs (pipe-b-missing-ccs-buffer-y-tiled-ccs)
[47.850955] Starting subtest: pipe-B-missing-ccs-buffer-y-tiled-ccs
[47.852432] Subtest pipe-B-missing-ccs-buffer-y-tiled-ccs: SKIP (0.000s)
[47.981217] [005/143] (933s left) gem_exec_balancer (nohangcheck)
[48.225482] Starting subtest: nohangcheck
[49.022069] Subtest nohangcheck: SUCCESS (0.796s)
[49.048430] [006/143] (932s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[49.257822] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[49.259263] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[49.343010] [007/143] (932s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[49.426697] Starting subtest: cursor-rapid-movement-512x170
[49.428158] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[49.480160] [008/143] (932s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[49.524324] Starting subtest: yf-tiled-ccs-to-y-tiled
[49.525131] Starting dynamic subtest: smem
[49.623128] Dynamic subtest smem: SUCCESS (0.099s)
[49.623881] Subtest yf-tiled-ccs-to-y-tiled: SUCCESS (0.099s)
[49.777243] [009/143] (932s left) v3d/v3d_wait_bo (bad-bo)
[49.849122] Subtest bad-bo: SKIP (0.000s)
[49.878436] [010/143] (931s left) gem_lmem_swapping (smem-oom)
[49.925359] Subtest smem-oom: SKIP (0.000s)
[49.944405] [011/143] (931s left) gem_softpin (allocator-basic)
[49.984580] Starting subtest: allocator-basic
[50.044851] Subtest allocator-basic: SUCCESS (0.060s)
[50.118319] [012/143] (931s left) api_intel_allocator (default-alignment)
[50.161014] Starting subtest: default-alignment
[50.162663] Subtest default-alignment: SUCCESS (0.002s)
[50.234886] [013/143] (931s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[50.333445] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[50.335062] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[50.389794] [014/143] (931s left) gem_eio (in-flight-internal-10ms)
[50.441464] Starting subtest: in-flight-internal-10ms
[50.692943] Subtest in-flight-internal-10ms: SUCCESS (0.254s)
[50.780899] [015/143] (931s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[50.860589] Starting subtest: cursorB-vs-flipB-legacy
[50.862142] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[50.892964] [016/143] (930s left) gem_mmap_gtt (basic-small-bo-tiledy)
[50.925885] Starting subtest: basic-small-bo-tiledY
[51.272645] Subtest basic-small-bo-tiledY: SUCCESS (0.348s)
[51.350127] [017/143] (930s left) fbdev (unaligned-read)
[51.431872] Starting subtest: unaligned-read
[51.436970] Subtest unaligned-read: SUCCESS (0.005s)
[51.468839] [018/143] (930s left) vc4/vc4_label_bo (set-bad-name)
[51.524646] Subtest set-bad-name: SKIP (0.000s)
[51.555807] [019/143] (930s left) gem_huc_copy (huc-copy)
[51.595551] Starting subtest: huc-copy
[51.597200] Subtest huc-copy: SKIP (0.000s)
[51.657268] [020/143] (930s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[51.702442] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[51.703275] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[51.743498] [021/143] (930s left) syncobj_timeline (invalid-query-bad-pad)
[51.790375] Starting subtest: invalid-query-bad-pad
[51.791242] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[51.828940] [022/143] (930s left) syncobj_basic (bad-destroy)
[51.874207] Starting subtest: bad-destroy
[51.874937] Subtest bad-destroy: SUCCESS (0.000s)
[51.920162] [023/143] (930s left) kms_pm_rpm (modeset-stress-extra-wait)
[53.467044] Starting subtest: modeset-stress-extra-wait
[105.553958] Subtest modeset-stress-extra-wait: SUCCESS (52.079s)
[105.600953] [024/143] (876s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[105.692500] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[105.694024] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[105.742417] [025/143] (876s left) i915_pm_rps (basic-api)
[105.784207] Starting subtest: basic-api
[105.786486] Subtest basic-api: SUCCESS (0.002s)
[105.824827] [026/143] (876s left) gem_mmap_offset (oob-read)
[105.862312] Starting subtest: oob-read
[105.863111] Subtest oob-read: SUCCESS (0.000s)
[105.910577] [027/143] (876s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[105.951335] Starting subtest: etime-multi-wait-all-for-submit-submitted
[106.053861] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[106.101146] [028/143] (875s left) kms_cdclk (mode-transition-all-outputs)
[106.141090] Subtest mode-transition-all-outputs: SKIP (0.000s)
[106.158744] [029/143] (875s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[106.245066] Starting subtest: psr-farfromfence-mmap-gtt
[106.246611] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[106.293819] [030/143] (875s left) gem_exec_fair (basic-pace-solo)
[106.347292] Starting subtest: basic-pace-solo
[106.348057] Starting dynamic subtest: rcs0
[111.935671] Dynamic subtest rcs0: FAIL (5.572s)
[111.936376] Subtest basic-pace-solo: FAIL (5.572s)
[112.067494] [031/143] (870s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[112.163890] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[112.165481] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[112.223594] [032/143] (869s left) syncobj_timeline (transfer-timeline-point)
[112.266757] Starting subtest: transfer-timeline-point
[112.267708] Subtest transfer-timeline-point: SUCCESS (0.000s)
[112.299714] [033/143] (869s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-dg2-mc-ccs)
[112.522067] Starting subtest: pipe-D-crc-primary-basic-4-tiled-dg2-mc-ccs
[112.523681] Subtest pipe-D-crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[112.611904] [034/143] (869s left) kms_color (deep-color)
[112.691488] Starting subtest: deep-color
[112.696062] Subtest deep-color: SKIP (0.003s)
[112.726571] [035/143] (869s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[112.815058] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[112.816571] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[112.862997] [036/143] (869s left) gem_exec_schedule (submit-early-slice)
[112.917302] Starting subtest: submit-early-slice
[112.918128] Starting dynamic subtest: rcs0
[112.944116] Dynamic subtest rcs0: SUCCESS (0.026s)
[112.948676] Starting dynamic subtest: bcs0
[112.970669] Dynamic subtest bcs0: SUCCESS (0.027s)
[112.974997] Starting dynamic subtest: vcs0
[112.996640] Dynamic subtest vcs0: SUCCESS (0.026s)
[113.001049] Starting dynamic subtest: vcs1
[113.023511] Dynamic subtest vcs1: SUCCESS (0.027s)
[113.027929] Starting dynamic subtest: vecs0
[113.048965] Dynamic subtest vecs0: SUCCESS (0.025s)
[113.049845] Subtest submit-early-slice: SUCCESS (0.132s)
[113.326369] [037/143] (868s left) prime_vgem (busy)
[113.409515] Starting subtest: busy
[113.410342] Starting dynamic subtest: rcs0
[113.443808] Dynamic subtest rcs0: SUCCESS (0.034s)
[113.453407] Starting dynamic subtest: bcs0
[113.467072] Dynamic subtest bcs0: SUCCESS (0.023s)
[113.476824] Starting dynamic subtest: vcs0
[113.490508] Dynamic subtest vcs0: SUCCESS (0.023s)
[113.500182] Starting dynamic subtest: vcs1
[113.511230] Dynamic subtest vcs1: SUCCESS (0.020s)
[113.520638] Starting dynamic subtest: vecs0
[113.525344] Dynamic subtest vecs0: SUCCESS (0.014s)
[113.526271] Subtest busy: SUCCESS (0.116s)
[113.612325] [038/143] (868s left) kms_plane (plane-position-hole)
[113.699346] Starting subtest: plane-position-hole
[113.700170] Starting dynamic subtest: pipe-A
[115.181038] Dynamic subtest pipe-A: SUCCESS (1.482s)
[115.187387] Starting dynamic subtest: pipe-B
[116.645197] Dynamic subtest pipe-B: SUCCESS (1.463s)
[116.645963] Subtest plane-position-hole: SUCCESS (2.947s)
[116.745775] [039/143] (865s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-mtl-mc-ccs)
[116.882121] Starting subtest: pipe-A-bad-rotation-90-4-tiled-mtl-mc-ccs
[116.883836] Subtest pipe-A-bad-rotation-90-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[117.050872] [040/143] (865s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[117.133363] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.000s)
[117.149453] [041/143] (865s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[117.230116] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[117.230944] Starting dynamic subtest: pipe-A-valid-mode
[117.993032] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.763s)
[117.993744] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (0.764s)
[118.100403] [042/143] (864s left) gem_render_copy (x-tiled)
[118.137952] Starting subtest: x-tiled
[118.138828] Starting dynamic subtest: smem
[118.233803] Dynamic subtest smem: SUCCESS (0.095s)
[118.234718] Subtest x-tiled: SUCCESS (0.096s)
[118.386068] [043/143] (863s left) gen7_exec_parse (basic-rejected)
[118.421755] Subtest basic-rejected: SKIP (0.000s)
[118.438035] [044/143] (863s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[118.524874] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[118.526476] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[118.571235] [045/143] (863s left) gem_create (create-ext-cpu-access-sanity-check)
[118.730485] Starting subtest: create-ext-cpu-access-sanity-check
[118.732642] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[118.780115] [046/143] (863s left) gem_exec_alignment (pi)