[68.224762] Initializing watchdogs
[68.224809]   /dev/watchdog0
[68.238721] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[68.243206] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[68.245442] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.248945] [001/134] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[68.383960] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[75.660522] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.429s)
[75.786382] [002/134] (952s left) gem_exec_params (secure-non-master)
[75.860533] Starting subtest: secure-non-master
[75.864712] Subtest secure-non-master: SKIP (0.000s)
[75.974947] [003/134] (952s left) tools_test (tools_test)
[76.028705] Starting subtest: tools_test
[76.084474] Subtest tools_test: SUCCESS (0.069s)
[76.157100] [004/134] (952s left) kms_selftest (drm_cmdline_parser)
[76.190511] Starting subtest: drm_cmdline_parser
[76.200825] Starting dynamic subtest: drm_test_cmdline_force_d_only
[76.336361] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.137s)
[76.345232] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[76.480300] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.147s)
[76.489048] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[76.634112] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.156s)
[76.641886] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[76.765365] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.133s)
[76.773228] Starting dynamic subtest: drm_test_cmdline_force_e_only
[76.917048] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.154s)
[76.928432] Starting dynamic subtest: drm_test_cmdline_res
[77.069191] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.154s)
[77.081256] Starting dynamic subtest: drm_test_cmdline_res_vesa
[77.162151] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.095s)
[77.170424] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[77.307076] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.146s)
[77.315650] Starting dynamic subtest: drm_test_cmdline_res_rblank
[77.429291] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.124s)
[77.437669] Starting dynamic subtest: drm_test_cmdline_res_bpp
[77.521787] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.095s)
[77.528415] Starting dynamic subtest: drm_test_cmdline_res_refresh
[77.614138] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.093s)
[77.622786] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[77.750895] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.138s)
[77.757487] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[77.878311] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.130s)
[77.893279] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[78.025589] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.147s)
[78.037978] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[78.181438] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.158s)
[78.193617] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[78.329226] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.151s)
[78.340982] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[78.475175] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.147s)
[78.482153] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[78.623044] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.150s)
[78.631872] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[78.747254] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.125s)
[78.755867] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[78.888127] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.142s)
[78.896477] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[79.012842] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.126s)
[79.019141] Starting dynamic subtest: drm_test_cmdline_name
[79.164805] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.154s)
[79.171120] Starting dynamic subtest: drm_test_cmdline_name_bpp
[79.299227] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.136s)
[79.307460] Starting dynamic subtest: drm_test_cmdline_name_option
[79.418871] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.121s)
[79.426144] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[79.572155] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.154s)
[79.579629] Starting dynamic subtest: drm_test_cmdline_rotate_0
[79.724184] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.152s)
[79.732316] Starting dynamic subtest: drm_test_cmdline_rotate_90
[79.865857] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.143s)
[79.872239] Starting dynamic subtest: drm_test_cmdline_rotate_180
[80.018086] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.154s)
[80.025519] Starting dynamic subtest: drm_test_cmdline_rotate_270
[80.165099] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.148s)
[80.174012] Starting dynamic subtest: drm_test_cmdline_hmirror
[80.306195] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.142s)
[80.314825] Starting dynamic subtest: drm_test_cmdline_vmirror
[80.439571] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.133s)
[80.446247] Starting dynamic subtest: drm_test_cmdline_margin_options
[80.586272] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.148s)
[80.593852] Starting dynamic subtest: drm_test_cmdline_multiple_options
[80.729926] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.146s)
[80.737050] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[80.844377] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.113s)
[80.852544] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[80.992246] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.149s)
[80.999846] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[81.133110] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.142s)
[81.141673] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[81.262040] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.130s)
[81.268971] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[81.405263] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.144s)
[81.413853] Starting dynamic subtest: drm_test_cmdline_invalid
[81.551070] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.148s)
[81.558833] Starting dynamic subtest: drm_test_cmdline_tv_options
[81.704155] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.156s)
[81.706593] Subtest drm_cmdline_parser: SUCCESS (5.586s)
[81.939823] [005/134] (946s left) gem_exec_suspend (basic-s0)
[82.005038] Starting subtest: basic-S0
[82.007732] Starting dynamic subtest: smem
[103.098022] Dynamic subtest smem: SUCCESS (8.027s)
[103.100282] Subtest basic-S0: SUCCESS (8.028s)
[103.293079] [006/134] (925s left) kms_content_protection (uevent)
[103.443302] Starting subtest: uevent
[103.447799] Subtest uevent: SKIP (0.000s)
[103.602155] [007/134] (925s left) gem_eio (in-flight-suspend)
[103.843716] Starting subtest: in-flight-suspend
[146.332908] Subtest in-flight-suspend: SUCCESS (13.460s)
[146.516090] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[146.519423] [008/134] (882s left) gem_spin_batch (resubmit-all)
[146.590744] Starting subtest: resubmit-all
[146.593127] Starting dynamic subtest: rcs0
[146.600880] Dynamic subtest rcs0: SUCCESS (0.010s)
[146.612870] Starting dynamic subtest: bcs0
[146.615156] Dynamic subtest bcs0: SUCCESS (0.002s)
[146.623244] Starting dynamic subtest: vcs0
[146.625395] Dynamic subtest vcs0: SUCCESS (0.002s)
[146.633280] Starting dynamic subtest: vcs1
[146.635450] Dynamic subtest vcs1: SUCCESS (0.003s)
[146.643470] Starting dynamic subtest: vecs0
[146.645632] Dynamic subtest vecs0: SUCCESS (0.003s)
[146.653860] Starting dynamic subtest: ccs0
[146.656065] Dynamic subtest ccs0: SUCCESS (0.002s)
[146.658281] Subtest resubmit-all: SUCCESS (0.023s)
[148.346640] [009/134] (880s left) kms_psr (psr-cursor-plane-onoff)
[148.450532] Starting subtest: psr-cursor-plane-onoff
[148.452768] Starting dynamic subtest: eDP-1
[150.140663] Dynamic subtest eDP-1: SUCCESS (1.697s)
[150.143112] Subtest psr-cursor-plane-onoff: SUCCESS (1.697s)
[151.018707] [010/134] (878s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[151.134924] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[157.961608] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.979s)
[158.142887] [011/134] (871s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[158.264012] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[158.268327] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[158.394797] [012/134] (870s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[163.423273] Starting subtest: fbc-psr2-cursor-plane-update-sf
[163.425579] Starting dynamic subtest: pipe-A-eDP-1
[164.221117] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s)
[164.231500] Starting dynamic subtest: pipe-B-eDP-1
[165.337970] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[165.342455] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.926s)
[166.524185] [013/134] (862s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[171.571811] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[171.574196] Starting dynamic subtest: pipe-A-eDP-1
[178.176447] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.614s)
[178.186436] Starting dynamic subtest: pipe-B-eDP-1
[184.853845] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.678s)
[184.856027] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.296s)
[185.723235] [014/134] (843s left) kms_pm_rpm (basic-pci-d3-state)
[187.381698] Starting subtest: basic-pci-d3-state
[188.867683] Subtest basic-pci-d3-state: SUCCESS (1.488s)
[189.006237] [015/134] (840s left) gem_ctx_exec (basic-norecovery)
[189.065888] Starting subtest: basic-norecovery
[189.425084] Subtest basic-norecovery: SUCCESS (0.360s)
[189.585668] [016/134] (839s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[189.718802] Starting subtest: protected-encrypted-src-copy-not-readible
[189.831710] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.113s)
[190.008373] [017/134] (839s left) kms_flip (absolute-wf_vblank)
[190.100555] Starting subtest: absolute-wf_vblank
[190.102812] Starting dynamic subtest: A-eDP1
[198.871917] Dynamic subtest A-eDP1: SUCCESS (8.772s)
[198.882547] Starting dynamic subtest: B-eDP1
[207.609113] Dynamic subtest B-eDP1: SUCCESS (8.737s)
[207.620613] Starting dynamic subtest: C-eDP1
[216.325009] Dynamic subtest C-eDP1: SUCCESS (8.716s)
[216.336430] Starting dynamic subtest: D-eDP1
[225.051668] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[225.054002] Subtest absolute-wf_vblank: SUCCESS (34.962s)
[226.287869] [018/134] (803s left) prime_mmap (test_refcounting)
[226.353399] Starting subtest: test_refcounting
[226.355595] Starting dynamic subtest: test_refcounting-smem
[226.384795] Dynamic subtest test_refcounting-smem: SUCCESS (0.033s)
[226.387250] Subtest test_refcounting: SUCCESS (0.034s)
[226.526525] [019/134] (802s left) gem_exec_schedule (pi-common)
[226.711247] Starting subtest: pi-common
[226.713264] Starting dynamic subtest: rcs0
[226.741898] Dynamic subtest rcs0: SUCCESS (0.033s)
[226.750261] Starting dynamic subtest: bcs0
[226.773058] Dynamic subtest bcs0: SUCCESS (0.030s)
[226.781780] Starting dynamic subtest: vcs0
[226.803582] Dynamic subtest vcs0: SUCCESS (0.030s)
[226.815587] Starting dynamic subtest: vcs1
[226.832689] Dynamic subtest vcs1: SUCCESS (0.029s)
[226.844249] Starting dynamic subtest: vecs0
[226.860496] Dynamic subtest vecs0: SUCCESS (0.027s)
[226.873851] Starting dynamic subtest: ccs0
[226.886176] Dynamic subtest ccs0: SUCCESS (0.025s)
[226.888438] Subtest pi-common: SUCCESS (0.178s)
[227.150209] [020/134] (802s left) perf (unprivileged-single-ctx-counters)
[227.278886] Starting subtest: unprivileged-single-ctx-counters
[227.282917] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[227.324130] [021/134] (802s left) api_intel_allocator (fork-simple-stress-signal)
[227.392832] Starting subtest: fork-simple-stress-signal
[232.794604] Subtest fork-simple-stress-signal: SUCCESS (5.403s)
[232.965043] [022/134] (796s left) kms_flip (flip-vs-panning-vs-hang)
[233.071372] Starting subtest: flip-vs-panning-vs-hang
[233.073574] Starting dynamic subtest: A-eDP1
[272.754271] Dynamic subtest A-eDP1: SUCCESS (39.680s)
[272.766112] Starting dynamic subtest: D-eDP1
[312.747523] Dynamic subtest D-eDP1: SUCCESS (39.994s)
[312.749948] Subtest flip-vs-panning-vs-hang: SUCCESS (79.678s)
[313.970753] [023/134] (715s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[318.938443] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[318.940689] Starting dynamic subtest: pipe-A-eDP-1
[330.962676] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.027s)
[330.971898] Starting dynamic subtest: pipe-B-eDP-1
[342.976622] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.013s)
[342.978888] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.045s)
[343.841700] [024/134] (685s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[343.906382] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[343.908643] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[344.028911] [025/134] (685s left) gem_exec_create (basic)
[344.085945] Starting subtest: basic
[344.088276] Starting dynamic subtest: smem
[346.187727] Dynamic subtest smem: SUCCESS (2.102s)
[346.190043] Subtest basic: SUCCESS (2.103s)
[346.386052] [026/134] (683s left) gem_ctx_persistence (smoketest)
[346.452809] Starting subtest: smoketest
[387.172291] Subtest smoketest: SUCCESS (40.722s)
[387.277415] [027/134] (642s left) gem_ctx_bad_destroy (invalid-pad)
[387.337066] Starting subtest: invalid-pad
[387.340123] Subtest invalid-pad: SUCCESS (0.000s)
[387.426892] [028/134] (642s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[387.568684] Starting subtest: cursorA-vs-flipB-legacy
[387.573236] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[387.748120] [029/134] (642s left) kms_atomic_interruptible (legacy-setmode)
[387.847234] Starting subtest: legacy-setmode
[387.849478] Starting dynamic subtest: pipe-A-eDP-1
[394.764547] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.914s)
[394.766892] Subtest legacy-setmode: SUCCESS (6.916s)
[395.634845] [030/134] (634s left) kms_cdclk (mode-transition)
[395.749186] Starting subtest: mode-transition
[395.751505] Starting dynamic subtest: pipe-A-eDP-1
[396.039526] Dynamic subtest pipe-A-eDP-1: SKIP (0.287s)
[396.049101] Starting dynamic subtest: pipe-B-eDP-1
[396.053771] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[396.062299] Starting dynamic subtest: pipe-C-eDP-1
[396.066902] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[396.075262] Starting dynamic subtest: pipe-D-eDP-1
[396.079371] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[396.083414] Subtest mode-transition: SKIP (0.295s)
[396.873923] [031/134] (633s left) kms_big_fb (linear-8bpp-rotate-270)
[396.973952] Starting subtest: linear-8bpp-rotate-270
[396.994796] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[397.132218] [032/134] (632s left) perf_pmu (faulting-read)
[397.178662] Starting subtest: faulting-read
[397.180840] Starting dynamic subtest: wb
[397.182914] Dynamic subtest wb: SUCCESS (0.002s)
[397.192283] Starting dynamic subtest: wc
[397.194399] Dynamic subtest wc: SUCCESS (0.000s)
[397.203114] Starting dynamic subtest: uc
[397.205245] Dynamic subtest uc: SUCCESS (0.000s)
[397.207298] Subtest faulting-read: SUCCESS (0.005s)
[397.305694] [033/134] (632s left) kms_psr (fbc-psr2-primary-render)
[397.401095] Starting subtest: fbc-psr2-primary-render
[397.403313] Starting dynamic subtest: eDP-1
[397.407359] Dynamic subtest eDP-1: SKIP (0.000s)
[397.411475] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[397.506695] [034/134] (632s left) gem_exec_schedule (deep)
[397.713164] Starting subtest: deep
[397.715639] Starting dynamic subtest: rcs0
[402.953706] Dynamic subtest rcs0: SKIP (5.236s)
[402.965964] Starting dynamic subtest: vcs0
[414.128808] Dynamic subtest vcs0: SUCCESS (11.173s)
[414.141610] Starting dynamic subtest: vcs1
[425.611913] Dynamic subtest vcs1: SUCCESS (11.480s)
[425.623820] Starting dynamic subtest: vecs0
[437.229224] Dynamic subtest vecs0: SUCCESS (11.615s)
[437.240261] Starting dynamic subtest: ccs0
[450.221775] Dynamic subtest ccs0: SUCCESS (12.990s)
[450.224240] Subtest deep: SUCCESS (52.500s)
[451.343245] [035/134] (578s left) kms_selftest (drm_plane_helper)
[451.382490] Starting subtest: drm_plane_helper
[451.427586] Starting dynamic subtest: drm_test_check_plane_state
[451.622620] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.193s)
[451.639004] Starting dynamic subtest: drm_test_check_invalid_plane_state
[451.761266] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.139s)
[451.763492] Subtest drm_plane_helper: SUCCESS (0.379s)
[451.971411] [036/134] (578s left) syncobj_basic (bad-pad-fd-to-handle)
[452.031883] Starting subtest: bad-pad-fd-to-handle
[452.033872] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[452.172490] [037/134] (578s left) gem_lmem_swapping (parallel-random-engines)
[452.216804] Subtest parallel-random-engines: SKIP (0.000s)
[452.262964] [038/134] (578s left) kms_mmap_write_crc (main)
[452.367954] Starting subtest: main
[452.370277] Starting dynamic subtest: pipe-A-eDP-1
[455.970142] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.599s)
[455.972363] Subtest main: SUCCESS (3.601s)
[456.806316] [039/134] (573s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[456.900346] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[463.830356] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.934s)
[464.012585] [040/134] (566s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[464.109322] Starting subtest: addfb25-yf-tiled-legacy
[464.111571] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[464.241832] [041/134] (566s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[464.346254] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[464.350179] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[464.474992] [042/134] (565s left) gem_ctx_exec (basic)
[464.532214] Starting subtest: basic
[464.536672] Subtest basic: SUCCESS (0.006s)
[464.698979] [043/134] (565s left) kms_atomic (test-only)
[464.789286] Starting subtest: test-only
[464.841035] Starting dynamic subtest: pipe-A-eDP-1
[467.345490] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.502s)
[467.347690] Subtest test-only: SUCCESS (2.556s)
[468.229583] [044/134] (562s left) gem_set_tiling_vs_gtt
[468.358369] [045/134] (562s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[468.462665] Starting subtest: yf-tiled-16bpp-rotate-0
[468.466995] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[468.587553] [046/134] (562s left) kms_getfb (getfb2-handle-zero)
[468.686423] Starting subtest: getfb2-handle-zero
[468.688682] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[468.827618] [047/134] (561s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[468.895754] Starting subtest: single-wait-all-for-submit-available-submitted
[468.898086] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[469.021477] [048/134] (561s left) gem_exec_reloc (basic-wc-cpu)
[469.086780] Subtest basic-wc-cpu: SKIP (0.000s)
[469.156595] [049/134] (561s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[469.261008] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[476.373358] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.114s)
[476.558164] [050/134] (554s left) gem_basic (multigpu-create-close)
[476.612225] Starting subtest: multigpu-create-close
[476.616333] Subtest multigpu-create-close: SKIP (0.000s)
[476.733124] [051/134] (554s left) kms_frontbuffer_tracking (fbc-suspend)
[476.822458] Starting subtest: fbc-suspend
[515.372755] Subtest fbc-suspend: SUCCESS (7.503s)
[516.547435] [052/134] (514s left) gem_wait (wait)
[516.620137] Starting subtest: wait
[516.622565] Starting dynamic subtest: all-engines
[518.389225] Dynamic subtest all-engines: SUCCESS (1.801s)
[518.401508] Starting dynamic subtest: rcs0
[519.433293] Dynamic subtest rcs0: SUCCESS (1.059s)
[519.446045] Starting dynamic subtest: bcs0
[520.480875] Dynamic subtest bcs0: SUCCESS (1.059s)
[520.493181] Starting dynamic subtest: vcs0
[521.524977] Dynamic subtest vcs0: SUCCESS (1.054s)
[521.536634] Starting dynamic subtest: vcs1
[522.570311] Dynamic subtest vcs1: SUCCESS (1.055s)
[522.582101] Starting dynamic subtest: vecs0
[523.614742] Dynamic subtest vecs0: SUCCESS (1.052s)
[523.626930] Starting dynamic subtest: ccs0
[524.661567] Dynamic subtest ccs0: SUCCESS (1.053s)
[524.664361] Subtest wait: SUCCESS (8.148s)
[524.884342] [053/134] (506s left) gem_exec_reloc (basic-wc-active)
[524.953027] Subtest basic-wc-active: SKIP (0.000s)
[525.032164] [054/134] (505s left) kms_pm_rpm (pc8-residency)
[526.552944] Starting subtest: pc8-residency
[526.557716] Subtest pc8-residency: SKIP (0.000s)
[527.287953] [055/134] (503s left) kms_dsc (dsc-with-bpc-formats)
[527.390586] Starting subtest: dsc-with-bpc-formats
[527.499596] Subtest dsc-with-bpc-formats: SKIP (0.004s)
[527.538379] [056/134] (503s left) kms_psr (pr-cursor-plane-move)
[527.629310] Starting subtest: pr-cursor-plane-move
[527.633354] Subtest pr-cursor-plane-move: SKIP (0.000s)
[527.711787] [057/134] (503s left) kms_vblank (ts-continuation-dpms-suspend)
[527.811216] Starting subtest: ts-continuation-dpms-suspend
[527.813437] Starting dynamic subtest: pipe-A-eDP-1
[545.524696] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.838s)
[545.540593] Starting dynamic subtest: pipe-D-eDP-1
[563.545210] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.515s)
[563.547583] Subtest ts-continuation-dpms-suspend: SUCCESS (4.357s)
[564.351549] [058/134] (466s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[564.415020] Starting subtest: multi-wait-available-unsubmitted-signaled
[564.417317] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[564.532694] [059/134] (466s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[564.664263] Starting subtest: basic-flip-before-cursor-legacy
[565.549426] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.914s)
[566.414493] [060/134] (464s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[566.785989] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[567.576380] [061/134] (463s left) gem_exec_params (invalid-flag)
[567.637322] Starting subtest: invalid-flag
[567.639658] Subtest invalid-flag: SUCCESS (0.000s)
[567.773765] [062/134] (463s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[567.860440] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[567.864544] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[567.994950] [063/134] (463s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[568.053881] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[568.056128] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[568.162468] [064/134] (463s left) prime_vgem (wait)
[568.229999] Starting subtest: wait
[568.232180] Starting dynamic subtest: rcs0
[568.275970] Dynamic subtest rcs0: SUCCESS (0.047s)
[568.288102] Starting dynamic subtest: bcs0
[568.352927] Dynamic subtest bcs0: SUCCESS (0.078s)
[568.362074] Starting dynamic subtest: vcs0
[568.399109] Dynamic subtest vcs0: SUCCESS (0.046s)
[568.408160] Starting dynamic subtest: vcs1
[568.443109] Dynamic subtest vcs1: SUCCESS (0.044s)
[568.452377] Starting dynamic subtest: vecs0
[568.490998] Dynamic subtest vecs0: SUCCESS (0.048s)
[568.500205] Starting dynamic subtest: ccs0
[568.534657] Dynamic subtest ccs0: SUCCESS (0.044s)
[568.536509] Subtest wait: SUCCESS (0.312s)
[568.684044] [065/134] (462s left) gem_exec_capture (capture)
[568.741323] Starting subtest: capture
[568.743396] Starting dynamic subtest: rcs0-smem
[569.556306] Dynamic subtest rcs0-smem: SUCCESS (0.827s)
[569.567281] Starting dynamic subtest: bcs0-smem
[570.872982] Dynamic subtest bcs0-smem: SUCCESS (1.338s)
[570.883908] Starting dynamic subtest: vcs0-smem
[571.681589] Dynamic subtest vcs0-smem: SUCCESS (0.820s)
[571.690665] Starting dynamic subtest: vcs1-smem
[572.487090] Dynamic subtest vcs1-smem: SUCCESS (0.818s)
[572.498424] Starting dynamic subtest: vecs0-smem
[573.880784] Dynamic subtest vecs0-smem: FAIL (1.354s)
[573.892124] Starting dynamic subtest: ccs0-smem
[575.147230] Dynamic subtest ccs0-smem: SUCCESS (1.331s)
[575.149548] Subtest capture: FAIL (6.492s)
[575.314658] [066/134] (456s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[575.597964] Starting subtest: cursor-rapid-movement-512x512
[575.602430] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[575.792038] [067/134] (455s left) kms_flip (2x-flip-vs-wf_vblank)
[575.887182] Starting subtest: 2x-flip-vs-wf_vblank
[575.896999] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[575.993881] [068/134] (455s left) i915_pm_freq_api (freq-suspend)
[576.057394] Starting subtest: freq-suspend
[576.059668] Starting dynamic subtest: gt0
[592.970533] Dynamic subtest gt0: SUCCESS (1.219s)
[592.988935] Starting dynamic subtest: gt1
[608.989872] Dynamic subtest gt1: SUCCESS (1.215s)
[608.991740] Subtest freq-suspend: SUCCESS (2.437s)
[609.085048] [069/134] (422s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[609.195876] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[609.200125] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[609.326152] [070/134] (422s left) kms_chamelium_frames (hdmi-aspect-ratio)
[609.724109] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[610.515411] [071/134] (421s left) kms_cursor_crc (cursor-sliding-max-size)
[610.810031] Starting subtest: cursor-sliding-max-size
[610.816280] Subtest cursor-sliding-max-size: SKIP (0.000s)
[610.947160] [072/134] (420s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[611.040834] Starting subtest: plane-ctm3x4-lut1d
[611.054225] Starting dynamic subtest: pipe-A-eDP-1
[611.702556] Dynamic subtest pipe-A-eDP-1: FAIL (0.327s)
[612.451352] Starting dynamic subtest: pipe-B-eDP-1
[613.255089] Dynamic subtest pipe-B-eDP-1: FAIL (0.311s)
[613.852259] Starting dynamic subtest: pipe-C-eDP-1
[614.652127] Dynamic subtest pipe-C-eDP-1: FAIL (0.246s)
[615.160899] Starting dynamic subtest: pipe-D-eDP-1
[615.991869] Dynamic subtest pipe-D-eDP-1: FAIL (0.267s)
[615.994182] Subtest plane-ctm3x4-lut1d: FAIL (4.768s)
[616.546014] [073/134] (415s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[616.648600] Starting subtest: psr-1p-pri-indfb-multidraw
[639.715798] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (23.179s)
[639.874303] [074/134] (391s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[639.975503] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[639.978936] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[640.112187] [075/134] (391s left) syncobj_timeline (invalid-query-illegal-handle)
[640.175496] Starting subtest: invalid-query-illegal-handle
[640.177468] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[640.302837] [076/134] (391s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[640.393174] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[640.394793] Starting dynamic subtest: pipe-A-eDP-1
[642.639990] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.247s)
[642.649432] Starting dynamic subtest: pipe-B-eDP-1
[645.066249] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.425s)
[645.075653] Starting dynamic subtest: pipe-C-eDP-1
[647.241039] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.173s)
[647.250991] Starting dynamic subtest: pipe-D-eDP-1
[649.370208] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.128s)
[649.372060] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.978s)
[650.593819] [077/134] (381s left) kms_addfb_basic (too-high)
[650.705425] Starting subtest: too-high
[650.707725] Subtest too-high: SUCCESS (0.001s)
[650.832861] [078/134] (381s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[650.936099] Starting subtest: psr-rgb565-draw-mmap-gtt
[650.940465] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[651.053070] [079/134] (380s left) gem_mmap_offset (mmap-unmap)
[651.109102] Starting subtest: mmap-unmap
[651.111342] Starting dynamic subtest: smem0
[674.088171] Dynamic subtest smem0: SUCCESS (22.983s)
[674.091050] Subtest mmap-unmap: SUCCESS (22.985s)
[674.243173] [080/134] (357s left) gem_pread (exhaustion)
[674.316303] Subtest exhaustion: SKIP (0.000s)
[674.384676] [081/134] (357s left) kms_joiner (invalid-modeset-ultra-joiner)
[674.477716] Starting subtest: invalid-modeset-ultra-joiner
[674.481651] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[674.557979] [082/134] (357s left) i915_drm_fdinfo (basics)
[674.600959] Starting subtest: basics
[674.603348] Subtest basics: SUCCESS (0.000s)
[674.683664] [083/134] (357s left) gem_ccs (ctrl-surf-copy-new-ctx)
[674.740019] Starting subtest: ctrl-surf-copy-new-ctx
[674.744608] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[674.857270] [084/134] (357s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[674.954316] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[674.980995] Starting dynamic subtest: pipe-A-plane-0
[675.580132] Dynamic subtest pipe-A-plane-0: SUCCESS (0.600s)
[675.589125] Starting dynamic subtest: pipe-A-plane-3
[676.013368] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[676.022625] Starting dynamic subtest: pipe-A-plane-5
[676.026143] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[677.167836] Starting dynamic subtest: pipe-B-plane-0
[677.849768] Dynamic subtest pipe-B-plane-0: SUCCESS (0.689s)
[677.859651] Starting dynamic subtest: pipe-B-plane-3
[678.237199] Dynamic subtest pipe-B-plane-3: SUCCESS (0.388s)
[678.253294] Starting dynamic subtest: pipe-B-plane-5
[678.257429] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[678.556913] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.604s)
[679.447770] [085/134] (352s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[679.560029] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[679.563977] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[679.667124] [086/134] (352s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[679.761442] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[679.763680] Starting dynamic subtest: pipe-A-default-mode
[680.908206] Dynamic subtest pipe-A-default-mode: SKIP (1.146s)
[680.912834] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.148s)
[681.089477] [087/134] (351s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[681.176267] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[681.180385] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[681.296669] [088/134] (351s left) kms_draw_crc (fill-fb)
[681.378388] Starting subtest: fill-fb
[681.852844] Subtest fill-fb: SUCCESS (0.476s)
[681.981035] [089/134] (350s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[682.092020] Starting subtest: y-tiled-8bpp-rotate-0
[682.095501] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[682.233618] [090/134] (350s left) gem_render_copy (yf-tiled-to-vebox-linear)
[682.296488] Starting subtest: yf-tiled-to-vebox-linear
[682.300347] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[682.493199] [091/134] (350s left) syncobj_wait (invalid-reset-illegal-handle)
[682.555786] Starting subtest: invalid-reset-illegal-handle
[682.558057] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[682.687010] [092/134] (349s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[682.786907] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[689.732798] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.952s)
[689.894052] [093/134] (342s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[690.008893] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[690.013236] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[690.129697] [094/134] (342s left) api_intel_bb (full-batch)
[690.182825] Starting subtest: full-batch
[690.187709] Subtest full-batch: SUCCESS (0.005s)
[690.328612] [095/134] (342s left) kms_psr (fbc-psr-basic)
[690.414300] Starting subtest: fbc-psr-basic
[690.416557] Starting dynamic subtest: eDP-1
[690.420653] Dynamic subtest eDP-1: SKIP (0.000s)
[690.424727] Subtest fbc-psr-basic: SKIP (0.001s)
[690.500232] [096/134] (342s left) gem_exercise_blt (fast-copy-emit)
[690.569615] Starting subtest: fast-copy-emit
[690.571508] Starting dynamic subtest: linear-smem-smem-emit
[690.591628] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.024s)
[690.602039] Starting dynamic subtest: xmajor-smem-smem-emit
[690.610093] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.018s)
[690.619426] Starting dynamic subtest: tile4-smem-smem-emit
[690.629128] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.018s)
[690.638337] Starting dynamic subtest: tile64-smem-smem-emit
[690.646188] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[690.648489] Subtest fast-copy-emit: SUCCESS (0.079s)
[690.790139] [097/134] (341s left) sysfs_heartbeat_interval (mixed)
[690.852005] Starting subtest: mixed
[690.853967] Starting dynamic subtest: vecs0
[696.119084] Dynamic subtest vecs0: SUCCESS (5.267s)
[696.140437] Starting dynamic subtest: ccs0
[701.330388] Dynamic subtest ccs0: SUCCESS (5.210s)
[701.342822] Starting dynamic subtest: vcs0
[706.577072] Dynamic subtest vcs0: SUCCESS (5.245s)
[706.589244] Starting dynamic subtest: bcs0
[711.786873] Dynamic subtest bcs0: SUCCESS (5.205s)
[711.798838] Starting dynamic subtest: rcs0
[717.000192] Dynamic subtest rcs0: SUCCESS (5.209s)
[717.012341] Starting dynamic subtest: vcs1
[722.224699] Dynamic subtest vcs1: SUCCESS (5.221s)
[722.227113] Subtest mixed: SUCCESS (31.371s)
[722.386524] [098/134] (310s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[722.520050] Starting subtest: cursor-vs-flip-legacy
[751.836492] Subtest cursor-vs-flip-legacy: SUCCESS (29.315s)
[752.661933] [099/134] (280s left) gem_exec_schedule (semaphore-resolve)
[752.739841] Starting subtest: semaphore-resolve
[752.765381] Subtest semaphore-resolve: SUCCESS (0.034s)
[753.137912] [100/134] (279s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[753.238762] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[753.242836] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[753.362819] [101/134] (279s left) kms_cursor_legacy (torture-bo)
[753.475751] Starting subtest: torture-bo
[753.477882] Starting dynamic subtest: pipe-A
[759.514417] Dynamic subtest pipe-A: SUCCESS (6.036s)
[759.526839] Starting dynamic subtest: pipe-B
[765.955915] Dynamic subtest pipe-B: SUCCESS (6.442s)
[765.967187] Starting dynamic subtest: pipe-C
[772.677641] Dynamic subtest pipe-C: SUCCESS (6.720s)
[772.689323] Starting dynamic subtest: pipe-D
[779.452660] Dynamic subtest pipe-D: SUCCESS (6.774s)
[779.464900] Starting dynamic subtest: all-pipes
[785.694844] Dynamic subtest all-pipes: SUCCESS (6.242s)
[785.697222] Subtest torture-bo: SUCCESS (32.223s)
[785.863838] [102/134] (247s left) gem_ctx_shared (q-independent)
[785.943257] Starting subtest: Q-independent
[785.945053] Starting dynamic subtest: rcs0
[786.005838] Dynamic subtest rcs0: SUCCESS (0.064s)
[786.018209] Starting dynamic subtest: vcs0
[786.043977] Dynamic subtest vcs0: SUCCESS (0.038s)
[786.054515] Starting dynamic subtest: vcs1
[786.081008] Dynamic subtest vcs1: SUCCESS (0.036s)
[786.092446] Starting dynamic subtest: vecs0
[786.121925] Dynamic subtest vecs0: SUCCESS (0.041s)
[786.132134] Starting dynamic subtest: ccs0
[786.161844] Dynamic subtest ccs0: SUCCESS (0.039s)
[786.164144] Subtest Q-independent: SUCCESS (0.222s)
[787.458876] [103/134] (245s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[788.014054] Starting subtest: syncobj-timeline-invalid-wait
[788.016568] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[789.436222] [104/134] (243s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[789.508298] Starting subtest: y-tiled-ccs-to-x-tiled
[789.512826] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[789.710236] [105/134] (243s left) drm_read (short-buffer-wakeup)
[789.868449] Starting subtest: short-buffer-wakeup
[822.075648] Subtest short-buffer-wakeup: SUCCESS (32.216s)
[822.233475] [106/134] (210s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[822.328461] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[822.330753] Starting dynamic subtest: pipe-A-eDP-1
[822.390169] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[822.711731] Starting dynamic subtest: pipe-B-eDP-1
[823.518987] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.818s)
[823.821769] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.492s)
[824.673854] [107/134] (208s left) syncobj_timeline (device-submit-unordered)
[824.734671] Starting subtest: device-submit-unordered
[824.736846] Subtest device-submit-unordered: SUCCESS (0.000s)
[824.878628] [108/134] (208s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[824.973125] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[824.975236] Starting dynamic subtest: pipe-A-eDP-1
[825.267648] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.262s)
[825.277323] Starting dynamic subtest: pipe-B-eDP-1
[826.572818] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.305s)
[826.583464] Starting dynamic subtest: pipe-C-eDP-1
[827.846942] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.274s)
[827.857754] Starting dynamic subtest: pipe-D-eDP-1
[829.104800] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.258s)
[829.107337] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.105s)
[830.413895] [109/134] (202s left) kms_pipe_crc_basic (nonblocking-crc)
[830.518723] Starting subtest: nonblocking-crc
[830.521089] Starting dynamic subtest: pipe-A-eDP-1
[831.254214] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.735s)
[831.264265] Starting dynamic subtest: pipe-B-eDP-1
[832.716652] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.463s)
[832.727113] Starting dynamic subtest: pipe-C-eDP-1
[834.107326] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.387s)
[834.117303] Starting dynamic subtest: pipe-D-eDP-1
[835.445480] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[835.447838] Subtest nonblocking-crc: SUCCESS (4.927s)
[836.271649] [110/134] (197s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[836.366055] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[836.370269] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[836.471359] [111/134] (196s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[836.571768] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[836.576179] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[836.699661] [112/134] (196s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[836.783328] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[836.808403] Starting dynamic subtest: pipe-A-plane-0
[836.812646] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[836.824424] Starting dynamic subtest: pipe-A-plane-3
[836.828773] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[836.838325] Starting dynamic subtest: pipe-A-plane-5
[836.841964] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[837.941393] Starting dynamic subtest: pipe-B-plane-0
[837.945761] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[837.955184] Starting dynamic subtest: pipe-B-plane-3
[837.959536] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[837.969391] Starting dynamic subtest: pipe-B-plane-5
[837.973099] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[838.253869] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (1.468s)
[839.128957] [113/134] (194s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[839.228397] Starting subtest: plane-downscale-factor-0-5-with-rotation
[839.230677] Starting dynamic subtest: pipe-A
[839.250362] Dynamic subtest pipe-A: SKIP (0.018s)
[839.260336] Starting dynamic subtest: pipe-B
[839.282307] Dynamic subtest pipe-B: SKIP (0.031s)
[839.291716] Starting dynamic subtest: pipe-C
[839.313706] Dynamic subtest pipe-C: SKIP (0.031s)
[839.323109] Starting dynamic subtest: pipe-D
[839.345227] Dynamic subtest pipe-D: SKIP (0.031s)
[839.348997] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.114s)
[839.473637] [114/134] (194s left) perf_pmu (busy-hang)
[839.513652] Starting subtest: busy-hang
[839.515764] Starting dynamic subtest: rcs0
[840.800577] Dynamic subtest rcs0: SUCCESS (1.288s)
[840.810772] Starting dynamic subtest: bcs0
[842.053123] Dynamic subtest bcs0: SUCCESS (1.247s)
[842.064895] Starting dynamic subtest: vcs0
[843.835857] Dynamic subtest vcs0: SUCCESS (1.784s)
[843.846337] Starting dynamic subtest: vcs1
[845.830652] Dynamic subtest vcs1: SUCCESS (1.990s)
[845.841636] Starting dynamic subtest: vecs0
[847.810099] Dynamic subtest vecs0: SUCCESS (1.976s)
[847.820443] Starting dynamic subtest: ccs0
[849.054635] Dynamic subtest ccs0: SUCCESS (1.241s)
[849.056955] Subtest busy-hang: SUCCESS (9.537s)
[849.166025] [115/134] (184s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[849.263667] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[849.265833] Starting dynamic subtest: pipe-A-eDP-1
[850.151479] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.881s)
[850.160941] Starting dynamic subtest: pipe-B-eDP-1
[851.749718] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.593s)
[851.760527] Starting dynamic subtest: pipe-C-eDP-1
[853.311647] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.564s)
[853.323011] Starting dynamic subtest: pipe-D-eDP-1
[854.853092] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.537s)
[854.855318] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.581s)
[856.078503] [116/134] (177s left) kms_cursor_crc (cursor-offscreen-128x42)
[856.325258] Starting subtest: cursor-offscreen-128x42
[856.374784] Subtest cursor-offscreen-128x42: SKIP (0.044s)
[856.620059] [117/134] (177s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[856.721747] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[856.724013] Starting dynamic subtest: pipe-A
[856.746867] Dynamic subtest pipe-A: SKIP (0.021s)
[856.756470] Starting dynamic subtest: pipe-B
[856.782721] Dynamic subtest pipe-B: SKIP (0.036s)
[856.794569] Starting dynamic subtest: pipe-C
[856.819753] Dynamic subtest pipe-C: SKIP (0.036s)
[856.829501] Starting dynamic subtest: pipe-D
[856.853785] Dynamic subtest pipe-D: SKIP (0.033s)
[856.857354] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.130s)
[856.985813] [118/134] (176s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[857.096911] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[858.121233] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.025s)
[858.292470] [119/134] (175s left) syncobj_basic (bad-pad-handle-to-fd)
[858.350483] Starting subtest: bad-pad-handle-to-fd
[858.352856] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[858.455381] [120/134] (175s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[858.566372] Starting subtest: cursorA-vs-flipB-atomic-transitions
[858.570103] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[858.737072] [121/134] (175s left) kms_frontbuffer_tracking (psr-2p-rte)
[858.845935] Starting subtest: psr-2p-rte
[858.849407] Subtest psr-2p-rte: SKIP (0.000s)
[858.968040] [122/134] (174s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[859.062848] Starting subtest: psr-shrfb-scaledprimary
[864.879612] Subtest psr-shrfb-scaledprimary: SUCCESS (5.827s)
[865.028324] [123/134] (168s left) gen7_exec_parse (cmd-crossing-page)
[865.090957] Subtest cmd-crossing-page: SKIP (0.000s)
[865.174430] [124/134] (168s left) kms_frontbuffer_tracking (fbc-1p-rte)
[865.266261] Starting subtest: fbc-1p-rte
[870.774615] Subtest fbc-1p-rte: SUCCESS (5.511s)
[871.993143] [125/134] (161s left) gem_mmap_wc (write)
[872.065763] Subtest write: SKIP (0.000s)
[872.139886] [126/134] (161s left) kms_busy (extended-modeset-hang-newfb)
[872.244796] Starting subtest: extended-modeset-hang-newfb
[872.247154] Starting dynamic subtest: pipe-A
[907.971387] Dynamic subtest pipe-A: SUCCESS (35.723s)
[907.984270] Starting dynamic subtest: pipe-D
[931.199361] Dynamic subtest pipe-D: SUCCESS (23.225s)
[931.201509] Subtest extended-modeset-hang-newfb: SUCCESS (58.955s)
[932.011198] [127/134] (102s left) i915_pm_rpm (gem-evict-pwrite)
[933.542242] Starting subtest: gem-evict-pwrite
[933.546800] Subtest gem-evict-pwrite: SKIP (0.000s)
[934.259196] [128/134] ( 99s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[934.347918] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[934.350257] Starting dynamic subtest: pipe-A-eDP-1
[934.373265] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[934.389289] Starting dynamic subtest: pipe-B-eDP-1
[934.410619] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[934.421884] Starting dynamic subtest: pipe-C-eDP-1
[934.442548] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[934.451774] Starting dynamic subtest: pipe-D-eDP-1
[934.473699] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[934.478061] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[936.630500] [129/134] ( 97s left) i915_suspend (basic-s2idle-without-i915)
[936.667742] Starting subtest: basic-s2idle-without-i915
[958.644869] Subtest basic-s2idle-without-i915: SUCCESS (6.975s)
[958.784539] [130/134] ( 75s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[958.906427] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[958.908739] Starting dynamic subtest: pipe-A-default-mode
[961.681469] Dynamic subtest pipe-A-default-mode: SUCCESS (2.764s)
[961.683858] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.765s)
[962.529686] [131/134] ( 71s left) kms_big_fb (4-tiled-addfb)
[962.650970] Starting subtest: 4-tiled-addfb
[962.653842] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[962.769714] [132/134] ( 71s left) kms_setmode (basic-clone-single-crtc)
[962.837147] Starting subtest: basic-clone-single-crtc
[962.845274] Subtest basic-clone-single-crtc: SKIP (0.000s)
[962.965523] [133/134] ( 71s left) gem_exec_balancer (full-late-pulse)
[963.041943] Starting subtest: full-late-pulse
[968.174665] Subtest full-late-pulse: SUCCESS (5.122s)
[968.536735] [134/134] ( 65s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[968.646798] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[968.651886] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[968.768015] 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.