[64.606776] Initializing watchdogs
[64.606844]   /dev/watchdog0
[64.622904] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[64.628680] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[64.631279] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.633213] [001/156] (960s left) syncobj_timeline (multi-wait-all-available-submitted)
[64.673035] Starting subtest: multi-wait-all-available-submitted
[64.674489] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[64.751792] [002/156] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[64.849721] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[64.851189] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[64.948457] [003/156] (960s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[65.037449] Starting subtest: cursorA-vs-flipB-toggle
[65.038823] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[65.095413] [004/156] (960s left) kms_dirtyfb (default-dirtyfb-ioctl)
[65.186603] Starting subtest: default-dirtyfb-ioctl
[65.187365] Starting dynamic subtest: A-HDMI-A-1
[66.402474] Dynamic subtest A-HDMI-A-1: SUCCESS (1.217s)
[66.403166] Subtest default-dirtyfb-ioctl: SUCCESS (1.219s)
[66.519471] [005/156] (958s left) gem_exec_schedule (u-semaphore-resolve)
[66.569699] Starting subtest: u-semaphore-resolve
[66.589318] Subtest u-semaphore-resolve: SUCCESS (0.020s)
[66.872075] [006/156] (958s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[67.007375] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[67.010256] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[67.357303] [007/156] (957s left) kms_frontbuffer_tracking (hdr-suspend)
[67.679462] Starting subtest: hdr-suspend
[67.681912] Subtest hdr-suspend: SKIP (0.000s)
[67.905862] [008/156] (957s left) gem_mmap_wc (invalid-flags)
[67.948317] Starting subtest: invalid-flags
[67.949029] Subtest invalid-flags: SUCCESS (0.000s)
[68.068761] [009/156] (957s left) gem_spin_batch (legacy)
[68.109691] Starting subtest: legacy
[68.110425] Starting dynamic subtest: default
[71.460054] Dynamic subtest default: SUCCESS (3.318s)
[71.468471] Starting dynamic subtest: render
[74.776514] Dynamic subtest render: SUCCESS (3.318s)
[74.785128] Starting dynamic subtest: bsd
[78.095171] Dynamic subtest bsd: SUCCESS (3.319s)
[78.104110] Starting dynamic subtest: bsd1
[81.413962] Dynamic subtest bsd1: SUCCESS (3.318s)
[81.422859] Starting dynamic subtest: bsd2
[84.706768] Dynamic subtest bsd2: SUCCESS (3.317s)
[84.715649] Starting dynamic subtest: blt
[88.051577] Dynamic subtest blt: SUCCESS (3.318s)
[88.060442] Starting dynamic subtest: vebox
[91.369764] Dynamic subtest vebox: SUCCESS (3.317s)
[91.370732] Subtest legacy: SUCCESS (23.231s)
[91.519970] [010/156] (933s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[91.610286] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[91.610994] Starting dynamic subtest: pipe-A-HDMI-A-1
[91.617813] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[91.624098] Starting dynamic subtest: pipe-B-HDMI-A-1
[91.630889] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[91.637009] Starting dynamic subtest: pipe-C-HDMI-A-1
[91.643783] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[91.649929] Starting dynamic subtest: pipe-D-HDMI-A-1
[91.656830] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[91.658196] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[91.859389] [011/156] (933s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[91.960850] Starting subtest: yf-tiled-8bpp-rotate-90
[91.962219] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[92.042231] [012/156] (933s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[92.130751] Starting subtest: plane-lut1d-post-ctm3x4
[92.210202] Starting dynamic subtest: pipe-A-plane-0
[92.318529] Dynamic subtest pipe-A-plane-0: SUCCESS (0.108s)
[92.330945] Starting dynamic subtest: pipe-A-plane-1
[92.418392] Dynamic subtest pipe-A-plane-1: SUCCESS (0.099s)
[92.428057] Starting dynamic subtest: pipe-A-plane-2
[92.501874] Dynamic subtest pipe-A-plane-2: SUCCESS (0.083s)
[92.747359] Starting dynamic subtest: pipe-B-plane-0
[92.833715] Dynamic subtest pipe-B-plane-0: SUCCESS (0.092s)
[92.844867] Starting dynamic subtest: pipe-B-plane-1
[92.926262] Dynamic subtest pipe-B-plane-1: SUCCESS (0.091s)
[92.936540] Starting dynamic subtest: pipe-B-plane-2
[93.025392] Dynamic subtest pipe-B-plane-2: SUCCESS (0.100s)
[93.232442] Starting dynamic subtest: pipe-C-plane-0
[93.310775] Dynamic subtest pipe-C-plane-0: SUCCESS (0.084s)
[93.322420] Starting dynamic subtest: pipe-C-plane-1
[93.410916] Dynamic subtest pipe-C-plane-1: SUCCESS (0.099s)
[93.422512] Starting dynamic subtest: pipe-C-plane-2
[93.510394] Dynamic subtest pipe-C-plane-2: SUCCESS (0.099s)
[93.716324] Starting dynamic subtest: pipe-D-plane-0
[93.802816] Dynamic subtest pipe-D-plane-0: SUCCESS (0.092s)
[93.812613] Starting dynamic subtest: pipe-D-plane-1
[93.902956] Dynamic subtest pipe-D-plane-1: SUCCESS (0.100s)
[93.915317] Starting dynamic subtest: pipe-D-plane-2
[94.003064] Dynamic subtest pipe-D-plane-2: SUCCESS (0.100s)
[94.051911] Subtest plane-lut1d-post-ctm3x4: SUCCESS (1.921s)
[94.162466] [013/156] (931s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[94.257165] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[94.258538] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[94.345249] [014/156] (931s left) syncobj_wait (wait-all-delayed-signal)
[94.379933] Starting subtest: wait-all-delayed-signal
[94.481631] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[94.551559] [015/156] (931s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-fullscreen)
[94.643446] Starting subtest: hdr-2p-scndscrn-spr-indfb-fullscreen
[94.644857] Subtest hdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[94.729637] [016/156] (930s left) gem_exec_capture (userptr)
[94.769759] Starting subtest: userptr
[96.048401] Subtest userptr: SUCCESS (1.278s)
[96.159737] [017/156] (929s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-blt)
[96.254091] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-blt
[96.255448] Subtest psrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[96.356775] [018/156] (929s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt)
[96.449067] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt
[96.450424] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[96.550835] [019/156] (929s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[96.643164] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[96.644535] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[96.741550] [020/156] (929s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[96.776154] Starting subtest: write-cpu-read-wc-unflushed
[96.853638] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.077s)
[96.927657] [021/156] (928s left) gem_userptr_blits (nohangcheck)
[96.981140] Starting subtest: nohangcheck
[97.145422] Subtest nohangcheck: SUCCESS (0.164s)
[97.279879] [022/156] (928s left) kms_content_protection (dp-mst-type-0-hdcp14)
[97.372394] Starting subtest: dp-mst-type-0-hdcp14
[97.373844] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[97.461662] [023/156] (928s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[97.554330] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[97.555769] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[97.652552] [024/156] (928s left) gem_mmap_gtt (cpuset-basic-small-copy)
[97.686844] Starting subtest: cpuset-basic-small-copy
[101.053165] Subtest cpuset-basic-small-copy: SUCCESS (3.366s)
[101.130420] [025/156] (924s left) kms_atomic_interruptible (legacy-cursor)
[101.274992] Starting subtest: legacy-cursor
[101.276496] Starting dynamic subtest: pipe-A-HDMI-A-1
[107.490210] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.214s)
[107.490970] Subtest legacy-cursor: SUCCESS (6.215s)
[107.634226] [026/156] (918s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[107.743432] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[107.744839] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[107.847322] [027/156] (918s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[107.940209] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[107.941652] Subtest fbcpsr-1p-pri-indfb-multidraw: SKIP (0.000s)
[108.023404] [028/156] (918s left) i915_query (query-regions-sanity-check)
[108.056748] Starting subtest: query-regions-sanity-check
[108.067181] Subtest query-regions-sanity-check: SUCCESS (0.010s)
[108.177417] [029/156] (918s left) gem_eio (in-flight-contexts-10ms)
[108.223590] Starting subtest: in-flight-contexts-10ms
[121.894602] Subtest in-flight-contexts-10ms: SUCCESS (13.670s)
[122.059661] [030/156] (904s left) kms_addfb_basic (invalid-get-prop-any)
[122.094032] Starting subtest: invalid-get-prop-any
[122.094811] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[122.255508] [031/156] (904s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[122.347651] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[122.349148] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[122.454643] [032/156] (903s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-move)
[122.546483] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-move
[122.547948] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[122.633776] [033/156] (903s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[122.725463] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[122.726931] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[122.819443] [034/156] (903s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[122.911271] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[122.912695] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[123.016340] [035/156] (903s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[123.056645] Starting subtest: y-tiled-to-vebox-x-tiled
[123.057377] Starting dynamic subtest: smem
[123.154572] Dynamic subtest smem: SUCCESS (0.098s)
[123.155356] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.098s)
[123.326786] [036/156] (903s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-move)
[123.430910] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-move
[123.432269] Subtest psrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[123.521017] [037/156] (903s left) syncobj_timeline (single-wait-available-signaled)
[123.555446] Starting subtest: single-wait-available-signaled
[123.556168] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[123.610952] [038/156] (902s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[123.697771] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[123.717717] Starting dynamic subtest: pipe-A-plane-0
[124.018160] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[124.032963] Starting dynamic subtest: pipe-A-plane-3
[124.259729] Dynamic subtest pipe-A-plane-3: SUCCESS (0.241s)
[124.273688] Starting dynamic subtest: pipe-A-plane-5
[124.484760] Dynamic subtest pipe-A-plane-5: SUCCESS (0.224s)
[124.643091] Starting dynamic subtest: pipe-B-plane-0
[124.885095] Dynamic subtest pipe-B-plane-0: SUCCESS (0.250s)
[124.899698] Starting dynamic subtest: pipe-B-plane-3
[125.126901] Dynamic subtest pipe-B-plane-3: SUCCESS (0.241s)
[125.141872] Starting dynamic subtest: pipe-B-plane-5
[125.360145] Dynamic subtest pipe-B-plane-5: SUCCESS (0.232s)
[125.434123] Subtest pixel-format-y-tiled-modifier-source-clamping: SUCCESS (1.736s)
[125.592537] [039/156] (901s left) kms_force_connector_basic (force-edid)
[125.726265] Starting subtest: force-edid
[125.870422] Subtest force-edid: SUCCESS (0.148s)
[126.021817] [040/156] (900s left) kms_chamelium_color (ctm-negative)
[126.111629] Subtest ctm-negative: SKIP (0.000s)
[126.150792] [041/156] (900s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[126.188271] Starting subtest: rel-constants-invalid-rel-gen5
[126.188996] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[126.272848] [042/156] (900s left) gem_mmap_gtt (big-copy-xy)
[126.306711] Starting subtest: big-copy-XY
[136.420099] Subtest big-copy-XY: SUCCESS (10.113s)
[136.492816] [043/156] (890s left) kms_addfb_basic (addfb25-bad-modifier)
[136.632023] Starting subtest: addfb25-bad-modifier
[136.632844] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[136.717073] [044/156] (890s left) gem_exec_reloc (basic-write-read)
[136.756499] Starting subtest: basic-write-read
[136.762344] Subtest basic-write-read: SUCCESS (0.006s)
[136.874417] [045/156] (889s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[136.968152] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[136.969483] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[137.059736] [046/156] (889s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[137.149437] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[137.187191] [047/156] (889s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[137.274701] Starting subtest: coverage-vs-premult-vs-constant
[137.345845] Starting dynamic subtest: pipe-A-HDMI-A-1
[137.606488] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.261s)
[137.616050] Starting dynamic subtest: pipe-D-HDMI-A-1
[137.897752] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.290s)
[137.898458] Subtest coverage-vs-premult-vs-constant: SUCCESS (0.623s)
[138.072547] [048/156] (888s left) gem_exec_reloc (basic-write-gtt)
[138.113242] Starting subtest: basic-write-gtt
[138.119365] Subtest basic-write-gtt: SUCCESS (0.006s)
[138.230663] [049/156] (888s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[138.324600] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[138.326033] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[138.435416] [050/156] (888s left) gem_ctx_engines (execute-one)
[138.478113] Starting subtest: execute-one
[139.729925] Subtest execute-one: SUCCESS (1.243s)
[139.910090] [051/156] (887s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[140.056469] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[140.163322] [052/156] (886s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[140.255914] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[140.256699] Starting dynamic subtest: pipe-A-valid-mode
[141.452864] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.196s)
[141.453582] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.197s)
[141.590947] [053/156] (885s left) kms_flip (flip-vs-suspend)
[141.683519] Starting subtest: flip-vs-suspend
[141.684267] Starting dynamic subtest: A-HDMI-A1
[286.585363] Dynamic subtest A-HDMI-A1: SUCCESS (11.089s)
[286.587082] Subtest flip-vs-suspend: SUCCESS (11.091s)
[295.694633] [054/156] (740s left) kms_big_fb (linear-16bpp-rotate-90)
[295.798211] Starting subtest: linear-16bpp-rotate-90
[295.888041] Subtest linear-16bpp-rotate-90: SKIP (0.091s)
[295.986606] [055/156] (740s left) kms_vblank (wait-forked)
[296.075057] Starting subtest: wait-forked
[296.075784] Starting dynamic subtest: pipe-A-HDMI-A-1
[297.268172] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.212s)
[297.279992] Starting dynamic subtest: pipe-D-HDMI-A-1
[298.462777] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.209s)
[298.463534] Subtest wait-forked: SUCCESS (2.427s)
[298.644087] [056/156] (737s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs)
[298.736945] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs
[298.737647] Starting dynamic subtest: pipe-A-HDMI-A-1
[298.744301] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[298.752371] Starting dynamic subtest: pipe-B-HDMI-A-1
[298.759176] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[298.767129] Starting dynamic subtest: pipe-C-HDMI-A-1
[298.773780] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[298.781500] Starting dynamic subtest: pipe-D-HDMI-A-1
[298.788242] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[298.789628] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.008s)
[298.989364] [057/156] (737s left) prime_busy (before)
[299.027444] Starting subtest: before
[299.032248] Starting dynamic subtest: rcs0
[299.071470] Dynamic subtest rcs0: SUCCESS (0.039s)
[299.090116] Starting dynamic subtest: bcs0
[299.092425] Dynamic subtest bcs0: SUCCESS (0.018s)
[299.100351] Starting dynamic subtest: vcs0
[299.110424] Dynamic subtest vcs0: SUCCESS (0.018s)
[299.119862] Starting dynamic subtest: vcs1
[299.126018] Dynamic subtest vcs1: SUCCESS (0.015s)
[299.134888] Starting dynamic subtest: vecs0
[299.142371] Dynamic subtest vecs0: SUCCESS (0.016s)
[299.250541] Subtest before: SUCCESS (0.225s)
[299.326959] [058/156] (737s left) gem_exec_reloc (basic-scanout)
[299.425974] Starting subtest: basic-scanout
[299.426661] Starting dynamic subtest: rcs0
[299.588899] Dynamic subtest rcs0: SUCCESS (0.165s)
[299.608561] Starting dynamic subtest: bcs0
[299.712292] Dynamic subtest bcs0: SUCCESS (0.123s)
[299.724829] Starting dynamic subtest: vcs0
[299.827617] Dynamic subtest vcs0: SUCCESS (0.115s)
[299.847086] Starting dynamic subtest: vcs1
[299.951193] Dynamic subtest vcs1: SUCCESS (0.123s)
[299.962536] Starting dynamic subtest: vecs0
[300.066694] Dynamic subtest vecs0: SUCCESS (0.115s)
[300.067678] Subtest basic-scanout: SUCCESS (0.650s)
[300.178073] [059/156] (736s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[300.271515] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[301.519716] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (1.262s)
[301.630955] [060/156] (734s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[301.722401] Starting subtest: invalid-modeset-force-ultra-joiner
[301.723871] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[301.782901] [061/156] (734s left) kms_cursor_crc (cursor-random-128x128)
[301.873985] Starting subtest: cursor-random-128x128
[301.878442] Starting dynamic subtest: pipe-A-HDMI-A-1
[303.775606] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.912s)
[303.815079] Starting dynamic subtest: pipe-D-HDMI-A-1
[305.679559] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.885s)
[305.680296] Subtest cursor-random-128x128: SUCCESS (3.832s)
[305.864635] [062/156] (730s left) syncobj_wait (multi-wait-signaled)
[305.912973] Starting subtest: multi-wait-signaled
[305.913737] Subtest multi-wait-signaled: SUCCESS (0.000s)
[305.985077] [063/156] (730s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[306.079789] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[306.081262] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[306.185368] [064/156] (730s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[306.273613] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[306.274413] Starting dynamic subtest: pipe-A
[307.376204] Dynamic subtest pipe-A: SUCCESS (1.108s)
[307.386205] Starting dynamic subtest: pipe-B
[308.549686] Dynamic subtest pipe-B: SUCCESS (1.177s)
[308.559447] Starting dynamic subtest: pipe-C
[308.767538] Dynamic subtest pipe-C: SUCCESS (0.218s)
[308.780997] Starting dynamic subtest: pipe-D
[308.975013] Dynamic subtest pipe-D: SUCCESS (0.207s)
[308.975760] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (2.715s)
[309.161441] [065/156] (727s left) kms_chamelium_frames (dp-crc-single)
[309.311049] Subtest dp-crc-single: SKIP (0.000s)
[309.404290] [066/156] (727s left) api_intel_bb (intel-bb-blit-y)
[309.444590] Starting subtest: intel-bb-blit-y
[312.671474] Subtest intel-bb-blit-y: SUCCESS (3.203s)
[312.734531] [067/156] (723s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[312.824080] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[312.825976] Starting dynamic subtest: pipe-A-HDMI-A-1
[313.548198] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.721s)
[313.561436] Starting dynamic subtest: pipe-B-HDMI-A-1
[313.940299] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.389s)
[313.956051] Starting dynamic subtest: pipe-C-HDMI-A-1
[314.339817] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.396s)
[314.355657] Starting dynamic subtest: pipe-D-HDMI-A-1
[314.730941] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.388s)
[314.732059] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SUCCESS (1.909s)
[314.952349] [068/156] (721s left) gem_exec_schedule (independent)
[314.996687] Starting subtest: independent
[314.997428] Starting dynamic subtest: rcs0
[315.103112] Dynamic subtest rcs0: SUCCESS (0.106s)
[315.121754] Starting dynamic subtest: bcs0
[315.213022] Dynamic subtest bcs0: SUCCESS (0.108s)
[315.232263] Starting dynamic subtest: vcs0
[315.332860] Dynamic subtest vcs0: SUCCESS (0.118s)
[315.349177] Starting dynamic subtest: vcs1
[315.438960] Dynamic subtest vcs1: SUCCESS (0.104s)
[315.460768] Starting dynamic subtest: vecs0
[315.555090] Dynamic subtest vecs0: SUCCESS (0.113s)
[315.558717] Subtest independent: SUCCESS (0.561s)
[315.853645] [069/156] (720s left) kms_psr (fbc-psr2-cursor-render)
[315.948860] Subtest fbc-psr2-cursor-render: SKIP (0.000s)
[315.987664] [070/156] (720s left) kms_psr (fbc-psr-primary-render)
[316.076929] Subtest fbc-psr-primary-render: SKIP (0.000s)
[316.119852] [071/156] (720s left) i915_pm_freq_api (freq-suspend)
[316.159082] Subtest freq-suspend: SKIP (0.000s)
[316.203400] [072/156] (720s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[316.300717] Starting subtest: yf-tiled-16bpp-rotate-270
[316.302127] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[316.405197] [073/156] (720s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[316.448536] Starting subtest: mixed-tiled-to-y-tiled-ccs
[316.449262] Starting dynamic subtest: smem
[316.557934] Dynamic subtest smem: SUCCESS (0.109s)
[316.558682] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.110s)
[316.731318] [074/156] (720s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[316.822710] Starting subtest: addfb25-y-tiled-small-legacy
[316.823543] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[316.909764] [075/156] (720s left) gem_fence_thrash (bo-copy)
[316.944294] Starting subtest: bo-copy
[318.200761] Subtest bo-copy: SUCCESS (1.251s)
[318.302971] [076/156] (718s left) gem_readwrite (beyond-eob)
[318.339110] Starting subtest: beyond-EOB
[318.340471] Subtest beyond-EOB: SUCCESS (0.000s)
[318.442578] [077/156] (718s left) kms_getfb (getfb-reject-nv12)
[318.534304] Starting subtest: getfb-reject-nv12
[318.536324] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[318.631218] [078/156] (718s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[318.723858] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[318.725270] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[318.834265] [079/156] (718s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[318.873464] Starting subtest: saturated-hostile-nopreempt
[318.874212] Starting dynamic subtest: rcs0
[319.610487] Dynamic subtest rcs0: SUCCESS (0.728s)
[319.621243] Starting dynamic subtest: bcs0
[320.363307] Dynamic subtest bcs0: SUCCESS (0.752s)
[320.374669] Starting dynamic subtest: vcs0
[321.123940] Dynamic subtest vcs0: SUCCESS (0.759s)
[321.134584] Starting dynamic subtest: vcs1
[321.875778] Dynamic subtest vcs1: SUCCESS (0.751s)
[321.887382] Starting dynamic subtest: vecs0
[322.599883] Dynamic subtest vecs0: SUCCESS (0.721s)
[322.600817] Subtest saturated-hostile-nopreempt: SUCCESS (3.722s)
[322.694490] [080/156] (714s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[322.789906] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[322.791384] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[322.894669] [081/156] (714s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-fullscreen)
[322.987876] Starting subtest: fbchdr-1p-primscrn-spr-indfb-fullscreen
[322.989341] Subtest fbchdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[323.086863] [082/156] (714s left) kms_frontbuffer_tracking (fbchdr-2p-pri-indfb-multidraw)
[323.179453] Starting subtest: fbchdr-2p-pri-indfb-multidraw
[323.180906] Subtest fbchdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[323.279478] [083/156] (714s left) dmabuf_sync_file (export-multiwait)
[323.368021] Starting subtest: export-multiwait
[323.370089] Subtest export-multiwait: SUCCESS (0.001s)
[323.431886] [FACT dmabuf_sync_file (export-multiwait)] new: kernel.kmod_is_loaded.vgem: true
[323.433822] [084/156] (713s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-blt)
[323.531332] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-blt
[323.532817] Subtest hdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[323.615618] [085/156] (713s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[323.708990] Starting subtest: psr-rgb101010-draw-mmap-gtt
[323.710428] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[323.816559] [086/156] (713s left) kms_psr (pr-primary-render)
[323.905843] Subtest pr-primary-render: SKIP (0.000s)
[323.951098] [087/156] (713s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[324.042583] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[324.093230] [088/156] (713s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[324.128367] Starting subtest: etime-single-wait-for-submit-submitted
[324.233850] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.105s)
[324.304730] [089/156] (713s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[324.394285] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[324.394997] Starting dynamic subtest: pipe-A-HDMI-A-1
[324.412568] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[324.420785] Starting dynamic subtest: pipe-B-HDMI-A-1
[324.439011] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[324.449252] Starting dynamic subtest: pipe-C-HDMI-A-1
[324.467559] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[324.478431] Starting dynamic subtest: pipe-D-HDMI-A-1
[324.495568] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[324.496885] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.014s)
[324.659076] [090/156] (712s left) i915_drm_fdinfo (busy-hang)
[324.697868] Starting subtest: busy-hang
[324.698596] Starting dynamic subtest: rcs0
[325.722886] Dynamic subtest rcs0: SUCCESS (1.024s)
[325.734307] Starting dynamic subtest: bcs0
[326.752337] Dynamic subtest bcs0: SUCCESS (1.025s)
[326.764825] Starting dynamic subtest: vcs0
[327.779307] Dynamic subtest vcs0: SUCCESS (1.024s)
[327.789268] Starting dynamic subtest: vcs1
[328.809763] Dynamic subtest vcs1: SUCCESS (1.027s)
[328.818997] Starting dynamic subtest: vecs0
[329.834280] Dynamic subtest vecs0: SUCCESS (1.022s)
[329.835309] Subtest busy-hang: SUCCESS (5.135s)
[329.909866] [091/156] (707s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[330.004030] Starting subtest: psrhdr-rgb101010-draw-pwrite
[330.005485] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[330.102919] [092/156] (707s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[330.202259] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[330.530295] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.329s)
[330.673189] [093/156] (707s left) kms_async_flips (invalid-async-flip-atomic)
[330.762723] Starting subtest: invalid-async-flip-atomic
[330.872201] Starting dynamic subtest: pipe-A-HDMI-A-1
[331.026376] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.154s)
[331.145042] Starting dynamic subtest: pipe-B-HDMI-A-1
[331.234237] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.098s)
[331.341511] Starting dynamic subtest: pipe-C-HDMI-A-1
[331.440123] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.107s)
[331.550496] Starting dynamic subtest: pipe-D-HDMI-A-1
[331.654575] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.114s)
[331.656881] Subtest invalid-async-flip-atomic: SUCCESS (0.894s)
[331.872999] [094/156] (705s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[331.970417] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[331.971393] Starting dynamic subtest: pipe-A
[332.435641] Dynamic subtest pipe-A: SUCCESS (0.466s)
[332.446822] Starting dynamic subtest: pipe-B
[332.885696] Dynamic subtest pipe-B: SUCCESS (0.449s)
[332.897116] Starting dynamic subtest: pipe-C
[333.327855] Dynamic subtest pipe-C: SUCCESS (0.441s)
[333.339597] Starting dynamic subtest: pipe-D
[333.769923] Dynamic subtest pipe-D: SUCCESS (0.441s)
[333.770675] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (1.801s)
[333.959989] [095/156] (703s left) syncobj_timeline (device-signal-unordered)
[333.996937] Starting subtest: device-signal-unordered
[333.997632] Subtest device-signal-unordered: SUCCESS (0.001s)
[334.074369] [096/156] (703s left) gem_linear_blits (interruptible)
[334.229965] Starting subtest: interruptible
[337.965566] Subtest interruptible: SUCCESS (3.728s)
[338.074931] [097/156] (699s left) gem_exec_schedule (smoketest-all)
[338.189600] Starting subtest: smoketest-all
[370.458367] Subtest smoketest-all: SUCCESS (32.265s)
[370.748522] [098/156] (667s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[370.847280] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[370.848943] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[370.944264] [099/156] (667s left) i915_pm_rc6_residency (rc6-accuracy)
[370.979521] Starting subtest: rc6-accuracy
[370.980278] Starting dynamic subtest: gt0
[374.153062] Dynamic subtest gt0: SUCCESS (3.170s)
[374.153882] Subtest rc6-accuracy: SUCCESS (3.172s)
[374.216937] [100/156] (663s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[374.310764] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[375.381223] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (1.072s)
[375.511852] [101/156] (662s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[375.615469] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[375.616948] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[375.705889] [102/156] (662s left) prime_vgem (basic-fence-mmap)
[375.778511] Starting subtest: basic-fence-mmap
[375.801422] Subtest basic-fence-mmap: SUCCESS (0.023s)
[375.894038] [103/156] (662s left) kms_lease (lease-invalid-connector)
[375.981200] Starting subtest: lease-invalid-connector
[375.982975] Starting dynamic subtest: pipe-A-HDMI-A-1
[375.983718] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.000s)
[375.992315] Starting dynamic subtest: pipe-B-HDMI-A-1
[375.993017] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.000s)
[376.001289] Starting dynamic subtest: pipe-C-HDMI-A-1
[376.001976] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.000s)
[376.010154] Starting dynamic subtest: pipe-D-HDMI-A-1
[376.010840] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.000s)
[376.011503] Subtest lease-invalid-connector: SUCCESS (0.006s)
[376.060932] [104/156] (662s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[376.161383] Starting subtest: y-tiled-16bpp-rotate-180
[377.280973] Subtest y-tiled-16bpp-rotate-180: SUCCESS (1.121s)
[377.430233] [105/156] (660s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[377.466019] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[377.512621] [106/156] (660s left) kms_pm_dc (dc9-dpms)
[378.608991] Starting subtest: dc9-dpms
[381.905370] Subtest dc9-dpms: SKIP (3.289s)
[382.017609] [107/156] (656s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[382.122130] Starting subtest: x-tiled-64bpp-rotate-180
[383.612073] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.491s)
[383.736591] [108/156] (654s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[383.833686] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[383.835098] Subtest psr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[383.924787] [109/156] (654s left) gem_partial_pwrite_pread (write-snoop)
[383.964015] Starting subtest: write-snoop
[394.229078] Subtest write-snoop: SUCCESS (10.263s)
[394.331012] [110/156] (644s left) gem_exec_whisper (basic-queues-forked-all)
[394.376439] Starting subtest: basic-queues-forked-all
[397.175736] Subtest basic-queues-forked-all: SUCCESS (2.788s)
[397.359938] [111/156] (641s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[397.454577] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[397.455957] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[397.567795] [112/156] (640s left) gem_pread (display)
[397.603580] Starting subtest: display
[398.434895] Subtest display: SUCCESS (0.822s)
[398.528965] [113/156] (640s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[398.621558] Subtest psr2-plane-move-sf-dmg-area: SKIP (0.000s)
[398.669884] [114/156] (639s left) api_intel_allocator (two-level-inception)
[398.707091] Starting subtest: two-level-inception
[404.160679] Subtest two-level-inception: SUCCESS (5.419s)
[404.260586] [115/156] (634s left) gem_exec_whisper (basic-contexts-priority-all)
[404.304578] Starting subtest: basic-contexts-priority-all
[407.850221] Subtest basic-contexts-priority-all: SUCCESS (3.536s)
[408.028380] [116/156] (630s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[408.123197] Starting subtest: flip-vs-cursor-varying-size
[416.896421] Subtest flip-vs-cursor-varying-size: SUCCESS (8.773s)
[417.046211] [117/156] (621s left) gem_madvise (dontneed-before-exec)
[417.082060] Starting subtest: dontneed-before-exec
[417.085274] Subtest dontneed-before-exec: SUCCESS (0.003s)
[417.183878] [118/156] (621s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt)
[417.280251] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt
[417.281714] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[417.380557] [119/156] (621s left) gem_ctx_sseu (engines)
[417.418238] Subtest engines: SKIP (0.000s)
[417.463132] [120/156] (621s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[417.555902] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[417.557367] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[417.652698] [121/156] (621s left) gem_mmap_offset (partial-unmap)
[417.688060] Starting subtest: partial-unmap
[417.688778] Starting dynamic subtest: smem0
[417.782916] Dynamic subtest smem0: SUCCESS (0.095s)
[417.783625] Subtest partial-unmap: SUCCESS (0.096s)
[417.886303] [122/156] (621s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[417.922234] Starting subtest: multi-wait-available-submitted-signaled
[417.922956] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s)
[418.002055] [123/156] (621s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[418.091173] Starting subtest: bo-too-small-due-to-tiling
[418.091907] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[418.202128] [124/156] (620s left) gem_ctx_param (invalid-get-ringsize)
[418.236991] Starting subtest: invalid-get-ringsize
[418.237714] Subtest invalid-get-ringsize: SUCCESS (0.000s)
[418.304211] [125/156] (620s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[418.396310] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[418.397784] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[418.503404] [126/156] (620s left) kms_cursor_crc (cursor-suspend)
[418.593416] Starting subtest: cursor-suspend
[418.594130] Starting dynamic subtest: pipe-A-HDMI-A-1
[451.699180] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.774s)
[451.710349] Starting dynamic subtest: pipe-D-HDMI-A-1
[484.700486] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.771s)
[484.701215] Subtest cursor-suspend: SUCCESS (5.550s)
[492.928207] [127/156] (554s left) kms_pm_rpm (system-suspend-idle)
[494.343570] Starting subtest: system-suspend-idle
[513.939958] Subtest system-suspend-idle: SUCCESS (4.889s)
[520.191895] [128/156] (533s left) kms_plane_lowres (tiling-y)
[520.297222] Starting subtest: tiling-y
[520.299100] Starting dynamic subtest: pipe-A-HDMI-A-1
[525.732744] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (5.450s)
[525.820680] Starting dynamic subtest: pipe-B-HDMI-A-1
[531.035265] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (5.255s)
[531.119808] Starting dynamic subtest: pipe-C-HDMI-A-1
[536.326987] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (5.232s)
[536.411982] Starting dynamic subtest: pipe-D-HDMI-A-1
[541.642785] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (5.248s)
[541.708779] Subtest tiling-y: SUCCESS (21.478s)
[541.909994] [129/156] (511s left) gem_exec_fence (parallel)
[542.086598] Starting subtest: parallel
[542.087436] Starting dynamic subtest: rcs0
[544.245013] Dynamic subtest rcs0: SUCCESS (2.152s)
[544.257606] Starting dynamic subtest: bcs0
[546.391899] Dynamic subtest bcs0: SUCCESS (2.148s)
[546.403639] Starting dynamic subtest: vcs0
[548.538748] Dynamic subtest vcs0: SUCCESS (2.148s)
[548.549309] Starting dynamic subtest: vcs1
[550.689465] Dynamic subtest vcs1: SUCCESS (2.151s)
[550.700460] Starting dynamic subtest: vecs0
[552.838957] Dynamic subtest vecs0: SUCCESS (2.150s)
[552.839687] Subtest parallel: SUCCESS (10.754s)
[553.330688] [130/156] (500s left) gem_madvise (dontneed-before-mmap)
[553.363315] Starting subtest: dontneed-before-mmap
[553.368771] Subtest dontneed-before-mmap: SUCCESS (0.006s)
[553.457876] [131/156] (500s left) kms_vblank (query-forked-busy)
[553.548211] Starting subtest: query-forked-busy
[553.548950] Starting dynamic subtest: pipe-A-HDMI-A-1
[554.826464] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.277s)
[554.841205] Starting dynamic subtest: pipe-D-HDMI-A-1
[556.111526] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.281s)
[556.112336] Subtest query-forked-busy: SUCCESS (2.565s)
[556.303465] [132/156] (497s left) gem_exec_fence (basic-wait)
[556.480533] Starting subtest: basic-wait
[556.481380] Starting dynamic subtest: rcs0
[556.506540] Dynamic subtest rcs0: SUCCESS (0.027s)
[556.524475] Starting dynamic subtest: bcs0
[556.535321] Dynamic subtest bcs0: SUCCESS (0.027s)
[556.550964] Starting dynamic subtest: vcs0
[556.565656] Dynamic subtest vcs0: SUCCESS (0.029s)
[556.582741] Starting dynamic subtest: vcs1
[556.605808] Dynamic subtest vcs1: SUCCESS (0.038s)
[556.624077] Starting dynamic subtest: vecs0
[556.642908] Dynamic subtest vecs0: SUCCESS (0.035s)
[556.644363] Subtest basic-wait: SUCCESS (0.166s)
[557.100189] [133/156] (496s left) gem_exec_params (no-bsd)
[557.138511] Starting subtest: no-bsd
[557.140350] Subtest no-bsd: SUCCESS (0.001s)
[557.244576] [134/156] (496s left) vgem_basic (unload)
[557.277405] Starting subtest: unload
[586.193701] Subtest unload: SUCCESS (28.881s)
[586.276744] [135/156] (467s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[586.370280] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[586.371639] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[586.478449] [136/156] (467s left) perf_pmu (most-busy-idle-check-all)
[586.515393] Starting subtest: most-busy-idle-check-all
[586.516108] Starting dynamic subtest: rcs0
[587.138422] Dynamic subtest rcs0: SUCCESS (0.623s)
[587.152064] Starting dynamic subtest: bcs0
[587.758513] Dynamic subtest bcs0: SUCCESS (0.618s)
[587.771974] Starting dynamic subtest: vcs0
[588.379852] Dynamic subtest vcs0: SUCCESS (0.618s)
[588.391335] Starting dynamic subtest: vcs1
[588.997700] Dynamic subtest vcs1: SUCCESS (0.617s)
[589.010911] Starting dynamic subtest: vecs0
[589.619399] Dynamic subtest vecs0: SUCCESS (0.620s)
[589.622398] Subtest most-busy-idle-check-all: SUCCESS (3.106s)
[589.701778] [137/156] (464s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[589.792748] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[589.801342] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.009s)
[589.877223] [138/156] (463s left) gem_exec_flush (basic-uc-rw-default)
[589.919596] Starting subtest: basic-uc-rw-default
[595.346560] Subtest basic-uc-rw-default: SUCCESS (5.428s)
[595.557959] [139/156] (458s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[595.652432] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[595.653906] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[595.715344] [140/156] (458s left) kms_frontbuffer_tracking (fbcpsrhdr-modesetfrombusy)
[595.808363] Starting subtest: fbcpsrhdr-modesetfrombusy
[595.809832] Subtest fbcpsrhdr-modesetfrombusy: SKIP (0.000s)
[595.889988] [141/156] (458s left) kms_cursor_crc (cursor-size-hints)
[595.980999] Starting subtest: cursor-size-hints
[595.981776] Starting dynamic subtest: pipe-A-HDMI-A-1
[596.296350] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.315s)
[596.307938] Starting dynamic subtest: pipe-D-HDMI-A-1
[596.573134] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.275s)
[596.575133] Subtest cursor-size-hints: SUCCESS (0.595s)
[596.743368] [142/156] (457s left) gem_busy (parallel)
[596.928637] Starting subtest: parallel
[596.929571] Starting dynamic subtest: rcs0
[596.954809] Dynamic subtest rcs0: SUCCESS (0.036s)
[596.967761] Starting dynamic subtest: bcs0
[596.986648] Dynamic subtest bcs0: SUCCESS (0.030s)
[597.002898] Starting dynamic subtest: vcs0
[597.014960] Dynamic subtest vcs0: SUCCESS (0.026s)
[597.029486] Starting dynamic subtest: vcs1
[597.041367] Dynamic subtest vcs1: SUCCESS (0.025s)
[597.054981] Starting dynamic subtest: vecs0
[597.066395] Dynamic subtest vecs0: SUCCESS (0.024s)
[597.067334] Subtest parallel: SUCCESS (0.149s)
[597.315813] [143/156] (456s left) gem_render_copy_redux (normal)
[597.354892] Starting subtest: normal
[597.679400] Subtest normal: SUCCESS (0.316s)
[597.782978] [144/156] (456s left) sysfs_timeslice_duration (timeout)
[597.820494] Starting subtest: timeout
[597.822395] Starting dynamic subtest: vecs0
[598.722763] Dynamic subtest vecs0: SUCCESS (0.901s)
[598.734904] Starting dynamic subtest: vcs0
[599.602670] Dynamic subtest vcs0: SUCCESS (0.878s)
[599.617624] Starting dynamic subtest: bcs0
[600.485761] Dynamic subtest bcs0: SUCCESS (0.881s)
[600.498125] Starting dynamic subtest: rcs0
[601.401740] Dynamic subtest rcs0: SUCCESS (0.914s)
[601.414500] Starting dynamic subtest: vcs1
[602.295898] Dynamic subtest vcs1: SUCCESS (0.892s)
[602.296958] Subtest timeout: SUCCESS (4.478s)
[602.368626] [145/156] (451s left) perf_pmu (busy-idle-check-all)
[602.406002] Starting subtest: busy-idle-check-all
[602.406723] Starting dynamic subtest: rcs0
[603.025528] Dynamic subtest rcs0: SUCCESS (0.619s)
[603.037530] Starting dynamic subtest: bcs0
[603.647534] Dynamic subtest bcs0: SUCCESS (0.621s)
[603.660898] Starting dynamic subtest: vcs0
[604.272453] Dynamic subtest vcs0: SUCCESS (0.622s)
[604.285785] Starting dynamic subtest: vcs1
[604.897456] Dynamic subtest vcs1: SUCCESS (0.623s)
[604.910332] Starting dynamic subtest: vecs0
[605.519975] Dynamic subtest vecs0: SUCCESS (0.621s)
[605.521209] Subtest busy-idle-check-all: SUCCESS (3.117s)
[605.606508] [146/156] (448s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[605.700612] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[605.702109] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[605.787829] [147/156] (448s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[605.880625] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[605.882119] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[605.995067] [148/156] (448s left) gen9_exec_parse (bb-start-param)
[606.033722] Subtest bb-start-param: SKIP (0.000s)
[606.102185] [149/156] (448s left) gem_exec_reloc (basic-cpu-read-noreloc)
[606.141965] Starting subtest: basic-cpu-read-noreloc
[606.144535] Subtest basic-cpu-read-noreloc: SUCCESS (0.003s)
[606.264933] [150/156] (448s left) gem_mmap_offset (ptrace)
[606.300000] Starting subtest: ptrace
[606.301561] Starting dynamic subtest: smem0-gtt
[606.327939] Dynamic subtest smem0-gtt: SUCCESS (0.026s)
[606.338607] Starting dynamic subtest: smem0-wb
[606.345301] Dynamic subtest smem0-wb: SUCCESS (0.016s)
[606.356139] Starting dynamic subtest: smem0-wc
[606.364856] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[606.375822] Starting dynamic subtest: smem0-uc
[606.384857] Dynamic subtest smem0-uc: SUCCESS (0.019s)
[606.385625] Subtest ptrace: SUCCESS (0.086s)
[606.512258] [151/156] (447s left) kms_psr (psr2-primary-blt)
[606.601106] Subtest psr2-primary-blt: SKIP (0.000s)
[606.643671] [152/156] (447s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[606.736623] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[606.738026] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[606.832441] [153/156] (447s left) kms_setmode (basic-clone-single-crtc)
[606.869051] Starting subtest: basic-clone-single-crtc
[606.871218] Subtest basic-clone-single-crtc: SKIP (0.000s)
[606.935444] [154/156] (447s left) gem_exec_balancer (hang)
[607.098184] Starting subtest: hang
[608.387767] Subtest hang: SUCCESS (1.296s)
[608.459902] [155/156] (446s left) kms_feature_discovery (chamelium)
[608.550520] Starting subtest: chamelium
[608.551910] Subtest chamelium: SKIP (0.000s)
[608.625257] [156/156] (445s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[608.713595] Starting subtest: primary-4-tiled-reflect-x-0
[608.767268] Subtest primary-4-tiled-reflect-x-0: SKIP (0.054s)
[608.849031] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_dirtyfb
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_mmap_wc
results: parsing output: 8/ for test: gem_spin_batch
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_color_pipeline
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_exec_capture
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_mmap_wc
results: parsing output: 20/ for test: gem_userptr_blits
results: parsing output: 21/ for test: kms_content_protection
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_mmap_gtt
results: parsing output: 24/ for test: kms_atomic_interruptible
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: i915_query
results: parsing output: 28/ for test: gem_eio
results: parsing output: 29/ for test: kms_addfb_basic
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_render_copy
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: kms_plane
results: parsing output: 38/ for test: kms_force_connector_basic
results: parsing output: 39/ for test: kms_chamelium_color
results: parsing output: 40/ for test: gem_exec_params
results: parsing output: 41/ for test: gem_mmap_gtt
results: parsing output: 42/ for test: kms_addfb_basic
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_psr2_sf
results: parsing output: 46/ for test: kms_plane_alpha_blend
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_ctx_engines
results: parsing output: 50/ for test: kms_chamelium_hpd
results: parsing output: 51/ for test: kms_flip_scaled_crc
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_vblank
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: prime_busy
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_joiner
results: parsing output: 60/ for test: kms_cursor_crc
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_plane_scaling
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: api_intel_bb
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: gem_exec_schedule
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: i915_pm_freq_api
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: gem_render_copy
results: parsing output: 73/ for test: kms_addfb_basic
results: parsing output: 74/ for test: gem_fence_thrash
results: parsing output: 75/ for test: gem_readwrite
results: parsing output: 76/ for test: kms_getfb
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_ctx_persistence
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: dmabuf_sync_file
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: i915_drm_fdinfo
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_async_flips
results: parsing output: 93/ for test: kms_plane_scaling
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: gem_linear_blits
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: i915_pm_rc6_residency
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: prime_vgem
results: parsing output: 102/ for test: kms_lease
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_pipe_b_c_ivb
results: parsing output: 105/ for test: kms_pm_dc
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_partial_pwrite_pread
results: parsing output: 109/ for test: gem_exec_whisper
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_pread
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: api_intel_allocator
results: parsing output: 114/ for test: gem_exec_whisper
results: parsing output: 115/ for test: kms_cursor_legacy
results: parsing output: 116/ for test: gem_madvise
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_ctx_sseu
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_mmap_offset
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: gem_ctx_param
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_cursor_crc
results: parsing output: 126/ for test: kms_pm_rpm
results: parsing output: 127/ for test: kms_plane_lowres
results: parsing output: 128/ for test: gem_exec_fence
results: parsing output: 129/ for test: gem_madvise
results: parsing output: 130/ for test: kms_vblank
results: parsing output: 131/ for test: gem_exec_fence
results: parsing output: 132/ for test: gem_exec_params
results: parsing output: 133/ for test: vgem_basic
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: perf_pmu
results: parsing output: 136/ for test: kms_flip
results: parsing output: 137/ for test: gem_exec_flush
results: parsing output: 138/ for test: kms_cursor_legacy
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_cursor_crc
results: parsing output: 141/ for test: gem_busy
results: parsing output: 142/ for test: gem_render_copy_redux
results: parsing output: 143/ for test: sysfs_timeslice_duration
results: parsing output: 144/ for test: perf_pmu
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: gen9_exec_parse
results: parsing output: 148/ for test: gem_exec_reloc
results: parsing output: 149/ for test: gem_mmap_offset
results: parsing output: 150/ for test: kms_psr
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_setmode
results: parsing output: 153/ for test: gem_exec_balancer
results: parsing output: 154/ for test: kms_feature_discovery
results: parsing output: 155/ for test: kms_rotation_crc
Done.