[46.638730] Initializing watchdogs
[46.638797]   /dev/watchdog0
[46.653998] [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]
[46.659809] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[46.662393] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[46.664205] [001/134] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[46.761780] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[46.763203] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[46.866730] [002/134] (960s left) gem_exec_params (secure-non-master)
[46.905716] Starting subtest: secure-non-master
[46.907107] Subtest secure-non-master: SKIP (0.000s)
[46.997208] [003/134] (960s left) tools_test (tools_test)
[47.032837] Starting subtest: tools_test
[47.105849] Subtest tools_test: SUCCESS (0.078s)
[47.185744] [004/134] (960s left) kms_selftest (drm_cmdline_parser)
[47.219017] Starting subtest: drm_cmdline_parser
[47.226496] Starting dynamic subtest: drm_test_cmdline_force_d_only
[47.323114] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.095s)
[47.335259] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[47.419167] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.096s)
[47.431882] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[47.501857] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.081s)
[47.514794] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[47.580127] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.077s)
[47.593221] Starting dynamic subtest: drm_test_cmdline_force_e_only
[47.685093] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.104s)
[47.698380] Starting dynamic subtest: drm_test_cmdline_res
[47.775389] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.090s)
[47.789294] Starting dynamic subtest: drm_test_cmdline_res_vesa
[47.863977] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.087s)
[47.878713] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[47.937413] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.073s)
[47.952393] Starting dynamic subtest: drm_test_cmdline_res_rblank
[48.013953] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.076s)
[48.028417] Starting dynamic subtest: drm_test_cmdline_res_bpp
[48.097875] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.084s)
[48.113060] Starting dynamic subtest: drm_test_cmdline_res_refresh
[48.187198] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.087s)
[48.200528] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[48.280088] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.092s)
[48.293833] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[48.365920] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.086s)
[48.378792] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[48.448529] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.082s)
[48.461393] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[48.543625] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.094s)
[48.556739] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[48.644791] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.100s)
[48.657847] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[48.747791] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.102s)
[48.761062] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[48.842825] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.094s)
[48.855822] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[48.949740] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.106s)
[48.963979] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[49.036106] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.085s)
[49.049312] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[49.110800] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.075s)
[49.123588] Starting dynamic subtest: drm_test_cmdline_name
[49.195752] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.084s)
[49.210755] Starting dynamic subtest: drm_test_cmdline_name_bpp
[49.279360] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.082s)
[49.294239] Starting dynamic subtest: drm_test_cmdline_name_option
[49.355026] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.075s)
[49.369174] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[49.429720] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.075s)
[49.442331] Starting dynamic subtest: drm_test_cmdline_rotate_0
[49.518323] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.087s)
[49.532888] Starting dynamic subtest: drm_test_cmdline_rotate_90
[49.598170] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.079s)
[49.612274] Starting dynamic subtest: drm_test_cmdline_rotate_180
[49.677140] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.078s)
[49.691183] Starting dynamic subtest: drm_test_cmdline_rotate_270
[49.753309] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.075s)
[49.767407] Starting dynamic subtest: drm_test_cmdline_hmirror
[49.837992] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.083s)
[49.852419] Starting dynamic subtest: drm_test_cmdline_vmirror
[49.905304] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.065s)
[49.917552] Starting dynamic subtest: drm_test_cmdline_margin_options
[49.989265] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.085s)
[50.001600] Starting dynamic subtest: drm_test_cmdline_multiple_options
[50.066391] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.076s)
[50.079200] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[50.148127] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.080s)
[50.160981] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[50.218100] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.070s)
[50.229258] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[50.306618] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.088s)
[50.319410] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[50.400676] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.093s)
[50.413839] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[50.490758] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.088s)
[50.503100] Starting dynamic subtest: drm_test_cmdline_invalid
[50.589754] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.098s)
[50.602477] Starting dynamic subtest: drm_test_cmdline_tv_options
[50.681849] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.090s)
[50.682928] Subtest drm_cmdline_parser: SUCCESS (3.462s)
[50.836100] [005/134] (956s left) gem_exec_suspend (basic-s0)
[50.877566] Starting subtest: basic-S0
[50.878267] Starting dynamic subtest: smem
[69.059355] Dynamic subtest smem: SUCCESS (2.646s)
[69.060138] Subtest basic-S0: SUCCESS (2.646s)
[78.303252] [006/134] (938s left) kms_content_protection (uevent)
[78.407510] Starting subtest: uevent
[78.421006] Subtest uevent: SKIP (0.012s)
[78.517872] [007/134] (937s left) gem_eio (in-flight-suspend)
[78.559297] Starting subtest: in-flight-suspend
[115.620577] Subtest in-flight-suspend: SUCCESS (7.632s)
[123.777679] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[123.815055] [008/134] (900s left) gem_spin_batch (resubmit-all)
[123.874572] Starting subtest: resubmit-all
[123.875291] Starting dynamic subtest: rcs0
[123.878905] Dynamic subtest rcs0: SUCCESS (0.004s)
[123.886429] Starting dynamic subtest: bcs0
[123.887257] Dynamic subtest bcs0: SUCCESS (0.002s)
[123.894017] Starting dynamic subtest: vcs0
[123.894682] Dynamic subtest vcs0: SUCCESS (0.002s)
[123.900712] Starting dynamic subtest: vcs1
[123.901401] Dynamic subtest vcs1: SUCCESS (0.002s)
[123.907148] Starting dynamic subtest: vecs0
[123.907885] Dynamic subtest vecs0: SUCCESS (0.002s)
[123.908543] Subtest resubmit-all: SUCCESS (0.014s)
[124.073105] [009/134] (900s left) kms_psr (psr-cursor-plane-onoff)
[124.162158] Subtest psr-cursor-plane-onoff: SKIP (0.000s)
[124.201729] [010/134] (900s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[124.288546] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[124.289935] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[124.385461] [011/134] (900s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[124.472691] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[124.474310] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[124.587668] [012/134] (900s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[124.675385] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[124.718236] [013/134] (900s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[124.806328] Subtest psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[124.842372] [014/134] (899s left) kms_pm_rpm (basic-pci-d3-state)
[127.439829] Starting subtest: basic-pci-d3-state
[128.738007] Subtest basic-pci-d3-state: SUCCESS (1.297s)
[128.866966] [015/134] (895s left) gem_ctx_exec (basic-norecovery)
[128.902724] Starting subtest: basic-norecovery
[128.916890] Subtest basic-norecovery: SUCCESS (0.014s)
[129.047795] [016/134] (895s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[129.147838] Starting subtest: protected-encrypted-src-copy-not-readible
[129.231928] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.085s)
[129.359124] [017/134] (895s left) kms_flip (absolute-wf_vblank)
[129.443410] Starting subtest: absolute-wf_vblank
[129.445479] Starting dynamic subtest: A-HDMI-A1
[137.144576] Dynamic subtest A-HDMI-A1: SUCCESS (7.679s)
[137.151411] Starting dynamic subtest: B-HDMI-A1
[144.785050] Dynamic subtest B-HDMI-A1: SUCCESS (7.657s)
[144.792496] Starting dynamic subtest: C-HDMI-A1
[152.465613] Dynamic subtest C-HDMI-A1: SUCCESS (7.654s)
[152.471769] Starting dynamic subtest: D-HDMI-A1
[160.124579] Dynamic subtest D-HDMI-A1: SUCCESS (7.659s)
[160.125335] Subtest absolute-wf_vblank: SUCCESS (30.658s)
[160.268871] [018/134] (864s left) prime_mmap (test_refcounting)
[160.301333] Starting subtest: test_refcounting
[160.302019] Starting dynamic subtest: test_refcounting-smem
[160.303392] Dynamic subtest test_refcounting-smem: SUCCESS (0.002s)
[160.304077] Subtest test_refcounting: SUCCESS (0.002s)
[160.414180] [019/134] (864s left) gem_exec_schedule (pi-common)
[160.581285] Starting subtest: pi-common
[160.582168] Starting dynamic subtest: rcs0
[160.609417] Dynamic subtest rcs0: SUCCESS (0.029s)
[160.616781] Starting dynamic subtest: bcs0
[160.633196] Dynamic subtest bcs0: SUCCESS (0.024s)
[160.640404] Starting dynamic subtest: vcs0
[160.657885] Dynamic subtest vcs0: SUCCESS (0.024s)
[160.665260] Starting dynamic subtest: vcs1
[160.681475] Dynamic subtest vcs1: SUCCESS (0.023s)
[160.688543] Starting dynamic subtest: vecs0
[160.704732] Dynamic subtest vecs0: SUCCESS (0.023s)
[160.705457] Subtest pi-common: SUCCESS (0.125s)
[160.878481] [020/134] (864s left) perf (unprivileged-single-ctx-counters)
[160.983363] Starting subtest: unprivileged-single-ctx-counters
[160.984680] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[161.022754] [021/134] (864s left) api_intel_allocator (fork-simple-stress-signal)
[161.053987] Starting subtest: fork-simple-stress-signal
[166.482390] Subtest fork-simple-stress-signal: SUCCESS (5.419s)
[166.551039] [022/134] (858s left) kms_flip (flip-vs-panning-vs-hang)
[166.651044] Starting subtest: flip-vs-panning-vs-hang
[166.651746] Starting dynamic subtest: A-HDMI-A1
[205.854416] Dynamic subtest A-HDMI-A1: SUCCESS (39.190s)
[205.861276] Starting dynamic subtest: D-HDMI-A1
[244.767228] Dynamic subtest D-HDMI-A1: SUCCESS (38.893s)
[244.768166] Subtest flip-vs-panning-vs-hang: SUCCESS (78.086s)
[244.933554] [023/134] (780s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[245.025495] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[245.061500] [024/134] (780s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[245.094579] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[245.095296] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[245.176710] [025/134] (780s left) gem_exec_create (basic)
[245.215244] Starting subtest: basic
[245.215996] Starting dynamic subtest: smem
[247.281795] Dynamic subtest smem: SUCCESS (2.066s)
[247.282564] Subtest basic: SUCCESS (2.067s)
[247.464212] [026/134] (777s left) gem_ctx_persistence (smoketest)
[247.501568] Starting subtest: smoketest
[285.809882] Subtest smoketest: SUCCESS (38.306s)
[285.895838] [027/134] (739s left) gem_ctx_bad_destroy (invalid-pad)
[285.928376] Starting subtest: invalid-pad
[285.929078] Subtest invalid-pad: SUCCESS (0.000s)
[286.009960] [028/134] (739s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[286.096747] Starting subtest: cursorA-vs-flipB-legacy
[286.098895] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[286.185801] [029/134] (739s left) kms_atomic_interruptible (legacy-setmode)
[286.271087] Starting subtest: legacy-setmode
[286.272565] Starting dynamic subtest: pipe-A-HDMI-A-1
[292.444533] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.171s)
[292.445218] Subtest legacy-setmode: SUCCESS (6.173s)
[292.598740] [030/134] (732s left) kms_cdclk (mode-transition)
[292.634604] Subtest mode-transition: SKIP (0.000s)
[292.679781] [031/134] (732s left) kms_big_fb (linear-8bpp-rotate-270)
[292.774618] Starting subtest: linear-8bpp-rotate-270
[292.852699] Subtest linear-8bpp-rotate-270: SKIP (0.078s)
[292.997585] [032/134] (732s left) perf_pmu (faulting-read)
[293.031557] Starting subtest: faulting-read
[293.032249] Starting dynamic subtest: gtt
[293.032930] Dynamic subtest gtt: SUCCESS (0.001s)
[293.040594] Starting dynamic subtest: wb
[293.041282] Dynamic subtest wb: SUCCESS (0.001s)
[293.048199] Starting dynamic subtest: wc
[293.048893] Dynamic subtest wc: SUCCESS (0.000s)
[293.055634] Starting dynamic subtest: uc
[293.056317] Dynamic subtest uc: SUCCESS (0.000s)
[293.056991] Subtest faulting-read: SUCCESS (0.005s)
[293.142273] [033/134] (732s left) kms_psr (fbc-psr2-primary-render)
[293.227367] Subtest fbc-psr2-primary-render: SKIP (0.000s)
[293.268974] [034/134] (732s left) gem_exec_schedule (deep)
[293.434138] Starting subtest: deep
[293.435147] Starting dynamic subtest: rcs0
[301.388926] Dynamic subtest rcs0: SUCCESS (7.946s)
[301.396969] Starting dynamic subtest: bcs0
[309.531477] Dynamic subtest bcs0: SUCCESS (8.142s)
[309.541502] Starting dynamic subtest: vcs0
[317.735726] Dynamic subtest vcs0: SUCCESS (8.204s)
[317.743685] Starting dynamic subtest: vcs1
[325.875088] Dynamic subtest vcs1: SUCCESS (8.139s)
[325.882824] Starting dynamic subtest: vecs0
[334.074287] Dynamic subtest vecs0: SUCCESS (8.199s)
[334.075107] Subtest deep: SUCCESS (40.632s)
[334.494209] [035/134] (691s left) kms_selftest (drm_plane_helper)
[334.526937] Starting subtest: drm_plane_helper
[334.539698] Starting dynamic subtest: drm_test_check_plane_state
[334.641354] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.099s)
[334.653801] Starting dynamic subtest: drm_test_check_invalid_plane_state
[334.731322] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.089s)
[334.732548] Subtest drm_plane_helper: SUCCESS (0.204s)
[334.879166] [036/134] (690s left) syncobj_basic (bad-pad-fd-to-handle)
[334.910512] Starting subtest: bad-pad-fd-to-handle
[334.911200] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[334.994324] [037/134] (690s left) gem_lmem_swapping (parallel-random-engines)
[335.029409] Subtest parallel-random-engines: SKIP (0.000s)
[335.068103] [038/134] (690s left) kms_mmap_write_crc (main)
[335.159900] Starting subtest: main
[335.161423] Starting dynamic subtest: pipe-A-HDMI-A-1
[336.620331] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.459s)
[336.621066] Subtest main: SUCCESS (1.460s)
[336.776484] [039/134] (688s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[336.868929] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[336.870330] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[336.967091] [040/134] (688s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[337.051868] Starting subtest: addfb25-yf-tiled-legacy
[337.052597] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[337.169826] [041/134] (688s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[337.260858] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[337.262199] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[337.379551] [042/134] (688s left) gem_ctx_exec (basic)
[337.417471] Starting subtest: basic
[337.419699] Subtest basic: SUCCESS (0.002s)
[337.555130] [043/134] (688s left) kms_atomic (test-only)
[337.642837] Starting subtest: test-only
[337.697648] Starting dynamic subtest: pipe-A-HDMI-A-1
[338.107741] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.411s)
[338.108440] Subtest test-only: SUCCESS (0.465s)
[338.250956] [044/134] (687s left) gem_set_tiling_vs_gtt
[338.735565] [045/134] (687s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[338.830076] Starting subtest: yf-tiled-16bpp-rotate-0
[338.831453] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[338.954901] [046/134] (687s left) kms_getfb (getfb2-handle-zero)
[339.041495] Starting subtest: getfb2-handle-zero
[339.042248] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[339.159155] [047/134] (686s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[339.192088] Starting subtest: single-wait-all-for-submit-available-submitted
[339.192796] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[339.261880] [048/134] (686s left) gem_exec_reloc (basic-wc-cpu)
[339.298840] Starting subtest: basic-wc-cpu
[339.305154] Subtest basic-wc-cpu: SUCCESS (0.006s)
[339.431939] [049/134] (686s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[339.521518] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[339.522863] Subtest psr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[339.626722] [050/134] (686s left) gem_basic (multigpu-create-close)
[339.661604] Starting subtest: multigpu-create-close
[339.662986] Subtest multigpu-create-close: SKIP (0.000s)
[339.752277] [051/134] (686s left) kms_frontbuffer_tracking (fbc-suspend)
[339.840813] Starting subtest: fbc-suspend
[375.802136] Subtest fbc-suspend: SUCCESS (5.194s)
[383.014040] [052/134] (650s left) gem_wait (wait)
[383.059985] Starting subtest: wait
[383.060760] Starting dynamic subtest: all-engines
[384.111634] Dynamic subtest all-engines: SUCCESS (1.051s)
[384.123243] Starting dynamic subtest: rcs0
[385.143726] Dynamic subtest rcs0: SUCCESS (1.030s)
[385.154985] Starting dynamic subtest: bcs0
[386.167448] Dynamic subtest bcs0: SUCCESS (1.021s)
[386.177792] Starting dynamic subtest: vcs0
[387.189508] Dynamic subtest vcs0: SUCCESS (1.021s)
[387.201121] Starting dynamic subtest: vcs1
[388.212557] Dynamic subtest vcs1: SUCCESS (1.020s)
[388.222383] Starting dynamic subtest: vecs0
[389.238541] Dynamic subtest vecs0: SUCCESS (1.025s)
[389.239670] Subtest wait: SUCCESS (6.176s)
[389.454363] [053/134] (643s left) gem_exec_reloc (basic-wc-active)
[389.490956] Starting subtest: basic-wc-active
[389.619223] Subtest basic-wc-active: SUCCESS (0.120s)
[389.750895] [054/134] (643s left) kms_pm_rpm (pc8-residency)
[391.145228] Starting subtest: pc8-residency
[391.147694] Subtest pc8-residency: SKIP (0.000s)
[391.346200] [055/134] (642s left) kms_dsc (dsc-with-bpc-formats)
[391.431045] Starting subtest: dsc-with-bpc-formats
[391.462931] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[391.503904] [056/134] (641s left) kms_psr (pr-cursor-plane-move)
[391.587825] Subtest pr-cursor-plane-move: SKIP (0.000s)
[391.633281] [057/134] (641s left) kms_vblank (ts-continuation-dpms-suspend)
[391.717030] Starting subtest: ts-continuation-dpms-suspend
[391.717704] Starting dynamic subtest: pipe-A-HDMI-A-1
[408.703273] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.533s)
[408.716005] Starting dynamic subtest: pipe-D-HDMI-A-1
[425.686574] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.406s)
[425.687252] Subtest ts-continuation-dpms-suspend: SUCCESS (2.945s)
[434.893829] [058/134] (607s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[434.938632] Starting subtest: multi-wait-available-unsubmitted-signaled
[434.939367] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[435.007423] [059/134] (607s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[435.092844] Starting subtest: basic-flip-before-cursor-legacy
[435.613398] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.520s)
[435.776073] [060/134] (606s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[435.915893] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[436.010541] [061/134] (606s left) gem_exec_params (invalid-flag)
[436.047520] Starting subtest: invalid-flag
[436.048711] Subtest invalid-flag: SUCCESS (0.000s)
[436.164749] [062/134] (606s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[436.251588] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[436.252982] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[436.356533] [063/134] (606s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[436.389768] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[436.390450] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[436.469091] [064/134] (606s left) prime_vgem (wait)
[436.533666] Starting subtest: wait
[436.534568] Starting dynamic subtest: rcs0
[436.571390] Dynamic subtest rcs0: SUCCESS (0.037s)
[436.578846] Starting dynamic subtest: bcs0
[436.590361] Dynamic subtest bcs0: SUCCESS (0.018s)
[436.599062] Starting dynamic subtest: vcs0
[436.608386] Dynamic subtest vcs0: SUCCESS (0.017s)
[436.620558] Starting dynamic subtest: vcs1
[436.624748] Dynamic subtest vcs1: SUCCESS (0.015s)
[436.632349] Starting dynamic subtest: vecs0
[436.643047] Dynamic subtest vecs0: SUCCESS (0.017s)
[436.643880] Subtest wait: SUCCESS (0.110s)
[436.779691] [065/134] (606s left) gem_exec_capture (capture)
[436.818892] Starting subtest: capture
[436.819552] Starting dynamic subtest: rcs0-smem
[438.101747] Dynamic subtest rcs0-smem: SUCCESS (1.276s)
[438.109312] Starting dynamic subtest: bcs0-smem
[439.373789] Dynamic subtest bcs0-smem: SUCCESS (1.269s)
[439.382993] Starting dynamic subtest: vcs0-smem
[440.646110] Dynamic subtest vcs0-smem: SUCCESS (1.271s)
[440.653720] Starting dynamic subtest: vcs1-smem
[441.917618] Dynamic subtest vcs1-smem: SUCCESS (1.271s)
[441.925016] Starting dynamic subtest: vecs0-smem
[443.189472] Dynamic subtest vecs0-smem: SUCCESS (1.271s)
[443.190143] Subtest capture: SUCCESS (6.360s)
[443.306983] [066/134] (599s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[443.397715] Starting subtest: cursor-rapid-movement-512x512
[443.399954] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[443.511980] [067/134] (599s left) kms_flip (2x-flip-vs-wf_vblank)
[443.595609] Starting subtest: 2x-flip-vs-wf_vblank
[443.604459] Subtest 2x-flip-vs-wf_vblank: SKIP (0.009s)
[443.686710] [068/134] (599s left) i915_pm_freq_api (freq-suspend)
[443.722709] Subtest freq-suspend: SKIP (0.000s)
[443.767107] [069/134] (599s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[443.854880] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[443.856260] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[443.970869] [070/134] (599s left) kms_chamelium_frames (hdmi-aspect-ratio)
[444.103332] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[444.209470] [071/134] (598s left) kms_cursor_crc (cursor-sliding-max-size)
[444.302831] Starting subtest: cursor-sliding-max-size
[444.304819] Subtest cursor-sliding-max-size: SKIP (0.000s)
[444.408147] [072/134] (598s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[444.494881] Starting subtest: plane-ctm3x4-lut1d
[444.508751] Starting dynamic subtest: pipe-A-HDMI-A-1
[444.675478] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.166s)
[444.832458] Starting dynamic subtest: pipe-B-HDMI-A-1
[444.973376] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.148s)
[445.113639] Starting dynamic subtest: pipe-C-HDMI-A-1
[445.254995] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.148s)
[445.381916] Starting dynamic subtest: pipe-D-HDMI-A-1
[445.521108] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.148s)
[445.568754] Subtest plane-ctm3x4-lut1d: SUCCESS (1.073s)
[445.689355] [073/134] (597s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[445.783914] Starting subtest: psr-1p-pri-indfb-multidraw
[445.785326] Subtest psr-1p-pri-indfb-multidraw: SKIP (0.000s)
[445.878806] [074/134] (597s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[445.973401] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[445.974777] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[446.094964] [075/134] (597s left) syncobj_timeline (invalid-query-illegal-handle)
[446.127232] Starting subtest: invalid-query-illegal-handle
[446.127969] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[446.207299] [076/134] (597s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[446.290973] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[446.291648] Starting dynamic subtest: pipe-A-HDMI-A-1
[446.298329] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[446.307904] Starting dynamic subtest: pipe-B-HDMI-A-1
[446.314727] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[446.324118] Starting dynamic subtest: pipe-C-HDMI-A-1
[446.330929] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[446.340104] Starting dynamic subtest: pipe-D-HDMI-A-1
[446.347151] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[446.348609] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SKIP (0.009s)
[446.541859] [077/134] (596s left) kms_addfb_basic (too-high)
[446.628384] Starting subtest: too-high
[446.629155] Subtest too-high: SUCCESS (0.001s)
[446.724632] [078/134] (596s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[446.815039] Starting subtest: psr-rgb565-draw-mmap-gtt
[446.816458] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[446.936445] [079/134] (596s left) gem_mmap_offset (mmap-unmap)
[446.968806] Starting subtest: mmap-unmap
[446.969563] Starting dynamic subtest: smem0
[478.106949] Dynamic subtest smem0: SUCCESS (31.134s)
[478.107745] Subtest mmap-unmap: SUCCESS (31.135s)
[478.229979] [080/134] (565s left) gem_pread (exhaustion)
[478.261919] Starting subtest: exhaustion
[482.206375] Subtest exhaustion: SUCCESS (3.936s)
[482.553312] [081/134] (560s left) kms_joiner (invalid-modeset-ultra-joiner)
[482.641051] Starting subtest: invalid-modeset-ultra-joiner
[482.642456] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[482.720258] [082/134] (560s left) i915_drm_fdinfo (basics)
[482.754800] Starting subtest: basics
[482.755499] Subtest basics: SUCCESS (0.000s)
[482.828436] [083/134] (560s left) gem_ccs (ctrl-surf-copy-new-ctx)
[482.864638] Starting subtest: ctrl-surf-copy-new-ctx
[482.866063] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[482.935187] [084/134] (560s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[483.020251] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[483.035542] Starting dynamic subtest: pipe-A-plane-0
[483.485674] Dynamic subtest pipe-A-plane-0: SUCCESS (0.450s)
[483.502640] Starting dynamic subtest: pipe-A-plane-3
[483.877457] Dynamic subtest pipe-A-plane-3: SUCCESS (0.390s)
[483.894499] Starting dynamic subtest: pipe-A-plane-5
[484.169035] Dynamic subtest pipe-A-plane-5: SUCCESS (0.290s)
[484.186721] Starting dynamic subtest: pipe-A-plane-7
[484.188126] Dynamic subtest pipe-A-plane-7: SKIP (0.000s)
[484.322106] Starting dynamic subtest: pipe-B-plane-0
[484.738840] Dynamic subtest pipe-B-plane-0: SUCCESS (0.425s)
[484.756653] Starting dynamic subtest: pipe-B-plane-3
[485.121972] Dynamic subtest pipe-B-plane-3: SUCCESS (0.381s)
[485.135681] Starting dynamic subtest: pipe-B-plane-5
[485.430863] Dynamic subtest pipe-B-plane-5: SUCCESS (0.305s)
[485.448125] Starting dynamic subtest: pipe-B-plane-7
[485.449552] Dynamic subtest pipe-B-plane-7: SKIP (0.000s)
[485.487006] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.467s)
[485.645832] [085/134] (558s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[485.744241] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[485.746022] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[485.855482] [086/134] (557s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[485.943911] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[485.944723] Starting dynamic subtest: pipe-A-valid-mode
[487.149064] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.204s)
[487.149812] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.206s)
[487.299731] [087/134] (556s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[487.397385] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[487.398726] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[487.518808] [088/134] (556s left) kms_draw_crc (fill-fb)
[487.604360] Starting subtest: fill-fb
[487.825071] Subtest fill-fb: SUCCESS (0.220s)
[487.931021] [089/134] (555s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[488.025901] Starting subtest: y-tiled-8bpp-rotate-0
[489.000768] Subtest y-tiled-8bpp-rotate-0: SUCCESS (0.976s)
[489.150846] [090/134] (554s left) gem_render_copy (yf-tiled-to-vebox-linear)
[489.189212] Starting subtest: yf-tiled-to-vebox-linear
[489.189904] Starting dynamic subtest: smem
[489.252975] Dynamic subtest smem: SUCCESS (0.063s)
[489.253810] Subtest yf-tiled-to-vebox-linear: SUCCESS (0.064s)
[489.444037] [091/134] (554s left) syncobj_wait (invalid-reset-illegal-handle)
[489.476850] Starting subtest: invalid-reset-illegal-handle
[489.477530] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[489.565871] [092/134] (554s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[489.654943] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[489.656304] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[489.776892] [093/134] (554s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[489.871149] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[489.872523] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[489.994500] [094/134] (554s left) api_intel_bb (full-batch)
[490.028110] Starting subtest: full-batch
[490.029904] Subtest full-batch: SUCCESS (0.003s)
[490.140575] [095/134] (554s left) kms_psr (fbc-psr-basic)
[490.224837] Subtest fbc-psr-basic: SKIP (0.000s)
[490.265752] [096/134] (553s left) gem_exercise_blt (fast-copy-emit)
[490.302268] Starting subtest: fast-copy-emit
[490.303002] Starting dynamic subtest: linear-smem-smem-emit
[490.322116] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.019s)
[490.330919] Starting dynamic subtest: ymajor-smem-smem-emit
[490.339450] Dynamic subtest ymajor-smem-smem-emit: SUCCESS (0.016s)
[490.348261] Starting dynamic subtest: tile4-smem-smem-emit
[490.356102] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[490.364953] Starting dynamic subtest: tile64-smem-smem-emit
[490.372758] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[490.373457] Subtest fast-copy-emit: SUCCESS (0.071s)
[490.500138] [097/134] (553s left) sysfs_heartbeat_interval (mixed)
[490.535335] Starting subtest: mixed
[490.537142] Starting dynamic subtest: vecs0
[495.709690] Dynamic subtest vecs0: SUCCESS (5.172s)
[495.731897] Starting dynamic subtest: vcs0
[500.903664] Dynamic subtest vcs0: SUCCESS (5.190s)
[500.923197] Starting dynamic subtest: bcs0
[506.053555] Dynamic subtest bcs0: SUCCESS (5.146s)
[506.064727] Starting dynamic subtest: rcs0
[511.263603] Dynamic subtest rcs0: SUCCESS (5.207s)
[511.274132] Starting dynamic subtest: vcs1
[516.405516] Dynamic subtest vcs1: SUCCESS (5.138s)
[516.406819] Subtest mixed: SUCCESS (25.870s)
[516.484899] [098/134] (527s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[516.573001] Starting subtest: cursor-vs-flip-legacy
[546.234382] Subtest cursor-vs-flip-legacy: SUCCESS (29.661s)
[546.380737] [099/134] (497s left) gem_exec_schedule (semaphore-resolve)
[546.428104] Starting subtest: semaphore-resolve
[546.445456] Subtest semaphore-resolve: SUCCESS (0.017s)
[546.760802] [100/134] (497s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[546.849868] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[546.851250] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[546.968152] [101/134] (497s left) kms_cursor_legacy (torture-bo)
[547.052133] Starting subtest: torture-bo
[547.052860] Starting dynamic subtest: pipe-A
[552.632421] Dynamic subtest pipe-A: SUCCESS (5.579s)
[552.642573] Starting dynamic subtest: pipe-B
[558.124954] Dynamic subtest pipe-B: SUCCESS (5.483s)
[558.134671] Starting dynamic subtest: pipe-C
[563.610602] Dynamic subtest pipe-C: SUCCESS (5.460s)
[563.621720] Starting dynamic subtest: pipe-D
[569.159961] Dynamic subtest pipe-D: SUCCESS (5.547s)
[569.180327] Starting dynamic subtest: all-pipes
[574.722571] Dynamic subtest all-pipes: SUCCESS (5.562s)
[574.723641] Subtest torture-bo: SUCCESS (27.637s)
[574.791131] [102/134] (469s left) gem_ctx_shared (q-independent)
[574.832731] Starting subtest: Q-independent
[574.833488] Starting dynamic subtest: rcs0
[574.854312] Dynamic subtest rcs0: SUCCESS (0.021s)
[574.863402] Starting dynamic subtest: bcs0
[574.873801] Dynamic subtest bcs0: SUCCESS (0.018s)
[574.883396] Starting dynamic subtest: vcs0
[574.894770] Dynamic subtest vcs0: SUCCESS (0.020s)
[574.903389] Starting dynamic subtest: vcs1
[574.914651] Dynamic subtest vcs1: SUCCESS (0.019s)
[574.924292] Starting dynamic subtest: vecs0
[574.937142] Dynamic subtest vecs0: SUCCESS (0.021s)
[574.937978] Subtest Q-independent: SUCCESS (0.105s)
[575.131194] [103/134] (469s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[575.598214] Starting subtest: syncobj-timeline-invalid-wait
[575.599150] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[575.779053] [104/134] (468s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[575.816485] Starting subtest: y-tiled-ccs-to-x-tiled
[575.817213] Starting dynamic subtest: smem
[575.920305] Dynamic subtest smem: SUCCESS (0.103s)
[575.921068] Subtest y-tiled-ccs-to-x-tiled: SUCCESS (0.104s)
[576.136816] [105/134] (468s left) drm_read (short-buffer-wakeup)
[576.241134] Starting subtest: short-buffer-wakeup
[608.465913] Subtest short-buffer-wakeup: SUCCESS (32.214s)
[608.577065] [106/134] (436s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[608.666060] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[608.667874] Starting dynamic subtest: pipe-A-HDMI-A-1
[608.720623] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.053s)
[608.791047] Starting dynamic subtest: pipe-B-HDMI-A-1
[608.964964] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.182s)
[609.016511] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.350s)
[609.181843] [107/134] (435s left) syncobj_timeline (device-submit-unordered)
[609.219676] Starting subtest: device-submit-unordered
[609.220374] Subtest device-submit-unordered: SUCCESS (0.001s)
[609.306481] [108/134] (435s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[609.392064] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[609.392853] Starting dynamic subtest: pipe-A-HDMI-A-1
[609.410297] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[609.420289] Starting dynamic subtest: pipe-B-HDMI-A-1
[609.437946] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[609.447513] Starting dynamic subtest: pipe-C-HDMI-A-1
[609.464919] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[609.472958] Starting dynamic subtest: pipe-D-HDMI-A-1
[609.490525] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[609.491976] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.014s)
[609.663428] [109/134] (435s left) kms_pipe_crc_basic (nonblocking-crc)
[609.748204] Starting subtest: nonblocking-crc
[609.750011] Starting dynamic subtest: pipe-A-HDMI-A-1
[610.110012] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.360s)
[610.122530] Starting dynamic subtest: pipe-B-HDMI-A-1
[610.435898] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.323s)
[610.447765] Starting dynamic subtest: pipe-C-HDMI-A-1
[610.756855] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.319s)
[610.766286] Starting dynamic subtest: pipe-D-HDMI-A-1
[611.099779] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.340s)
[611.100465] Subtest nonblocking-crc: SUCCESS (1.351s)
[611.223427] [110/134] (433s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[611.314617] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[612.405403] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SUCCESS (1.090s)
[612.543416] [111/134] (432s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[612.635989] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[613.803315] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SUCCESS (1.167s)
[613.926716] [112/134] (430s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[614.016005] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[614.036587] Starting dynamic subtest: pipe-A-plane-0
[614.037933] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[614.045777] Starting dynamic subtest: pipe-A-plane-3
[614.047110] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[614.054749] Starting dynamic subtest: pipe-A-plane-5
[614.056070] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[614.063780] Starting dynamic subtest: pipe-A-plane-7
[614.065087] Dynamic subtest pipe-A-plane-7: SKIP (0.000s)
[614.193789] Starting dynamic subtest: pipe-B-plane-0
[614.195244] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[614.206016] Starting dynamic subtest: pipe-B-plane-3
[614.207465] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[614.218040] Starting dynamic subtest: pipe-B-plane-5
[614.219460] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[614.230611] Starting dynamic subtest: pipe-B-plane-7
[614.232070] Dynamic subtest pipe-B-plane-7: SKIP (0.000s)
[614.275442] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.259s)
[614.407409] [113/134] (430s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[614.496584] Starting subtest: plane-downscale-factor-0-5-with-rotation
[614.497424] Starting dynamic subtest: pipe-A
[615.613016] Dynamic subtest pipe-A: SKIP (1.115s)
[615.621118] Starting dynamic subtest: pipe-B
[616.743403] Dynamic subtest pipe-B: SKIP (1.130s)
[616.751291] Starting dynamic subtest: pipe-C
[617.884480] Dynamic subtest pipe-C: SKIP (1.140s)
[617.892363] Starting dynamic subtest: pipe-D
[619.014959] Dynamic subtest pipe-D: SKIP (1.129s)
[619.016494] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (4.519s)
[619.202512] [114/134] (425s left) perf_pmu (busy-hang)
[619.247442] Starting subtest: busy-hang
[619.248200] Starting dynamic subtest: rcs0
[620.297169] Dynamic subtest rcs0: SUCCESS (1.048s)
[620.305632] Starting dynamic subtest: bcs0
[621.349931] Dynamic subtest bcs0: SUCCESS (1.052s)
[621.363900] Starting dynamic subtest: vcs0
[622.402781] Dynamic subtest vcs0: SUCCESS (1.049s)
[622.413841] Starting dynamic subtest: vcs1
[623.456683] Dynamic subtest vcs1: SUCCESS (1.051s)
[623.468761] Starting dynamic subtest: vecs0
[624.506840] Dynamic subtest vecs0: SUCCESS (1.047s)
[624.510158] Subtest busy-hang: SUCCESS (5.260s)
[624.581915] [115/134] (420s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[624.671078] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[624.671813] Starting dynamic subtest: pipe-A-HDMI-A-1
[624.678457] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[624.686295] Starting dynamic subtest: pipe-B-HDMI-A-1
[624.692992] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[624.700548] Starting dynamic subtest: pipe-C-HDMI-A-1
[624.707226] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[624.714767] Starting dynamic subtest: pipe-D-HDMI-A-1
[624.721523] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[624.722870] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SKIP (0.009s)
[624.915219] [116/134] (420s left) kms_cursor_crc (cursor-offscreen-128x42)
[625.004976] Starting subtest: cursor-offscreen-128x42
[625.009344] Starting dynamic subtest: pipe-A-HDMI-A-1
[626.275016] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.266s)
[626.313825] Starting dynamic subtest: pipe-D-HDMI-A-1
[627.529146] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.224s)
[627.529922] Subtest cursor-offscreen-128x42: SUCCESS (2.525s)
[627.690915] [117/134] (417s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[627.782073] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[627.782757] Starting dynamic subtest: pipe-A
[627.908159] Dynamic subtest pipe-A: SUCCESS (0.127s)
[627.919476] Starting dynamic subtest: pipe-B
[628.067698] Dynamic subtest pipe-B: SUCCESS (0.158s)
[628.076785] Starting dynamic subtest: pipe-C
[628.201817] Dynamic subtest pipe-C: SUCCESS (0.133s)
[628.213294] Starting dynamic subtest: pipe-D
[628.328103] Dynamic subtest pipe-D: SUCCESS (0.125s)
[628.328870] Subtest planes-upscale-20x20-downscale-factor-0-75: SUCCESS (0.547s)
[628.522677] [118/134] (416s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[628.621076] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[629.220688] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (0.599s)
[629.362059] [119/134] (415s left) syncobj_basic (bad-pad-handle-to-fd)
[629.394009] Starting subtest: bad-pad-handle-to-fd
[629.394713] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[629.486991] [120/134] (415s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[629.574485] Starting subtest: cursorA-vs-flipB-atomic-transitions
[629.577349] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[629.663039] [121/134] (415s left) kms_frontbuffer_tracking (psr-2p-rte)
[629.750850] Starting subtest: psr-2p-rte
[629.752177] Subtest psr-2p-rte: SKIP (0.000s)
[629.842500] [122/134] (415s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[629.931870] Starting subtest: psr-shrfb-scaledprimary
[629.933186] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[630.035868] [123/134] (415s left) gen7_exec_parse (cmd-crossing-page)
[630.072392] Subtest cmd-crossing-page: SKIP (0.000s)
[630.122007] [124/134] (415s left) kms_frontbuffer_tracking (fbc-1p-rte)
[630.212871] Starting subtest: fbc-1p-rte
[632.975062] Subtest fbc-1p-rte: SUCCESS (2.763s)
[633.123508] [125/134] (412s left) gem_mmap_wc (write)
[633.156602] Starting subtest: write
[633.191290] Subtest write: SUCCESS (0.035s)
[633.309302] [126/134] (412s left) kms_busy (extended-modeset-hang-newfb)
[633.400589] Starting subtest: extended-modeset-hang-newfb
[633.402774] Starting dynamic subtest: pipe-A
[657.869032] Dynamic subtest pipe-A: SUCCESS (24.465s)
[657.881011] Starting dynamic subtest: pipe-D
[681.915390] Dynamic subtest pipe-D: SUCCESS (24.043s)
[681.916132] Subtest extended-modeset-hang-newfb: SUCCESS (48.515s)
[682.047456] [127/134] (363s left) i915_pm_rpm (gem-evict-pwrite)
[683.450071] Starting subtest: gem-evict-pwrite
[684.744712] Subtest gem-evict-pwrite: SUCCESS (1.295s)
[684.919146] [128/134] (360s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[685.015113] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[685.016867] Starting dynamic subtest: pipe-A-HDMI-A-1
[685.022728] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.001s)
[685.030542] Starting dynamic subtest: pipe-B-HDMI-A-1
[685.036392] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.000s)
[685.044214] Starting dynamic subtest: pipe-C-HDMI-A-1
[685.050010] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.000s)
[685.057835] Starting dynamic subtest: pipe-D-HDMI-A-1
[685.063562] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.001s)
[685.064235] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SUCCESS (0.009s)
[685.268271] [129/134] (360s left) i915_suspend (basic-s2idle-without-i915)
[685.299698] Starting subtest: basic-s2idle-without-i915
[703.598776] Subtest basic-s2idle-without-i915: SUCCESS (3.139s)
[710.679859] [130/134] (341s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[710.770414] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[710.772177] Starting dynamic subtest: pipe-A-valid-mode
[711.756490] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.985s)
[711.757209] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (0.987s)
[711.905203] [131/134] (340s left) kms_big_fb (4-tiled-addfb)
[712.007566] Starting subtest: 4-tiled-addfb
[712.008851] Subtest 4-tiled-addfb: SKIP (0.000s)
[712.107010] [132/134] (340s left) kms_setmode (basic-clone-single-crtc)
[712.139647] Starting subtest: basic-clone-single-crtc
[712.142173] Subtest basic-clone-single-crtc: SKIP (0.000s)
[712.234401] [133/134] (340s left) gem_exec_balancer (full-late-pulse)
[712.277055] Starting subtest: full-late-pulse
[713.896451] Subtest full-late-pulse: SUCCESS (1.617s)
[714.125029] [134/134] (338s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[714.216377] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[714.217774] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[714.332224] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_params
results: parsing output: 2/ for test: tools_test
results: parsing output: 3/ for test: kms_selftest
results: parsing output: 4/ for test: gem_exec_suspend
results: parsing output: 5/ for test: kms_content_protection
results: parsing output: 6/ for test: gem_eio
results: parsing output: 7/ for test: gem_spin_batch
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_psr2_sf
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: gem_ctx_exec
results: parsing output: 15/ for test: gem_pxp
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: prime_mmap
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: api_intel_allocator
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_psr2_sf
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: gem_exec_create
results: parsing output: 25/ for test: gem_ctx_persistence
results: parsing output: 26/ for test: gem_ctx_bad_destroy
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_atomic_interruptible
results: parsing output: 29/ for test: kms_cdclk
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_selftest
results: parsing output: 35/ for test: syncobj_basic
results: parsing output: 36/ for test: gem_lmem_swapping
results: parsing output: 37/ for test: kms_mmap_write_crc
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_ctx_exec
results: parsing output: 42/ for test: kms_atomic
results: parsing output: 43/ for test: gem_set_tiling_vs_gtt
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_getfb
results: parsing output: 46/ for test: syncobj_timeline
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_basic
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_wait
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: kms_dsc
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_vblank
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: kms_chamelium_hpd
results: parsing output: 60/ for test: gem_exec_params
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: prime_vgem
results: parsing output: 64/ for test: gem_exec_capture
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: i915_pm_freq_api
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_chamelium_frames
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: kms_color_pipeline
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_mmap_offset
results: parsing output: 79/ for test: gem_pread
results: parsing output: 80/ for test: kms_joiner
results: parsing output: 81/ for test: i915_drm_fdinfo
results: parsing output: 82/ for test: gem_ccs
results: parsing output: 83/ for test: kms_plane
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_flip_scaled_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_draw_crc
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: api_intel_bb
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: gem_exercise_blt
results: parsing output: 96/ for test: sysfs_heartbeat_interval
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: gem_exec_schedule
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: gem_ctx_shared
results: parsing output: 102/ for test: gem_exec_fence
results: parsing output: 103/ for test: gem_render_copy
results: parsing output: 104/ for test: drm_read
results: parsing output: 105/ for test: kms_atomic_transition
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_pipe_crc_basic
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_plane_scaling
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_plane_scaling
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: syncobj_basic
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gen7_exec_parse
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_mmap_wc
results: parsing output: 125/ for test: kms_busy
results: parsing output: 126/ for test: i915_pm_rpm
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: i915_suspend
results: parsing output: 129/ for test: kms_flip_scaled_crc
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_setmode
results: parsing output: 132/ for test: gem_exec_balancer
results: parsing output: 133/ for test: kms_frontbuffer_tracking
Done.