[61.964570] Initializing watchdogs
[61.964633]   /dev/watchdog0
[61.983078] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[61.993449] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.996951] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.999280] [001/130] (960s left) api_intel_allocator (reopen)
[62.072050] Starting subtest: reopen
[62.096517] Subtest reopen: SUCCESS (0.025s)
[62.285139] [002/130] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[62.488647] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[62.490411] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[62.617590] [003/130] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[62.821598] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[62.823309] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[62.959343] [004/130] (959s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[63.233859] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[63.378993] [005/130] (959s left) gem_mmap_wc (write-wc-read-gtt)
[63.450171] Subtest write-wc-read-gtt: SKIP (0.000s)
[63.533389] [006/130] (959s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[63.747173] Starting subtest: yf-tiled-addfb-size-offset-overflow
[63.749133] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[63.892617] [007/130] (958s left) gem_exec_reloc (basic-cpu-wc-active)
[63.975226] Subtest basic-cpu-wc-active: SKIP (0.000s)
[64.058643] [008/130] (958s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[64.264315] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[64.317615] [009/130] (958s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[64.538123] Starting subtest: extended-modeset-hang-oldfb-with-reset
[64.539981] Starting dynamic subtest: pipe-A
[75.207292] Dynamic subtest pipe-A: SUCCESS (10.668s)
[75.222497] Starting dynamic subtest: pipe-D
[85.894037] Dynamic subtest pipe-D: SUCCESS (10.683s)
[85.895021] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (21.358s)
[86.191064] [010/130] (936s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[86.396747] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[86.398445] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[86.530900] [011/130] (936s left) gem_madvise (dontneed-before-pwrite)
[86.576587] Starting subtest: dontneed-before-pwrite
[86.602370] Subtest dontneed-before-pwrite: SKIP (0.024s)
[86.667409] [012/130] (936s left) kms_pm_dc (dc5-dpms)
[87.875971] Starting subtest: dc5-dpms
[89.183183] Subtest dc5-dpms: SUCCESS (1.307s)
[89.279209] [013/130] (933s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[89.483268] Starting subtest: fbc-rgb565-draw-render
[93.874384] Subtest fbc-rgb565-draw-render: FAIL (4.056s)
[93.944361] [014/130] (929s left) gem_partial_pwrite_pread (reads-snoop)
[94.021304] Subtest reads-snoop: SKIP (0.000s)
[94.080386] [015/130] (929s left) gem_exec_whisper (basic-queues-forked-all)
[94.163161] Starting subtest: basic-queues-forked-all
[116.109483] Subtest basic-queues-forked-all: SUCCESS (21.945s)
[116.339140] [016/130] (907s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[116.540507] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[116.542244] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[116.653707] [017/130] (906s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[116.849568] Starting subtest: flip-vs-cursor-busy-crc-legacy
[117.550071] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.699s)
[117.815809] [018/130] (905s left) fbdev (write)
[117.870599] Starting subtest: write
[119.112054] Subtest write: SUCCESS (1.241s)
[119.178724] [019/130] (904s left) gem_softpin (invalid)
[119.262646] Starting subtest: invalid
[119.272789] Subtest invalid: SUCCESS (0.010s)
[119.431601] [020/130] (904s left) gem_create (create-invalid-size)
[119.500974] Starting subtest: create-invalid-size
[119.501939] Subtest create-invalid-size: SUCCESS (0.000s)
[119.760669] [021/130] (903s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[119.963496] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[119.965193] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[120.076149] [022/130] (903s left) kms_legacy_colorkey (basic)
[120.278179] Starting subtest: basic
[120.279130] Starting dynamic subtest: pipe-A
[120.336549] Dynamic subtest pipe-A: SUCCESS (0.058s)
[120.349036] Starting dynamic subtest: pipe-B
[120.350158] Dynamic subtest pipe-B: SUCCESS (0.000s)
[120.363564] Starting dynamic subtest: pipe-C
[120.364600] Dynamic subtest pipe-C: SUCCESS (0.000s)
[120.377573] Starting dynamic subtest: pipe-D
[120.378798] Dynamic subtest pipe-D: SUCCESS (0.000s)
[120.380008] Subtest basic: SUCCESS (0.060s)
[120.433935] [023/130] (903s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[120.638180] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[120.639181] Starting dynamic subtest: pipe-A-DP-3
[159.693746] Dynamic subtest pipe-A-DP-3: SUCCESS (8.727s)
[159.710454] Starting dynamic subtest: pipe-B-DP-3
[198.560397] Dynamic subtest pipe-B-DP-3: SUCCESS (7.541s)
[198.570989] Starting dynamic subtest: pipe-C-DP-3
[236.989773] Dynamic subtest pipe-C-DP-3: SUCCESS (7.653s)
[237.005780] Starting dynamic subtest: pipe-D-DP-3
[276.186329] Dynamic subtest pipe-D-DP-3: SUCCESS (7.680s)
[276.187373] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SUCCESS (31.606s)
[276.558544] [024/130] (871s left) kms_color (legacy-gamma-reset)
[276.757065] Starting subtest: legacy-gamma-reset
[276.758021] Starting dynamic subtest: pipe-A-DP-3
[276.990922] Dynamic subtest pipe-A-DP-3: SUCCESS (0.234s)
[277.002465] Starting dynamic subtest: pipe-B-DP-3
[277.009587] Dynamic subtest pipe-B-DP-3: SUCCESS (0.018s)
[277.020037] Starting dynamic subtest: pipe-C-DP-3
[277.027485] Dynamic subtest pipe-C-DP-3: SUCCESS (0.018s)
[277.037780] Starting dynamic subtest: pipe-D-DP-3
[277.044792] Dynamic subtest pipe-D-DP-3: SUCCESS (0.017s)
[277.045729] Subtest legacy-gamma-reset: SUCCESS (0.288s)
[277.266545] [025/130] (870s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[277.477254] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[277.479251] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[277.579426] [026/130] (870s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[277.784028] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[277.799447] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.015s)
[277.884938] [027/130] (870s left) kms_selftest (drm_damage_helper)
[277.933354] Starting subtest: drm_damage_helper
[277.946253] Starting dynamic subtest: drm_test_damage_iter_no_damage
[278.049418] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.103s)
[278.082417] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[278.171401] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.120s)
[278.183450] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[278.283679] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.109s)
[278.325428] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[278.407987] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.123s)
[278.448044] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[278.523984] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.114s)
[278.565755] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[278.631768] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.106s)
[278.671439] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[278.746563] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.115s)
[278.788050] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[278.876225] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.126s)
[278.918357] Starting dynamic subtest: drm_test_damage_iter_single_damage
[278.968769] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.091s)
[279.009651] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[279.077022] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.106s)
[279.118654] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[279.162853] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.087s)
[279.190548] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[279.281006] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.115s)
[279.322426] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[279.360644] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.080s)
[279.388005] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[279.465357] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.104s)
[279.484042] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[279.560162] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.092s)
[279.599160] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[279.668971] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.107s)
[279.680763] Starting dynamic subtest: drm_test_damage_iter_damage
[279.760298] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.093s)
[279.771808] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[279.839152] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.077s)
[279.877038] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[279.977296] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.138s)
[279.989099] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[280.101380] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.121s)
[280.138292] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[280.175540] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.074s)
[280.176625] Subtest drm_damage_helper: SUCCESS (2.241s)
[280.367855] [028/130] (867s left) gem_softpin (softpin)
[280.459530] Starting subtest: softpin
[312.890220] Subtest softpin: SUCCESS (32.429s)
[313.038120] [029/130] (835s left) gem_exec_params (rel-constants-invalid-ring)
[313.114270] Starting subtest: rel-constants-invalid-ring
[313.115213] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[313.292335] [030/130] (834s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[313.362395] Starting subtest: etime-multi-wait-all-submitted-signaled
[313.469689] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[313.616477] [031/130] (834s left) gem_eio (unwedge-stress)
[313.915314] Starting subtest: unwedge-stress
[355.890318] Subtest unwedge-stress: SUCCESS (41.974s)
[356.222473] [FACT gem_eio (unwedge-stress)] new: kernel.kmod_is_loaded.vgem: true
[356.224941] [032/130] (792s left) kms_cursor_crc (cursor-size-change)
[356.434168] Starting subtest: cursor-size-change
[356.436071] Starting dynamic subtest: pipe-A-DP-3
[357.292236] Dynamic subtest pipe-A-DP-3: SUCCESS (0.855s)
[357.318929] Starting dynamic subtest: pipe-D-DP-3
[358.123166] Dynamic subtest pipe-D-DP-3: SUCCESS (0.829s)
[358.124080] Subtest cursor-size-change: SUCCESS (1.689s)
[358.664531] [033/130] (789s left) gem_ctx_persistence (legacy-engines-hang)
[358.744243] Starting subtest: legacy-engines-hang
[358.745234] Starting dynamic subtest: render
[358.857467] Dynamic subtest render: SUCCESS (0.113s)
[358.879670] Starting dynamic subtest: bsd1
[358.954066] Dynamic subtest bsd1: SUCCESS (0.095s)
[358.997723] Starting dynamic subtest: bsd2
[359.013105] Dynamic subtest bsd2: SUCCESS (0.057s)
[359.028373] Starting dynamic subtest: blt
[359.112353] Dynamic subtest blt: SUCCESS (0.097s)
[359.157726] Starting dynamic subtest: vebox
[359.182286] Dynamic subtest vebox: SUCCESS (0.068s)
[359.183484] Subtest legacy-engines-hang: SUCCESS (0.438s)
[359.368731] [034/130] (789s left) gem_flink_basic (double-flink)
[359.452043] Starting subtest: double-flink
[359.453008] Subtest double-flink: SUCCESS (0.000s)
[359.615719] [035/130] (788s left) perf_pmu (idle-no-semaphores)
[359.687107] Starting subtest: idle-no-semaphores
[359.688050] Starting dynamic subtest: rcs0
[360.264240] Dynamic subtest rcs0: SUCCESS (0.576s)
[360.278110] Starting dynamic subtest: bcs0
[360.777205] Dynamic subtest bcs0: SUCCESS (0.511s)
[360.791123] Starting dynamic subtest: vcs0
[361.290256] Dynamic subtest vcs0: SUCCESS (0.511s)
[361.304143] Starting dynamic subtest: vcs1
[361.803148] Dynamic subtest vcs1: SUCCESS (0.511s)
[361.817062] Starting dynamic subtest: vecs0
[362.317222] Dynamic subtest vecs0: SUCCESS (0.512s)
[362.330637] Starting dynamic subtest: vecs1
[362.833327] Dynamic subtest vecs1: SUCCESS (0.514s)
[362.847038] Starting dynamic subtest: ccs0
[363.346493] Dynamic subtest ccs0: SUCCESS (0.511s)
[363.348521] Subtest idle-no-semaphores: SUCCESS (3.659s)
[363.490606] [036/130] (785s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[363.710588] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[363.712270] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[363.850727] [037/130] (784s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[363.945279] Starting subtest: x-tiled-to-vebox-yf-tiled
[363.947346] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[364.155502] [038/130] (784s left) kms_flip (2x-flip-vs-suspend-interruptible)
[364.372451] Starting subtest: 2x-flip-vs-suspend-interruptible
[364.387822] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.014s)
[364.487630] [039/130] (784s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[364.816024] Starting subtest: parallel-dmabuf-import-out-fence
[366.874045] Subtest parallel-dmabuf-import-out-fence: SUCCESS (2.058s)
[367.000431] [040/130] (781s left) kms_pm_rpm (basic-rte)
[367.049037] Starting subtest: basic-rte
[369.685181] Subtest basic-rte: SUCCESS (2.634s)
[370.362330] [041/130] (778s left) kms_setmode (basic-clone-single-crtc)
[370.444049] Starting subtest: basic-clone-single-crtc
[370.447083] Subtest basic-clone-single-crtc: SKIP (0.000s)
[370.590599] [042/130] (778s left) api_intel_allocator (fork-simple-once)
[370.669110] Starting subtest: fork-simple-once
[370.709752] Subtest fork-simple-once: SUCCESS (0.041s)
[370.893550] [043/130] (778s left) gem_exec_balancer (bonded-semaphore)
[370.979465] Starting subtest: bonded-semaphore
[370.981641] Subtest bonded-semaphore: SKIP (0.000s)
[371.360254] [044/130] (777s left) gem_ctx_create (basic-files)
[371.448698] Starting subtest: basic-files
[373.461773] Subtest basic-files: SUCCESS (2.013s)
[373.879320] [045/130] (775s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[374.106776] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[374.775635] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.669s)
[375.025309] [046/130] (774s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[375.249271] Starting subtest: x-tiled-16bpp-rotate-180
[376.909982] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.660s)
[377.042317] [047/130] (772s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[377.251653] Starting subtest: flip-vs-cursor-crc-legacy
[377.796825] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.544s)
[378.087345] [048/130] (771s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[378.299911] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[378.301674] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[378.431058] [049/130] (771s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[378.649345] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[378.651551] Starting dynamic subtest: pipe-A-DP-3
[378.660972] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[378.673353] Starting dynamic subtest: pipe-B-DP-3
[378.682161] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[378.694069] Starting dynamic subtest: pipe-C-DP-3
[378.702489] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[378.714529] Starting dynamic subtest: pipe-D-DP-3
[378.725242] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[378.727080] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SKIP (0.006s)
[379.103801] [050/130] (770s left) kms_flip (flip-vs-panning)
[379.323046] Starting subtest: flip-vs-panning
[379.327569] Starting dynamic subtest: A-DP3
[387.190779] Dynamic subtest A-DP3: SUCCESS (7.861s)
[387.231476] Starting dynamic subtest: B-DP3
[394.993474] Dynamic subtest B-DP3: SUCCESS (7.800s)
[395.006559] Starting dynamic subtest: C-DP3
[402.766309] Dynamic subtest C-DP3: SUCCESS (7.770s)
[402.807583] Starting dynamic subtest: D-DP3
[410.571215] Dynamic subtest D-DP3: SUCCESS (7.802s)
[410.573368] Subtest flip-vs-panning: SUCCESS (31.248s)
[410.925564] [051/130] (738s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[411.149160] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[411.150032] Starting dynamic subtest: pipe-A
[411.906780] Dynamic subtest pipe-A: SKIP (0.756s)
[411.918724] Starting dynamic subtest: pipe-B
[413.052264] Dynamic subtest pipe-B: SKIP (1.144s)
[413.064192] Starting dynamic subtest: pipe-C
[414.197422] Dynamic subtest pipe-C: SKIP (1.144s)
[414.209359] Starting dynamic subtest: pipe-D
[415.341736] Dynamic subtest pipe-D: SKIP (1.144s)
[415.343135] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (4.191s)
[415.475551] [052/130] (734s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[415.691904] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[415.693602] Subtest psr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[415.810415] [053/130] (733s left) kms_dp_aux_dev
[416.459410] [054/130] (733s left) kms_chamelium_color (ctm-0-50)
[416.668016] Subtest ctm-0-50: SKIP (0.000s)
[416.738644] [055/130] (733s left) gem_exec_params (larger-than-life-batch)
[416.822543] Starting subtest: larger-than-life-batch
[424.716107] Subtest larger-than-life-batch: SUCCESS (7.893s)
[425.126632] [056/130] (724s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[425.409045] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[425.410906] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[425.543478] [057/130] (724s left) kms_cursor_legacy (single-move)
[425.758723] Starting subtest: single-move
[425.759664] Starting dynamic subtest: pipe-A
[431.142084] Dynamic subtest pipe-A: SUCCESS (5.381s)
[431.155063] Starting dynamic subtest: pipe-B
[436.524421] Dynamic subtest pipe-B: SUCCESS (5.381s)
[436.537253] Starting dynamic subtest: pipe-C
[441.907046] Dynamic subtest pipe-C: SUCCESS (5.381s)
[441.919633] Starting dynamic subtest: pipe-D
[447.289387] Dynamic subtest pipe-D: SUCCESS (5.381s)
[447.302444] Starting dynamic subtest: all-pipes
[452.672414] Dynamic subtest all-pipes: SUCCESS (5.382s)
[452.673329] Subtest single-move: SUCCESS (26.912s)
[452.762639] [058/130] (697s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[452.978808] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[453.068756] [059/130] (697s left) kms_content_protection (lic-type-1)
[453.339332] Starting subtest: lic-type-1
[453.933260] Subtest lic-type-1: SKIP (0.590s)
[454.316430] [060/130] (695s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[454.543933] Starting subtest: 4-tiled-32bpp-rotate-90
[454.598514] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.054s)
[454.797056] [061/130] (695s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[455.018309] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[455.020168] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[455.155078] [062/130] (695s left) gem_exec_schedule (u-semaphore-noskip)
[455.256371] Starting subtest: u-semaphore-noskip
[455.984648] Subtest u-semaphore-noskip: SUCCESS (0.728s)
[456.353017] [063/130] (694s left) gem_mmap_gtt (basic-copy)
[456.432485] Subtest basic-copy: SKIP (0.000s)
[456.511985] [064/130] (693s left) i915_pm_rps (engine-order)
[456.599525] Starting subtest: engine-order
[467.330960] Subtest engine-order: SUCCESS (10.730s)
[467.515872] [065/130] (682s left) kms_chamelium_frames (dp-crc-multiple)
[467.811481] Subtest dp-crc-multiple: SKIP (0.000s)
[467.967459] [066/130] (682s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[468.048928] Starting subtest: multi-wait-all-for-submit-available-submitted
[468.049889] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[468.180570] [067/130] (682s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[468.388392] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[468.390741] Starting dynamic subtest: pipe-A-DP-3
[468.399785] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[468.412659] Starting dynamic subtest: pipe-B-DP-3
[468.421642] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[468.434155] Starting dynamic subtest: pipe-C-DP-3
[468.442795] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[468.455438] Starting dynamic subtest: pipe-D-DP-3
[468.466227] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[468.468229] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SKIP (0.006s)
[468.842655] [068/130] (681s left) perf (non-zero-reason)
[469.211076] Starting subtest: non-zero-reason
[469.211996] Starting dynamic subtest: 0-rcs0
[472.286260] Dynamic subtest 0-rcs0: FAIL (2.746s)
[472.287915] Subtest non-zero-reason: FAIL (2.747s)
[472.362776] [069/130] (678s left) kms_psr (psr-primary-mmap-cpu)
[472.578181] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[472.643186] [070/130] (678s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[472.858923] Starting subtest: psr-rgb101010-draw-pwrite
[472.860761] Subtest psr-rgb101010-draw-pwrite: SKIP (0.000s)
[473.018364] [071/130] (677s left) gem_mmap_wc (write-read)
[473.099433] Subtest write-read: SKIP (0.000s)
[473.189280] [072/130] (677s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[473.403037] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[473.404977] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[473.541203] [073/130] (677s left) syncobj_timeline (wait-zero-handles)
[473.618794] Starting subtest: wait-zero-handles
[473.620097] Subtest wait-zero-handles: SUCCESS (0.000s)
[473.784684] [074/130] (677s left) kms_psr (fbc-psr2-cursor-plane-move)
[473.998295] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[474.061619] [075/130] (677s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[474.277831] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[474.279496] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[474.402229] [076/130] (676s left) dumb_buffer (invalid-bpp)
[474.482305] Starting subtest: invalid-bpp
[474.483313] Subtest invalid-bpp: SUCCESS (0.000s)
[474.598245] [077/130] (676s left) prime_vgem (basic-fence-mmap)
[474.702227] Starting subtest: basic-fence-mmap
[474.704093] Subtest basic-fence-mmap: SKIP (0.000s)
[474.890701] [078/130] (676s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[475.104698] Starting subtest: disable-crc-after-crtc
[475.107302] Starting dynamic subtest: pipe-A-DP-3
[475.836365] Dynamic subtest pipe-A-DP-3: SUCCESS (0.730s)
[475.859905] Starting dynamic subtest: pipe-B-DP-3
[476.443304] Dynamic subtest pipe-B-DP-3: SUCCESS (0.604s)
[476.460744] Starting dynamic subtest: pipe-C-DP-3
[477.093096] Dynamic subtest pipe-C-DP-3: SUCCESS (0.648s)
[477.109952] Starting dynamic subtest: pipe-D-DP-3
[477.720274] Dynamic subtest pipe-D-DP-3: SUCCESS (0.625s)
[477.721303] Subtest disable-crc-after-crtc: SUCCESS (2.616s)
[477.980630] [079/130] (673s left) gem_workarounds (basic-read-context)
[478.075225] Starting subtest: basic-read-context
[478.107677] Subtest basic-read-context: SUCCESS (0.032s)
[478.274464] [080/130] (673s left) prime_busy (hang)
[478.359668] Starting subtest: hang
[478.362049] Starting dynamic subtest: rcs0
[504.442315] Dynamic subtest rcs0: SUCCESS (26.078s)
[504.455855] Starting dynamic subtest: bcs0
[511.586676] Dynamic subtest bcs0: SUCCESS (7.145s)
[511.600519] Starting dynamic subtest: vcs0
[518.647080] Dynamic subtest vcs0: SUCCESS (7.058s)
[518.660053] Starting dynamic subtest: vcs1
[525.611463] Dynamic subtest vcs1: SUCCESS (6.963s)
[525.625054] Starting dynamic subtest: vecs0
[532.599143] Dynamic subtest vecs0: SUCCESS (6.986s)
[532.612712] Starting dynamic subtest: vecs1
[539.623901] Dynamic subtest vecs1: SUCCESS (7.025s)
[539.637207] Starting dynamic subtest: ccs0
[553.465029] Dynamic subtest ccs0: SUCCESS (13.840s)
[553.466149] Subtest hang: SUCCESS (75.104s)
[553.591797] [081/130] (598s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[553.805048] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[556.787387] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (2.982s)
[557.013297] [082/130] (594s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[557.092946] Starting subtest: wait-all-for-submit-delayed-submit
[557.196704] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[557.357727] [083/130] (594s left) kms_addfb_basic (unused-pitches)
[557.435268] Starting subtest: unused-pitches
[557.436193] Subtest unused-pitches: SUCCESS (0.000s)
[557.724883] [084/130] (594s left) gem_exec_schedule (pi-common)
[557.943521] Starting subtest: pi-common
[557.944486] Starting dynamic subtest: rcs0
[558.040177] Dynamic subtest rcs0: SUCCESS (0.096s)
[558.073850] Starting dynamic subtest: bcs0
[558.150962] Dynamic subtest bcs0: SUCCESS (0.108s)
[558.165643] Starting dynamic subtest: vcs0
[558.238707] Dynamic subtest vcs0: SUCCESS (0.087s)
[558.259929] Starting dynamic subtest: vcs1
[558.329217] Dynamic subtest vcs1: SUCCESS (0.089s)
[558.351277] Starting dynamic subtest: vecs0
[558.413106] Dynamic subtest vecs0: SUCCESS (0.082s)
[558.438444] Starting dynamic subtest: vecs1
[558.501451] Dynamic subtest vecs1: SUCCESS (0.087s)
[558.519682] Starting dynamic subtest: ccs0
[558.580785] Dynamic subtest ccs0: SUCCESS (0.078s)
[558.581811] Subtest pi-common: SUCCESS (0.638s)
[558.894988] [085/130] (593s left) kms_vrr (flipline)
[559.110660] Starting subtest: flipline
[559.113545] Subtest flipline: SKIP (0.000s)
[559.236756] [086/130] (592s left) gem_ringfill (legacy-basic)
[559.682484] Starting subtest: legacy-basic
[559.687794] Starting dynamic subtest: default
[559.794415] Dynamic subtest default: SUCCESS (0.110s)
[559.811953] Starting dynamic subtest: render
[559.838432] Dynamic subtest render: SUCCESS (0.042s)
[559.859471] Starting dynamic subtest: bsd
[559.879565] Dynamic subtest bsd: SUCCESS (0.040s)
[559.900918] Starting dynamic subtest: bsd1
[559.920782] Dynamic subtest bsd1: SUCCESS (0.040s)
[559.942052] Starting dynamic subtest: bsd2
[559.963438] Dynamic subtest bsd2: SUCCESS (0.041s)
[559.984761] Starting dynamic subtest: blt
[560.006330] Dynamic subtest blt: SUCCESS (0.041s)
[560.023721] Starting dynamic subtest: vebox
[560.049445] Dynamic subtest vebox: SUCCESS (0.041s)
[560.050586] Subtest legacy-basic: SUCCESS (0.368s)
[560.238754] [087/130] (591s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[560.447443] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[560.449796] Starting dynamic subtest: pipe-A-DP-3
[560.458758] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[560.471802] Starting dynamic subtest: pipe-B-DP-3
[560.480456] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[560.493266] Starting dynamic subtest: pipe-C-DP-3
[560.502119] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[560.515210] Starting dynamic subtest: pipe-D-DP-3
[560.526262] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[560.528414] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[560.887061] [088/130] (591s left) i915_query (query-regions-garbage-items)
[560.964281] Starting subtest: query-regions-garbage-items
[560.965448] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[561.137491] [089/130] (591s left) i915_pm_rpm (gem-evict-pwrite)
[563.015157] Starting subtest: gem-evict-pwrite
[563.018991] Subtest gem-evict-pwrite: SKIP (0.000s)
[563.385250] [090/130] (588s left) gem_mmap_offset (mmap-boundaries)
[563.466255] Starting subtest: mmap-boundaries
[563.467272] Starting dynamic subtest: lmem0
[563.560761] Dynamic subtest lmem0: SUCCESS (0.094s)
[563.574350] Starting dynamic subtest: smem0
[564.859932] Dynamic subtest smem0: SUCCESS (1.298s)
[564.860979] Subtest mmap-boundaries: SUCCESS (1.394s)
[565.185794] [091/130] (587s left) gen9_exec_parse (bb-chained)
[565.276098] Subtest bb-chained: SKIP (0.000s)
[565.378470] [092/130] (587s left) sysfs_timeslice_duration (duration)
[565.466975] Starting subtest: duration
[565.467963] Starting dynamic subtest: vecs0
[578.637953] Dynamic subtest vecs0: SUCCESS (13.169s)
[578.654861] Starting dynamic subtest: ccs0
[591.788807] Dynamic subtest ccs0: SUCCESS (13.146s)
[591.805090] Starting dynamic subtest: vcs0
[604.936847] Dynamic subtest vcs0: SUCCESS (13.143s)
[604.953404] Starting dynamic subtest: bcs0
[618.058221] Dynamic subtest bcs0: SUCCESS (13.118s)
[618.072473] Starting dynamic subtest: vecs1
[631.195573] Dynamic subtest vecs1: SUCCESS (13.135s)
[631.212935] Starting dynamic subtest: rcs0
[644.349811] Dynamic subtest rcs0: SUCCESS (13.150s)
[644.366639] Starting dynamic subtest: vcs1
[657.457755] Dynamic subtest vcs1: SUCCESS (13.103s)
[657.460070] Subtest duration: SUCCESS (91.990s)
[657.593569] [093/130] (494s left) kms_psr (psr2-primary-render)
[657.801242] Subtest psr2-primary-render: SKIP (0.000s)
[657.867818] [094/130] (494s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[658.085362] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[658.100631] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.014s)
[658.221909] [095/130] (494s left) gem_exec_fence (syncobj-channel)
[658.747503] Starting subtest: syncobj-channel
[659.771072] Subtest syncobj-channel: SUCCESS (1.022s)
[660.155626] [096/130] (492s left) api_intel_bb (object-noreloc-keep-cache-simple)
[660.241241] Starting subtest: object-noreloc-keep-cache-simple
[660.267096] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.026s)
[660.459174] [097/130] (492s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[660.536696] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[660.537754] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[660.668921] [098/130] (492s left) syncobj_timeline (etime-single-wait-all-submitted)
[660.748960] Starting subtest: etime-single-wait-all-submitted
[660.853420] Subtest etime-single-wait-all-submitted: SUCCESS (0.103s)
[661.007695] [099/130] (491s left) gem_ctx_persistence (hostile)
[661.093282] Starting subtest: hostile
[671.247461] Subtest hostile: SUCCESS (10.149s)
[671.394844] [100/130] (481s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[671.614927] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[671.616717] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[671.778253] [101/130] (481s left) kms_cursor_crc (cursor-onscreen-128x42)
[672.134441] Starting subtest: cursor-onscreen-128x42
[672.139759] Starting dynamic subtest: pipe-A-DP-3
[674.784289] Dynamic subtest pipe-A-DP-3: SUCCESS (2.645s)
[674.831837] Starting dynamic subtest: pipe-D-DP-3
[677.393852] Dynamic subtest pipe-D-DP-3: SUCCESS (2.575s)
[677.394941] Subtest cursor-onscreen-128x42: SUCCESS (5.259s)
[677.807902] [102/130] (475s left) kms_atomic_interruptible (legacy-setmode)
[678.021330] Starting subtest: legacy-setmode
[678.022223] Starting dynamic subtest: pipe-A-DP-3
[684.367008] Dynamic subtest pipe-A-DP-3: SUCCESS (6.344s)
[684.368046] Subtest legacy-setmode: SUCCESS (6.346s)
[684.610189] [103/130] (468s left) kms_cursor_crc (cursor-sliding-256x85)
[685.018786] Starting subtest: cursor-sliding-256x85
[685.024790] Starting dynamic subtest: pipe-A-DP-3
[689.390066] Dynamic subtest pipe-A-DP-3: SUCCESS (4.363s)
[689.441654] Starting dynamic subtest: pipe-D-DP-3
[693.705311] Dynamic subtest pipe-D-DP-3: SUCCESS (4.276s)
[693.706282] Subtest cursor-sliding-256x85: SUCCESS (8.687s)
[694.071383] [104/130] (459s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[694.287445] Starting subtest: x-tiled-64bpp-rotate-90
[694.352572] Subtest x-tiled-64bpp-rotate-90: SKIP (0.064s)
[694.556004] [105/130] (458s left) gem_exec_fence (nb-await)
[694.797895] Starting subtest: nb-await
[694.799530] Starting dynamic subtest: rcs0
[694.895927] Dynamic subtest rcs0: SUCCESS (0.097s)
[694.910518] Starting dynamic subtest: bcs0
[694.958761] Dynamic subtest bcs0: SUCCESS (0.062s)
[694.988683] Starting dynamic subtest: vcs0
[695.028136] Dynamic subtest vcs0: SUCCESS (0.067s)
[695.047159] Starting dynamic subtest: vcs1
[695.092984] Dynamic subtest vcs1: SUCCESS (0.062s)
[695.119236] Starting dynamic subtest: vecs0
[695.159979] Dynamic subtest vecs0: SUCCESS (0.065s)
[695.178651] Starting dynamic subtest: vecs1
[695.224749] Dynamic subtest vecs1: SUCCESS (0.062s)
[695.248433] Starting dynamic subtest: ccs0
[695.291190] Dynamic subtest ccs0: SUCCESS (0.064s)
[695.293379] Subtest nb-await: SUCCESS (0.495s)
[696.037897] [106/130] (457s left) perf (create-destroy-userspace-config)
[696.237951] Starting subtest: create-destroy-userspace-config
[696.266407] Subtest create-destroy-userspace-config: SUCCESS (0.028s)
[696.316540] [107/130] (457s left) kms_psr (pr-cursor-plane-onoff)
[696.523204] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[696.579444] [108/130] (456s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[696.792824] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[696.794721] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[696.920617] [109/130] (456s left) gem_pread (snoop)
[697.000401] Subtest snoop: SKIP (0.000s)
[697.089223] [110/130] (456s left) drm_fdinfo (memory-info-idle)
[697.159314] Starting subtest: memory-info-idle
[697.160268] Starting dynamic subtest: lmem0
[697.248102] Dynamic subtest lmem0: SUCCESS (0.088s)
[697.262227] Starting dynamic subtest: smem0
[697.297994] Dynamic subtest smem0: SUCCESS (0.049s)
[697.299134] Subtest memory-info-idle: SUCCESS (0.139s)
[697.466937] [111/130] (456s left) syncobj_timeline (single-wait-all-signaled)
[697.548039] Starting subtest: single-wait-all-signaled
[697.549009] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[697.682549] [112/130] (456s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[697.895754] Starting subtest: 2x-cursor-vs-flip-legacy
[697.899117] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.002s)
[697.986092] [113/130] (455s left) gem_huc_copy (huc-copy)
[698.092405] Starting subtest: huc-copy
[698.098663] Subtest huc-copy: SUCCESS (0.006s)
[698.329330] [114/130] (455s left) gem_mmap_gtt (hang-user)
[698.405385] Subtest hang-user: SKIP (0.000s)
[698.479406] [115/130] (455s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[698.563392] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[698.649825] [116/130] (455s left) kms_addfb_basic (basic-y-tiled-legacy)
[698.727605] Starting subtest: basic-y-tiled-legacy
[698.729328] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[698.984043] [117/130] (455s left) gem_cs_tlb (engines)
[699.069667] Starting subtest: engines
[699.070613] Starting dynamic subtest: rcs0
[701.223909] Dynamic subtest rcs0: SUCCESS (2.153s)
[701.237399] Starting dynamic subtest: bcs0
[703.379118] Dynamic subtest bcs0: SUCCESS (2.154s)
[703.392666] Starting dynamic subtest: vcs0
[705.531809] Dynamic subtest vcs0: SUCCESS (2.152s)
[705.545552] Starting dynamic subtest: vcs1
[707.684353] Dynamic subtest vcs1: SUCCESS (2.151s)
[707.697461] Starting dynamic subtest: vecs0
[709.836473] Dynamic subtest vecs0: SUCCESS (2.151s)
[709.849973] Starting dynamic subtest: vecs1
[711.989507] Dynamic subtest vecs1: SUCCESS (2.152s)
[712.003124] Starting dynamic subtest: ccs0
[714.142042] Dynamic subtest ccs0: SUCCESS (2.151s)
[714.143056] Subtest engines: SUCCESS (15.071s)
[714.276428] [118/130] (439s left) kms_universal_plane (universal-plane-sanity)
[714.489511] Starting subtest: universal-plane-sanity
[714.491921] Starting dynamic subtest: pipe-A-DP-3
[715.110081] Dynamic subtest pipe-A-DP-3: SUCCESS (0.619s)
[715.130048] Starting dynamic subtest: pipe-B-DP-3
[715.626178] Dynamic subtest pipe-B-DP-3: SUCCESS (0.513s)
[715.647552] Starting dynamic subtest: pipe-C-DP-3
[716.109851] Dynamic subtest pipe-C-DP-3: SUCCESS (0.481s)
[716.129171] Starting dynamic subtest: pipe-D-DP-3
[716.578277] Dynamic subtest pipe-D-DP-3: SUCCESS (0.467s)
[716.579176] Subtest universal-plane-sanity: SUCCESS (2.089s)
[716.817961] [119/130] (437s left) syncobj_wait (multi-wait-signaled)
[716.897818] Starting subtest: multi-wait-signaled
[716.898784] Subtest multi-wait-signaled: SUCCESS (0.000s)
[717.042257] [120/130] (437s left) kms_invalid_mode (overflow-vrefresh)
[717.251040] Starting subtest: overflow-vrefresh
[717.251992] Starting dynamic subtest: pipe-A-DP-3
[717.331395] Dynamic subtest pipe-A-DP-3: SUCCESS (0.079s)
[717.349421] Starting dynamic subtest: pipe-B-DP-3
[717.350671] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[717.366697] Starting dynamic subtest: pipe-C-DP-3
[717.367654] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[717.381247] Starting dynamic subtest: pipe-D-DP-3
[717.382009] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[717.382798] Subtest overflow-vrefresh: SUCCESS (0.089s)
[717.570526] [121/130] (436s left) kms_fbcon_fbt (fbc-suspend)
[717.657036] Starting subtest: fbc-suspend
[769.592204] Subtest fbc-suspend: SUCCESS (21.473s)
[769.696547] [122/130] (415s left) kms_hdr (static-toggle-dpms)
[769.902654] Starting subtest: static-toggle-dpms
[773.248228] Starting dynamic subtest: pipe-A-DP-3
[774.546099] Dynamic subtest pipe-A-DP-3: SUCCESS (1.298s)
[774.547569] Subtest static-toggle-dpms: SUCCESS (4.644s)
[774.900347] [123/130] (410s left) gem_userptr_blits (sync-unmap-cycles)
[774.998333] Starting subtest: sync-unmap-cycles
[780.383815] Subtest sync-unmap-cycles: SUCCESS (5.384s)
[780.568828] [124/130] (404s left) gem_spin_batch (user-each)
[780.663549] Starting subtest: user-each
[784.093856] Subtest user-each: SUCCESS (3.429s)
[784.355147] [125/130] (400s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[784.441037] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[784.567788] [126/130] (400s left) template (b)
[784.645637] Starting subtest: B
[784.646608] Subtest B: SUCCESS (0.000s)
[784.755105] [127/130] (400s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[784.965806] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[784.968171] Starting dynamic subtest: pipe-A-valid-mode
[786.813143] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.843s)
[786.814791] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (1.846s)
[787.077487] [128/130] (398s left) kms_psr (fbc-psr2-primary-blt)
[787.288433] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[787.348841] [129/130] (398s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[787.570934] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[787.571934] Starting dynamic subtest: pipe-A
[789.670699] Dynamic subtest pipe-A: SUCCESS (2.099s)
[789.693744] Starting dynamic subtest: pipe-B
[791.930503] Dynamic subtest pipe-B: SUCCESS (2.258s)
[791.959645] Starting dynamic subtest: pipe-C
[792.251304] Dynamic subtest pipe-C: SUCCESS (0.319s)
[792.285821] Starting dynamic subtest: pipe-D
[792.571235] Dynamic subtest pipe-D: SUCCESS (0.318s)
[792.572590] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.001s)
[792.889581] [130/130] (392s left) gem_wait (busy)
[792.987397] Starting subtest: busy
[792.988392] Starting dynamic subtest: all-engines
[793.569579] Dynamic subtest all-engines: SUCCESS (0.582s)
[793.583232] Starting dynamic subtest: rcs0
[794.108348] Dynamic subtest rcs0: SUCCESS (0.538s)
[794.121953] Starting dynamic subtest: bcs0
[794.647550] Dynamic subtest bcs0: SUCCESS (0.538s)
[794.661305] Starting dynamic subtest: vcs0
[795.186528] Dynamic subtest vcs0: SUCCESS (0.538s)
[795.200240] Starting dynamic subtest: vcs1
[795.725515] Dynamic subtest vcs1: SUCCESS (0.538s)
[795.739232] Starting dynamic subtest: vecs0
[796.264566] Dynamic subtest vecs0: SUCCESS (0.538s)
[796.278201] Starting dynamic subtest: vecs1
[796.803540] Dynamic subtest vecs1: SUCCESS (0.538s)
[796.817196] Starting dynamic subtest: ccs0
[797.342399] Dynamic subtest ccs0: SUCCESS (0.538s)
[797.343400] Subtest busy: SUCCESS (4.355s)
[797.581655] Closing watchdogs
results: parsing output: 0/ for test: api_intel_allocator
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: gem_mmap_wc
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: gem_exec_reloc
results: parsing output: 7/ for test: kms_psr2_sf
results: parsing output: 8/ for test: kms_busy
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: gem_madvise
results: parsing output: 11/ for test: kms_pm_dc
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_partial_pwrite_pread
results: parsing output: 14/ for test: gem_exec_whisper
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: fbdev
results: parsing output: 18/ for test: gem_softpin
results: parsing output: 19/ for test: gem_create
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_legacy_colorkey
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: kms_color
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: kms_selftest
results: parsing output: 27/ for test: gem_softpin
results: parsing output: 28/ for test: gem_exec_params
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: gem_eio
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: gem_ctx_persistence
results: parsing output: 33/ for test: gem_flink_basic
results: parsing output: 34/ for test: perf_pmu
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_render_copy
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: kms_pm_rpm
results: parsing output: 40/ for test: kms_setmode
results: parsing output: 41/ for test: api_intel_allocator
results: parsing output: 42/ for test: gem_exec_balancer
results: parsing output: 43/ for test: gem_ctx_create
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_dp_aux_dev
results: parsing output: 53/ for test: kms_chamelium_color
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: kms_content_protection
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: i915_pm_rps
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: perf
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_mmap_wc
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: dumb_buffer
results: parsing output: 76/ for test: prime_vgem
results: parsing output: 77/ for test: kms_pipe_crc_basic
results: parsing output: 78/ for test: gem_workarounds
results: parsing output: 79/ for test: prime_busy
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: gem_exec_schedule
results: parsing output: 84/ for test: kms_vrr
results: parsing output: 85/ for test: gem_ringfill
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: i915_query
results: parsing output: 88/ for test: i915_pm_rpm
results: parsing output: 89/ for test: gem_mmap_offset
results: parsing output: 90/ for test: gen9_exec_parse
results: parsing output: 91/ for test: sysfs_timeslice_duration
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: gem_exec_fence
results: parsing output: 95/ for test: api_intel_bb
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: gem_ctx_persistence
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: kms_atomic_interruptible
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_pread
results: parsing output: 109/ for test: drm_fdinfo
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: gem_huc_copy
results: parsing output: 113/ for test: gem_mmap_gtt
results: parsing output: 114/ for test: gem_fenced_exec_thrash
results: parsing output: 115/ for test: kms_addfb_basic
results: parsing output: 116/ for test: gem_cs_tlb
results: parsing output: 117/ for test: kms_universal_plane
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: kms_invalid_mode
results: parsing output: 120/ for test: kms_fbcon_fbt
results: parsing output: 121/ for test: kms_hdr
results: parsing output: 122/ for test: gem_userptr_blits
results: parsing output: 123/ for test: gem_spin_batch
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: template
results: parsing output: 126/ for test: kms_flip_scaled_crc
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_plane_scaling
results: parsing output: 129/ for test: gem_wait
Done.