[102.165345] Initializing watchdogs
[102.165394]   /dev/watchdog0
[102.179547] [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]
[102.183718] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[102.186446] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[102.189886] [001/134] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[102.326493] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[109.917488] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.595s)
[110.098454] [002/134] (952s left) gem_exec_params (secure-non-master)
[110.164260] Starting subtest: secure-non-master
[110.168873] Subtest secure-non-master: SKIP (0.000s)
[110.290553] [003/134] (952s left) tools_test (tools_test)
[110.339657] Starting subtest: tools_test
[110.394472] Subtest tools_test: SUCCESS (0.069s)
[110.463832] [004/134] (952s left) kms_selftest (drm_cmdline_parser)
[110.498842] Starting subtest: drm_cmdline_parser
[110.510443] Starting dynamic subtest: drm_test_cmdline_force_d_only
[110.654307] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.143s)
[110.662694] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[110.807596] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.153s)
[110.816681] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[110.936204] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.129s)
[110.945185] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[111.084191] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.148s)
[111.092676] Starting dynamic subtest: drm_test_cmdline_force_e_only
[111.243324] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.158s)
[111.249895] Starting dynamic subtest: drm_test_cmdline_res
[111.379090] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.136s)
[111.385986] Starting dynamic subtest: drm_test_cmdline_res_vesa
[111.517127] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.138s)
[111.523883] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[111.660245] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.143s)
[111.667498] Starting dynamic subtest: drm_test_cmdline_res_rblank
[111.801247] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.141s)
[111.808460] Starting dynamic subtest: drm_test_cmdline_res_bpp
[111.944425] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.142s)
[111.951451] Starting dynamic subtest: drm_test_cmdline_res_refresh
[112.104810] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.160s)
[112.111706] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[112.251970] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.147s)
[112.258877] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[112.395066] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.144s)
[112.401865] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[112.550927] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.155s)
[112.557847] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[112.688966] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.138s)
[112.695534] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[112.825316] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.135s)
[112.833620] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[112.953341] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.127s)
[112.962271] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[113.095447] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.142s)
[113.104221] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[113.231055] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.135s)
[113.239765] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[113.373326] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.142s)
[113.382083] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[113.517528] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.144s)
[113.526588] Starting dynamic subtest: drm_test_cmdline_name
[113.643816] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.125s)
[113.652653] Starting dynamic subtest: drm_test_cmdline_name_bpp
[113.772506] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.129s)
[113.781450] Starting dynamic subtest: drm_test_cmdline_name_option
[113.883526] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.111s)
[113.891673] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[114.020272] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.136s)
[114.029116] Starting dynamic subtest: drm_test_cmdline_rotate_0
[114.167247] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.146s)
[114.175907] Starting dynamic subtest: drm_test_cmdline_rotate_90
[114.306896] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.140s)
[114.313757] Starting dynamic subtest: drm_test_cmdline_rotate_180
[114.422282] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.114s)
[114.430796] Starting dynamic subtest: drm_test_cmdline_rotate_270
[114.530530] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.109s)
[114.539068] Starting dynamic subtest: drm_test_cmdline_hmirror
[114.619841] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.088s)
[114.634918] Starting dynamic subtest: drm_test_cmdline_vmirror
[114.761249] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.140s)
[114.775519] Starting dynamic subtest: drm_test_cmdline_margin_options
[114.902945] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.144s)
[114.909577] Starting dynamic subtest: drm_test_cmdline_multiple_options
[115.069188] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.164s)
[115.078359] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[115.202293] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.134s)
[115.211076] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[115.341261] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.138s)
[115.350235] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[115.502344] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.162s)
[115.511340] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[115.664582] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.162s)
[115.676756] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[115.821492] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.155s)
[115.834152] Starting dynamic subtest: drm_test_cmdline_invalid
[115.973868] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.153s)
[115.980737] Starting dynamic subtest: drm_test_cmdline_tv_options
[116.142214] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.169s)
[116.144669] Subtest drm_cmdline_parser: SUCCESS (5.643s)
[116.346940] [005/134] (946s left) gem_exec_suspend (basic-s0)
[116.427117] Starting subtest: basic-S0
[116.429572] Starting dynamic subtest: smem
[136.666329] Dynamic subtest smem: SUCCESS (6.462s)
[136.669209] Subtest basic-S0: SUCCESS (6.463s)
[136.861164] [006/134] (926s left) kms_content_protection (uevent)
[137.025303] Starting subtest: uevent
[137.030136] Subtest uevent: SKIP (0.000s)
[137.171195] [007/134] (925s left) gem_eio (in-flight-suspend)
[137.417808] Starting subtest: in-flight-suspend
[180.172544] Subtest in-flight-suspend: SUCCESS (13.228s)
[180.363775] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[180.366855] [008/134] (882s left) gem_spin_batch (resubmit-all)
[180.435769] Starting subtest: resubmit-all
[180.438122] Starting dynamic subtest: rcs0
[180.446773] Dynamic subtest rcs0: SUCCESS (0.011s)
[180.458051] Starting dynamic subtest: bcs0
[180.460215] Dynamic subtest bcs0: SUCCESS (0.002s)
[180.468421] Starting dynamic subtest: vcs0
[180.470509] Dynamic subtest vcs0: SUCCESS (0.002s)
[180.478638] Starting dynamic subtest: vcs1
[180.480778] Dynamic subtest vcs1: SUCCESS (0.001s)
[180.488786] Starting dynamic subtest: vecs0
[180.490914] Dynamic subtest vecs0: SUCCESS (0.002s)
[180.498945] Starting dynamic subtest: ccs0
[180.501140] Dynamic subtest ccs0: SUCCESS (0.002s)
[180.503318] Subtest resubmit-all: SUCCESS (0.022s)
[182.199760] [009/134] (880s left) kms_psr (psr-cursor-plane-onoff)
[182.292289] Starting subtest: psr-cursor-plane-onoff
[182.294601] Starting dynamic subtest: eDP-1
[183.998429] Dynamic subtest eDP-1: SUCCESS (1.695s)
[184.000749] Subtest psr-cursor-plane-onoff: SUCCESS (1.696s)
[184.881540] [010/134] (878s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[184.998756] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[191.918121] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.893s)
[192.101272] [011/134] (870s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[192.212419] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[192.216847] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[192.319022] [012/134] (870s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[197.337903] Starting subtest: fbc-psr2-cursor-plane-update-sf
[197.340275] Starting dynamic subtest: pipe-A-eDP-1
[198.116590] Dynamic subtest pipe-A-eDP-1: SKIP (0.774s)
[198.126905] Starting dynamic subtest: pipe-B-eDP-1
[199.245766] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s)
[199.250489] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.902s)
[200.431341] [013/134] (862s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[205.539129] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[205.541477] Starting dynamic subtest: pipe-A-eDP-1
[212.161441] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.617s)
[212.170752] Starting dynamic subtest: pipe-B-eDP-1
[218.816586] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.654s)
[218.819109] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.274s)
[219.704824] [014/134] (843s left) kms_pm_rpm (basic-pci-d3-state)
[221.267086] Starting subtest: basic-pci-d3-state
[222.751037] Subtest basic-pci-d3-state: SUCCESS (1.485s)
[222.848010] [015/134] (840s left) gem_ctx_exec (basic-norecovery)
[222.905894] Starting subtest: basic-norecovery
[223.324314] Subtest basic-norecovery: SUCCESS (0.420s)
[223.485655] [016/134] (839s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[223.602179] Starting subtest: protected-encrypted-src-copy-not-readible
[223.715894] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.115s)
[223.860965] [017/134] (839s left) kms_flip (absolute-wf_vblank)
[223.971819] Starting subtest: absolute-wf_vblank
[223.973696] Starting dynamic subtest: A-eDP1
[232.674769] Dynamic subtest A-eDP1: SUCCESS (8.705s)
[232.685345] Starting dynamic subtest: B-eDP1
[241.410295] Dynamic subtest B-eDP1: SUCCESS (8.733s)
[241.422365] Starting dynamic subtest: C-eDP1
[250.126009] Dynamic subtest C-eDP1: SUCCESS (8.713s)
[250.135862] Starting dynamic subtest: D-eDP1
[258.862697] Dynamic subtest D-eDP1: SUCCESS (8.734s)
[258.864992] Subtest absolute-wf_vblank: SUCCESS (34.893s)
[260.088149] [018/134] (803s left) prime_mmap (test_refcounting)
[260.143855] Starting subtest: test_refcounting
[260.146054] Starting dynamic subtest: test_refcounting-smem
[260.192642] Dynamic subtest test_refcounting-smem: SUCCESS (0.050s)
[260.195646] Subtest test_refcounting: SUCCESS (0.051s)
[260.322521] [019/134] (803s left) gem_exec_schedule (pi-common)
[260.508526] Starting subtest: pi-common
[260.510841] Starting dynamic subtest: rcs0
[260.538288] Dynamic subtest rcs0: SUCCESS (0.031s)
[260.547629] Starting dynamic subtest: bcs0
[260.571190] Dynamic subtest bcs0: SUCCESS (0.032s)
[260.584361] Starting dynamic subtest: vcs0
[260.601888] Dynamic subtest vcs0: SUCCESS (0.030s)
[260.615005] Starting dynamic subtest: vcs1
[260.629739] Dynamic subtest vcs1: SUCCESS (0.027s)
[260.642814] Starting dynamic subtest: vecs0
[260.657383] Dynamic subtest vecs0: SUCCESS (0.027s)
[260.670377] Starting dynamic subtest: ccs0
[260.683756] Dynamic subtest ccs0: SUCCESS (0.026s)
[260.685946] Subtest pi-common: SUCCESS (0.178s)
[260.941929] [020/134] (802s left) perf (unprivileged-single-ctx-counters)
[261.067935] Starting subtest: unprivileged-single-ctx-counters
[261.072301] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[261.108907] [021/134] (802s left) api_intel_allocator (fork-simple-stress-signal)
[261.170009] Starting subtest: fork-simple-stress-signal
[266.573217] Subtest fork-simple-stress-signal: SUCCESS (5.402s)
[266.732301] [022/134] (796s left) kms_flip (flip-vs-panning-vs-hang)
[266.841996] Starting subtest: flip-vs-panning-vs-hang
[266.844327] Starting dynamic subtest: A-eDP1
[317.662921] Dynamic subtest A-eDP1: SUCCESS (50.824s)
[317.675469] Starting dynamic subtest: D-eDP1
[357.676545] Dynamic subtest D-eDP1: SUCCESS (40.006s)
[357.678960] Subtest flip-vs-panning-vs-hang: SUCCESS (90.833s)
[358.879150] [023/134] (704s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[363.938519] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[363.940886] Starting dynamic subtest: pipe-A-eDP-1
[375.977781] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.035s)
[375.987629] Starting dynamic subtest: pipe-B-eDP-1
[388.003293] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.020s)
[388.005224] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.059s)
[388.863059] [024/134] (674s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[388.933413] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[388.935730] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[389.068853] [025/134] (674s left) gem_exec_create (basic)
[389.134343] Starting subtest: basic
[389.136598] Starting dynamic subtest: smem
[393.091558] Dynamic subtest smem: SUCCESS (3.954s)
[393.094691] Subtest basic: SUCCESS (3.956s)
[393.293545] [026/134] (670s left) gem_ctx_persistence (smoketest)
[393.354871] Starting subtest: smoketest
[434.081761] Subtest smoketest: SUCCESS (40.724s)
[434.200771] [027/134] (629s left) gem_ctx_bad_destroy (invalid-pad)
[434.259458] Starting subtest: invalid-pad
[434.261865] Subtest invalid-pad: SUCCESS (0.000s)
[434.347596] [028/134] (629s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[434.481583] Starting subtest: cursorA-vs-flipB-legacy
[434.486154] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[434.648544] [029/134] (629s left) kms_atomic_interruptible (legacy-setmode)
[434.749411] Starting subtest: legacy-setmode
[434.751713] Starting dynamic subtest: pipe-A-eDP-1
[441.657016] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.905s)
[441.659256] Subtest legacy-setmode: SUCCESS (6.907s)
[442.534743] [030/134] (621s left) kms_cdclk (mode-transition)
[442.642220] Starting subtest: mode-transition
[442.644390] Starting dynamic subtest: pipe-A-eDP-1
[442.932773] Dynamic subtest pipe-A-eDP-1: SKIP (0.287s)
[442.943381] Starting dynamic subtest: pipe-B-eDP-1
[442.948215] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[442.959469] Starting dynamic subtest: pipe-C-eDP-1
[442.964442] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[442.973186] Starting dynamic subtest: pipe-D-eDP-1
[442.977292] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[442.981408] Subtest mode-transition: SKIP (0.294s)
[443.766809] [031/134] (620s left) kms_big_fb (linear-8bpp-rotate-270)
[443.867613] Starting subtest: linear-8bpp-rotate-270
[443.891296] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[444.024917] [032/134] (619s left) perf_pmu (faulting-read)
[444.069249] Starting subtest: faulting-read
[444.071498] Starting dynamic subtest: wb
[444.073793] Dynamic subtest wb: SUCCESS (0.001s)
[444.083174] Starting dynamic subtest: wc
[444.085292] Dynamic subtest wc: SUCCESS (0.000s)
[444.094408] Starting dynamic subtest: uc
[444.096249] Dynamic subtest uc: SUCCESS (0.000s)
[444.098122] Subtest faulting-read: SUCCESS (0.003s)
[444.229251] [033/134] (619s left) kms_psr (fbc-psr2-primary-render)
[444.326585] Starting subtest: fbc-psr2-primary-render
[444.328758] Starting dynamic subtest: eDP-1
[444.332734] Dynamic subtest eDP-1: SKIP (0.000s)
[444.336690] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[444.409439] [034/134] (619s left) gem_exec_schedule (deep)
[444.602699] Starting subtest: deep
[444.605277] Starting dynamic subtest: rcs0
[449.896613] Dynamic subtest rcs0: SKIP (5.289s)
[449.908760] Starting dynamic subtest: vcs0
[460.913070] Dynamic subtest vcs0: SUCCESS (11.016s)
[460.927944] Starting dynamic subtest: vcs1
[472.360350] Dynamic subtest vcs1: SUCCESS (11.446s)
[472.375965] Starting dynamic subtest: vecs0
[483.811756] Dynamic subtest vecs0: SUCCESS (11.448s)
[483.824206] Starting dynamic subtest: ccs0
[497.175249] Dynamic subtest ccs0: SUCCESS (13.361s)
[497.178219] Subtest deep: SUCCESS (52.568s)
[498.885406] [035/134] (565s left) kms_selftest (drm_plane_helper)
[498.922503] Starting subtest: drm_plane_helper
[498.972032] Starting dynamic subtest: drm_test_check_plane_state
[499.113910] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.144s)
[499.125764] Starting dynamic subtest: drm_test_check_invalid_plane_state
[499.249937] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.133s)
[499.252230] Subtest drm_plane_helper: SUCCESS (0.327s)
[499.468998] [036/134] (564s left) syncobj_basic (bad-pad-fd-to-handle)
[499.532099] Starting subtest: bad-pad-fd-to-handle
[499.534244] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[499.654834] [037/134] (564s left) gem_lmem_swapping (parallel-random-engines)
[499.705157] Subtest parallel-random-engines: SKIP (0.000s)
[499.751164] [038/134] (564s left) kms_mmap_write_crc (main)
[499.865407] Starting subtest: main
[499.867529] Starting dynamic subtest: pipe-A-eDP-1
[503.443146] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.575s)
[503.445445] Subtest main: SUCCESS (3.576s)
[504.292444] [039/134] (559s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[504.391919] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[511.279871] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.892s)
[511.428304] [040/134] (552s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[511.549913] Starting subtest: addfb25-yf-tiled-legacy
[511.551797] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[511.682814] [041/134] (552s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[511.782814] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[511.787347] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[511.895505] [042/134] (552s left) gem_ctx_exec (basic)
[511.956293] Starting subtest: basic
[511.961926] Subtest basic: SUCCESS (0.005s)
[512.103095] [043/134] (552s left) kms_atomic (test-only)
[512.206987] Starting subtest: test-only
[512.256655] Starting dynamic subtest: pipe-A-eDP-1
[514.855141] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.596s)
[514.857483] Subtest test-only: SUCCESS (2.649s)
[515.750106] [044/134] (548s left) gem_set_tiling_vs_gtt
[515.887482] [045/134] (548s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[515.996799] Starting subtest: yf-tiled-16bpp-rotate-0
[516.001409] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[516.124181] [046/134] (548s left) kms_getfb (getfb2-handle-zero)
[516.222465] Starting subtest: getfb2-handle-zero
[516.224741] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[516.336547] [047/134] (548s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[516.397354] Starting subtest: single-wait-all-for-submit-available-submitted
[516.399748] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[516.518440] [048/134] (548s left) gem_exec_reloc (basic-wc-cpu)
[516.579299] Subtest basic-wc-cpu: SKIP (0.000s)
[516.688822] [049/134] (547s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[516.782973] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[523.998698] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.218s)
[524.182712] [050/134] (540s left) gem_basic (multigpu-create-close)
[524.249133] Starting subtest: multigpu-create-close
[524.253631] Subtest multigpu-create-close: SKIP (0.000s)
[524.376440] [051/134] (540s left) kms_frontbuffer_tracking (fbc-suspend)
[524.489960] Starting subtest: fbc-suspend
[562.332561] Subtest fbc-suspend: SUCCESS (7.485s)
[563.553095] [052/134] (501s left) gem_wait (wait)
[563.625528] Starting subtest: wait
[563.628274] Starting dynamic subtest: all-engines
[565.495727] Dynamic subtest all-engines: SUCCESS (1.858s)
[565.508124] Starting dynamic subtest: rcs0
[566.546540] Dynamic subtest rcs0: SUCCESS (1.044s)
[566.558674] Starting dynamic subtest: bcs0
[567.597238] Dynamic subtest bcs0: SUCCESS (1.044s)
[567.608564] Starting dynamic subtest: vcs0
[568.645546] Dynamic subtest vcs0: SUCCESS (1.042s)
[568.657897] Starting dynamic subtest: vcs1
[569.695386] Dynamic subtest vcs1: SUCCESS (1.044s)
[569.707984] Starting dynamic subtest: vecs0
[570.744422] Dynamic subtest vecs0: SUCCESS (1.044s)
[570.757364] Starting dynamic subtest: ccs0
[571.795483] Dynamic subtest ccs0: SUCCESS (1.046s)
[571.798294] Subtest wait: SUCCESS (8.137s)
[572.005776] [053/134] (492s left) gem_exec_reloc (basic-wc-active)
[572.070925] Subtest basic-wc-active: SKIP (0.000s)
[572.132436] [054/134] (492s left) kms_pm_rpm (pc8-residency)
[573.655433] Starting subtest: pc8-residency
[573.660665] Subtest pc8-residency: SKIP (0.000s)
[574.426768] [055/134] (490s left) kms_dsc (dsc-with-bpc-formats)
[574.520030] Starting subtest: dsc-with-bpc-formats
[574.651819] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[574.693591] [056/134] (490s left) kms_psr (pr-cursor-plane-move)
[574.790860] Starting subtest: pr-cursor-plane-move
[574.795715] Subtest pr-cursor-plane-move: SKIP (0.000s)
[574.861818] [057/134] (490s left) kms_vblank (ts-continuation-dpms-suspend)
[574.953476] Starting subtest: ts-continuation-dpms-suspend
[574.955639] Starting dynamic subtest: pipe-A-eDP-1
[592.369538] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.811s)
[592.383873] Starting dynamic subtest: pipe-D-eDP-1
[610.395997] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.442s)
[610.398689] Subtest ts-continuation-dpms-suspend: SUCCESS (4.257s)
[611.205755] [058/134] (453s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[611.275980] Starting subtest: multi-wait-available-unsubmitted-signaled
[611.278549] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[611.386326] [059/134] (453s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[611.506303] Starting subtest: basic-flip-before-cursor-legacy
[612.421580] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.912s)
[613.246195] [060/134] (451s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[613.633126] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[614.445629] [061/134] (450s left) gem_exec_params (invalid-flag)
[614.504985] Starting subtest: invalid-flag
[614.507692] Subtest invalid-flag: SUCCESS (0.000s)
[614.637116] [062/134] (450s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[614.744037] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[614.749014] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[614.861085] [063/134] (450s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[614.923503] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[614.925748] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[615.049192] [064/134] (450s left) prime_vgem (wait)
[615.130489] Starting subtest: wait
[615.133317] Starting dynamic subtest: rcs0
[615.173640] Dynamic subtest rcs0: SUCCESS (0.044s)
[615.183503] Starting dynamic subtest: bcs0
[615.218750] Dynamic subtest bcs0: SUCCESS (0.044s)
[615.228616] Starting dynamic subtest: vcs0
[615.263484] Dynamic subtest vcs0: SUCCESS (0.044s)
[615.272745] Starting dynamic subtest: vcs1
[615.307735] Dynamic subtest vcs1: SUCCESS (0.043s)
[615.317165] Starting dynamic subtest: vecs0
[615.355630] Dynamic subtest vecs0: SUCCESS (0.047s)
[615.365250] Starting dynamic subtest: ccs0
[615.403281] Dynamic subtest ccs0: SUCCESS (0.047s)
[615.405323] Subtest wait: SUCCESS (0.274s)
[615.546189] [065/134] (449s left) gem_exec_capture (capture)
[615.602065] Starting subtest: capture
[615.604070] Starting dynamic subtest: rcs0-smem
[616.421319] Dynamic subtest rcs0-smem: SUCCESS (0.816s)
[616.432570] Starting dynamic subtest: bcs0-smem
[617.233248] Dynamic subtest bcs0-smem: SUCCESS (0.810s)
[617.243369] Starting dynamic subtest: vcs0-smem
[618.043293] Dynamic subtest vcs0-smem: SUCCESS (0.812s)
[618.055289] Starting dynamic subtest: vcs1-smem
[618.955753] Dynamic subtest vcs1-smem: SUCCESS (0.911s)
[618.968233] Starting dynamic subtest: vecs0-smem
[619.858896] Dynamic subtest vecs0-smem: FAIL (0.830s)
[619.869657] Starting dynamic subtest: ccs0-smem
[621.112706] Dynamic subtest ccs0-smem: SUCCESS (1.321s)
[621.115226] Subtest capture: FAIL (5.504s)
[621.238111] [066/134] (444s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[621.508521] Starting subtest: cursor-rapid-movement-512x512
[621.513413] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[621.700095] [067/134] (443s left) kms_flip (2x-flip-vs-wf_vblank)
[621.793411] Starting subtest: 2x-flip-vs-wf_vblank
[621.804432] Subtest 2x-flip-vs-wf_vblank: SKIP (0.010s)
[621.896013] [068/134] (443s left) i915_pm_freq_api (freq-suspend)
[621.967902] Starting subtest: freq-suspend
[621.970596] Starting dynamic subtest: gt0
[637.826029] Dynamic subtest gt0: SUCCESS (1.230s)
[637.845106] Starting dynamic subtest: gt1
[653.850240] Dynamic subtest gt1: SUCCESS (1.209s)
[653.852378] Subtest freq-suspend: SUCCESS (2.440s)
[653.934103] [069/134] (411s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[654.052582] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[654.057322] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[654.178955] [070/134] (411s left) kms_chamelium_frames (hdmi-aspect-ratio)
[654.580706] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[655.423177] [071/134] (410s left) kms_cursor_crc (cursor-sliding-max-size)
[655.729735] Starting subtest: cursor-sliding-max-size
[655.737790] Subtest cursor-sliding-max-size: SKIP (0.000s)
[655.886854] [072/134] (409s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[655.985098] Starting subtest: plane-ctm3x4-lut1d
[656.002635] Starting dynamic subtest: pipe-A-eDP-1
[656.734544] Dynamic subtest pipe-A-eDP-1: FAIL (0.327s)
[657.432439] Starting dynamic subtest: pipe-B-eDP-1
[658.422338] Dynamic subtest pipe-B-eDP-1: FAIL (0.348s)
[658.873872] Starting dynamic subtest: pipe-C-eDP-1
[659.859323] Dynamic subtest pipe-C-eDP-1: FAIL (0.278s)
[660.205846] Starting dynamic subtest: pipe-D-eDP-1
[661.173256] Dynamic subtest pipe-D-eDP-1: FAIL (0.269s)
[661.175835] Subtest plane-ctm3x4-lut1d: FAIL (4.743s)
[661.596978] [073/134] (404s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[661.701629] Starting subtest: psr-1p-pri-indfb-multidraw
[684.760559] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (23.026s)
[684.896510] [074/134] (380s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[685.004434] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[685.008159] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[685.118649] [075/134] (380s left) syncobj_timeline (invalid-query-illegal-handle)
[685.179529] Starting subtest: invalid-query-illegal-handle
[685.181802] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[685.318212] [076/134] (380s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[685.408981] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[685.410836] Starting dynamic subtest: pipe-A-eDP-1
[687.603043] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.191s)
[687.611836] Starting dynamic subtest: pipe-B-eDP-1
[690.006234] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.404s)
[690.014851] Starting dynamic subtest: pipe-C-eDP-1
[692.233331] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.227s)
[692.242534] Starting dynamic subtest: pipe-D-eDP-1
[694.380451] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.146s)
[694.382327] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.973s)
[695.551628] [077/134] (370s left) kms_addfb_basic (too-high)
[695.656043] Starting subtest: too-high
[695.658743] Subtest too-high: SUCCESS (0.000s)
[695.779662] [078/134] (370s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[695.880848] Starting subtest: psr-rgb565-draw-mmap-gtt
[695.885529] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[696.003268] [079/134] (369s left) gem_mmap_offset (mmap-unmap)
[696.062562] Starting subtest: mmap-unmap
[696.065225] Starting dynamic subtest: smem0
[719.257182] Dynamic subtest smem0: SUCCESS (23.193s)
[719.260073] Subtest mmap-unmap: SUCCESS (23.196s)
[719.405280] [080/134] (346s left) gem_pread (exhaustion)
[719.471510] Subtest exhaustion: SKIP (0.000s)
[719.540301] [081/134] (346s left) kms_joiner (invalid-modeset-ultra-joiner)
[719.643684] Starting subtest: invalid-modeset-ultra-joiner
[719.648162] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[719.723856] [082/134] (346s left) i915_drm_fdinfo (basics)
[719.768992] Starting subtest: basics
[719.771288] Subtest basics: SUCCESS (0.000s)
[719.844809] [083/134] (346s left) gem_ccs (ctrl-surf-copy-new-ctx)
[719.903568] Starting subtest: ctrl-surf-copy-new-ctx
[719.908299] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[720.022204] [084/134] (346s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[720.120603] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[720.147337] Starting dynamic subtest: pipe-A-plane-0
[720.813336] Dynamic subtest pipe-A-plane-0: SUCCESS (0.666s)
[720.822758] Starting dynamic subtest: pipe-A-plane-3
[721.257674] Dynamic subtest pipe-A-plane-3: SUCCESS (0.444s)
[721.267675] Starting dynamic subtest: pipe-A-plane-5
[721.272464] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[722.332692] Starting dynamic subtest: pipe-B-plane-0
[723.059069] Dynamic subtest pipe-B-plane-0: SUCCESS (0.733s)
[723.068238] Starting dynamic subtest: pipe-B-plane-3
[723.547050] Dynamic subtest pipe-B-plane-3: SUCCESS (0.488s)
[723.556667] Starting dynamic subtest: pipe-B-plane-5
[723.560403] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[723.865342] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.745s)
[724.720628] [085/134] (341s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[724.835657] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[724.840620] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[724.965906] [086/134] (341s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[725.064803] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[725.067612] Starting dynamic subtest: pipe-A-default-mode
[726.197861] Dynamic subtest pipe-A-default-mode: SKIP (1.129s)
[726.203359] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.131s)
[726.374269] [087/134] (339s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[726.487634] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[726.492622] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[726.608851] [088/134] (339s left) kms_draw_crc (fill-fb)
[726.693547] Starting subtest: fill-fb
[727.155255] Subtest fill-fb: SUCCESS (0.464s)
[727.305274] [089/134] (339s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[727.398519] Starting subtest: y-tiled-8bpp-rotate-0
[727.403081] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[727.543302] [090/134] (338s left) gem_render_copy (yf-tiled-to-vebox-linear)
[727.609569] Starting subtest: yf-tiled-to-vebox-linear
[727.613706] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[727.810968] [091/134] (338s left) syncobj_wait (invalid-reset-illegal-handle)
[727.878525] Starting subtest: invalid-reset-illegal-handle
[727.881078] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[728.028667] [092/134] (338s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[728.127775] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[735.165587] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (7.037s)
[735.298061] [093/134] (331s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[735.410326] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[735.415544] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[735.537169] [094/134] (331s left) api_intel_bb (full-batch)
[735.602667] Starting subtest: full-batch
[735.607859] Subtest full-batch: SUCCESS (0.006s)
[735.718798] [095/134] (330s left) kms_psr (fbc-psr-basic)
[735.810718] Starting subtest: fbc-psr-basic
[735.813561] Starting dynamic subtest: eDP-1
[735.818797] Dynamic subtest eDP-1: SKIP (0.000s)
[735.824384] Subtest fbc-psr-basic: SKIP (0.001s)
[735.894581] [096/134] (330s left) gem_exercise_blt (fast-copy-emit)
[735.971820] Starting subtest: fast-copy-emit
[735.974787] Starting dynamic subtest: linear-smem-smem-emit
[735.996298] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.024s)
[736.007477] Starting dynamic subtest: xmajor-smem-smem-emit
[736.013795] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.016s)
[736.025083] Starting dynamic subtest: tile4-smem-smem-emit
[736.030452] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.017s)
[736.039695] Starting dynamic subtest: tile64-smem-smem-emit
[736.047584] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.017s)
[736.050393] Subtest fast-copy-emit: SUCCESS (0.076s)
[736.191923] [097/134] (330s left) sysfs_heartbeat_interval (mixed)
[736.250812] Starting subtest: mixed
[736.253426] Starting dynamic subtest: vecs0
[741.503989] Dynamic subtest vecs0: SUCCESS (5.249s)
[741.516471] Starting dynamic subtest: ccs0
[746.706151] Dynamic subtest ccs0: SUCCESS (5.199s)
[746.718220] Starting dynamic subtest: vcs0
[751.917746] Dynamic subtest vcs0: SUCCESS (5.209s)
[751.929800] Starting dynamic subtest: bcs0
[757.148385] Dynamic subtest bcs0: SUCCESS (5.230s)
[757.160664] Starting dynamic subtest: rcs0
[762.385285] Dynamic subtest rcs0: SUCCESS (5.236s)
[762.396827] Starting dynamic subtest: vcs1
[767.588783] Dynamic subtest vcs1: SUCCESS (5.203s)
[767.591738] Subtest mixed: SUCCESS (31.337s)
[767.683020] [098/134] (299s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[767.812510] Starting subtest: cursor-vs-flip-legacy
[797.116595] Subtest cursor-vs-flip-legacy: SUCCESS (29.303s)
[797.923276] [099/134] (268s left) gem_exec_schedule (semaphore-resolve)
[798.004745] Starting subtest: semaphore-resolve
[798.030031] Subtest semaphore-resolve: SUCCESS (0.036s)
[798.400973] [100/134] (268s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[798.498177] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[798.503452] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[798.625196] [101/134] (268s left) kms_cursor_legacy (torture-bo)
[798.747317] Starting subtest: torture-bo
[798.750216] Starting dynamic subtest: pipe-A
[804.664708] Dynamic subtest pipe-A: SUCCESS (5.916s)
[804.676524] Starting dynamic subtest: pipe-B
[811.659862] Dynamic subtest pipe-B: SUCCESS (6.993s)
[811.669422] Starting dynamic subtest: pipe-C
[818.914802] Dynamic subtest pipe-C: SUCCESS (7.251s)
[818.926624] Starting dynamic subtest: pipe-D
[826.439679] Dynamic subtest pipe-D: SUCCESS (7.523s)
[826.456338] Starting dynamic subtest: all-pipes
[832.825140] Dynamic subtest all-pipes: SUCCESS (6.380s)
[832.828194] Subtest torture-bo: SUCCESS (34.073s)
[833.120967] [102/134] (233s left) gem_ctx_shared (q-independent)
[833.254745] Starting subtest: Q-independent
[833.257784] Starting dynamic subtest: rcs0
[833.346430] Dynamic subtest rcs0: SUCCESS (0.089s)
[833.361614] Starting dynamic subtest: vcs0
[833.395812] Dynamic subtest vcs0: SUCCESS (0.050s)
[833.406997] Starting dynamic subtest: vcs1
[833.443791] Dynamic subtest vcs1: SUCCESS (0.047s)
[833.456381] Starting dynamic subtest: vecs0
[833.488853] Dynamic subtest vecs0: SUCCESS (0.045s)
[833.501860] Starting dynamic subtest: ccs0
[833.524555] Dynamic subtest ccs0: SUCCESS (0.035s)
[833.527337] Subtest Q-independent: SUCCESS (0.271s)
[835.190327] [103/134] (231s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[835.798854] Starting subtest: syncobj-timeline-invalid-wait
[835.801843] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.006s)
[837.230898] [104/134] (229s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[837.300828] Starting subtest: y-tiled-ccs-to-x-tiled
[837.306638] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[837.515901] [105/134] (229s left) drm_read (short-buffer-wakeup)
[837.655693] Starting subtest: short-buffer-wakeup
[869.879241] Subtest short-buffer-wakeup: SUCCESS (32.217s)
[870.035475] [106/134] (197s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[870.133195] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[870.135713] Starting dynamic subtest: pipe-A-eDP-1
[870.195333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[870.492465] Starting dynamic subtest: pipe-B-eDP-1
[871.324958] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.844s)
[871.624940] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.492s)
[872.497661] [107/134] (194s left) syncobj_timeline (device-submit-unordered)
[872.550851] Starting subtest: device-submit-unordered
[872.553667] Subtest device-submit-unordered: SUCCESS (0.000s)
[872.673633] [108/134] (194s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[872.764414] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[872.766666] Starting dynamic subtest: pipe-A-eDP-1
[873.067427] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.266s)
[873.078076] Starting dynamic subtest: pipe-B-eDP-1
[874.379741] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.309s)
[874.390717] Starting dynamic subtest: pipe-C-eDP-1
[875.656884] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.275s)
[875.668658] Starting dynamic subtest: pipe-D-eDP-1
[876.907793] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.251s)
[876.910403] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.106s)
[878.033204] [109/134] (189s left) kms_pipe_crc_basic (nonblocking-crc)
[878.127631] Starting subtest: nonblocking-crc
[878.130456] Starting dynamic subtest: pipe-A-eDP-1
[878.891526] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.763s)
[878.902730] Starting dynamic subtest: pipe-B-eDP-1
[880.332304] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.442s)
[880.342533] Starting dynamic subtest: pipe-C-eDP-1
[881.694804] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[881.705719] Starting dynamic subtest: pipe-D-eDP-1
[883.029689] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[883.032507] Subtest nonblocking-crc: SUCCESS (4.904s)
[883.845486] [110/134] (183s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[883.949613] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[883.954727] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[884.068340] [111/134] (183s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[884.172823] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[884.178120] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[884.314285] [112/134] (183s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[884.421586] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[884.448033] Starting dynamic subtest: pipe-A-plane-0
[884.453661] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[884.464651] Starting dynamic subtest: pipe-A-plane-3
[884.469758] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[884.479582] Starting dynamic subtest: pipe-A-plane-5
[884.485294] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[885.506960] Starting dynamic subtest: pipe-B-plane-0
[885.511841] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[885.521500] Starting dynamic subtest: pipe-B-plane-3
[885.526075] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[885.535717] Starting dynamic subtest: pipe-B-plane-5
[885.541459] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[885.823448] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (1.398s)
[886.702288] [113/134] (180s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[886.803579] Starting subtest: plane-downscale-factor-0-5-with-rotation
[886.806205] Starting dynamic subtest: pipe-A
[886.823644] Dynamic subtest pipe-A: SKIP (0.017s)
[886.834053] Starting dynamic subtest: pipe-B
[886.856704] Dynamic subtest pipe-B: SKIP (0.030s)
[886.866532] Starting dynamic subtest: pipe-C
[886.884057] Dynamic subtest pipe-C: SKIP (0.030s)
[886.896804] Starting dynamic subtest: pipe-D
[886.917434] Dynamic subtest pipe-D: SKIP (0.030s)
[886.922236] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.110s)
[887.009043] [114/134] (180s left) perf_pmu (busy-hang)
[887.057167] Starting subtest: busy-hang
[887.059714] Starting dynamic subtest: rcs0
[888.349232] Dynamic subtest rcs0: SUCCESS (1.295s)
[888.361641] Starting dynamic subtest: bcs0
[889.598377] Dynamic subtest bcs0: SUCCESS (1.247s)
[889.610993] Starting dynamic subtest: vcs0
[891.575391] Dynamic subtest vcs0: SUCCESS (1.975s)
[891.587493] Starting dynamic subtest: vcs1
[893.632660] Dynamic subtest vcs1: SUCCESS (2.052s)
[893.644151] Starting dynamic subtest: vecs0
[895.616854] Dynamic subtest vecs0: SUCCESS (1.983s)
[895.628386] Starting dynamic subtest: ccs0
[896.857689] Dynamic subtest ccs0: SUCCESS (1.240s)
[896.860696] Subtest busy-hang: SUCCESS (9.803s)
[897.001618] [115/134] (170s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[897.110830] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[897.113268] Starting dynamic subtest: pipe-A-eDP-1
[897.957067] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.840s)
[897.974037] Starting dynamic subtest: pipe-B-eDP-1
[899.596544] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.634s)
[899.607027] Starting dynamic subtest: pipe-C-eDP-1
[901.090104] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s)
[901.100731] Starting dynamic subtest: pipe-D-eDP-1
[902.630541] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.539s)
[902.633099] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.512s)
[903.800797] [116/134] (163s left) kms_cursor_crc (cursor-offscreen-128x42)
[904.047433] Starting subtest: cursor-offscreen-128x42
[904.095710] Subtest cursor-offscreen-128x42: SKIP (0.043s)
[904.336126] [117/134] (163s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[904.442385] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[904.444813] Starting dynamic subtest: pipe-A
[904.467100] Dynamic subtest pipe-A: SKIP (0.020s)
[904.477514] Starting dynamic subtest: pipe-B
[904.500463] Dynamic subtest pipe-B: SKIP (0.033s)
[904.510593] Starting dynamic subtest: pipe-C
[904.534254] Dynamic subtest pipe-C: SKIP (0.033s)
[904.544183] Starting dynamic subtest: pipe-D
[904.566371] Dynamic subtest pipe-D: SKIP (0.033s)
[904.571081] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.121s)
[904.701163] [118/134] (163s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[904.804532] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[905.840269] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.035s)
[906.002141] [119/134] (161s left) syncobj_basic (bad-pad-handle-to-fd)
[906.057703] Starting subtest: bad-pad-handle-to-fd
[906.060536] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[906.179140] [120/134] (161s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[906.299056] Starting subtest: cursorA-vs-flipB-atomic-transitions
[906.303379] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[906.458574] [121/134] (161s left) kms_frontbuffer_tracking (psr-2p-rte)
[906.559672] Starting subtest: psr-2p-rte
[906.564230] Subtest psr-2p-rte: SKIP (0.000s)
[906.668832] [122/134] (161s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[906.776193] Starting subtest: psr-shrfb-scaledprimary
[912.580370] Subtest psr-shrfb-scaledprimary: SUCCESS (5.807s)
[912.728907] [123/134] (155s left) gen7_exec_parse (cmd-crossing-page)
[912.798117] Subtest cmd-crossing-page: SKIP (0.000s)
[912.884660] [124/134] (155s left) kms_frontbuffer_tracking (fbc-1p-rte)
[912.989259] Starting subtest: fbc-1p-rte
[918.490342] Subtest fbc-1p-rte: SUCCESS (5.507s)
[919.730110] [125/134] (148s left) gem_mmap_wc (write)
[919.798659] Subtest write: SKIP (0.000s)
[919.858630] [126/134] (148s left) kms_busy (extended-modeset-hang-newfb)
[919.950719] Starting subtest: extended-modeset-hang-newfb
[919.953543] Starting dynamic subtest: pipe-A
[954.809698] Dynamic subtest pipe-A: SUCCESS (34.857s)
[954.820366] Starting dynamic subtest: pipe-D
[974.985101] Dynamic subtest pipe-D: SUCCESS (20.172s)
[974.989660] Subtest extended-modeset-hang-newfb: SUCCESS (55.033s)
[975.796205] [127/134] ( 92s left) i915_pm_rpm (gem-evict-pwrite)
[977.364801] Starting subtest: gem-evict-pwrite
[977.369992] Subtest gem-evict-pwrite: SKIP (0.000s)
[978.142440] [128/134] ( 89s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[978.236629] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[978.239047] Starting dynamic subtest: pipe-A-eDP-1
[978.261679] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[978.273008] Starting dynamic subtest: pipe-B-eDP-1
[978.298272] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[978.307709] Starting dynamic subtest: pipe-C-eDP-1
[978.332828] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[978.342018] Starting dynamic subtest: pipe-D-eDP-1
[978.368954] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[978.373807] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[980.510826] [129/134] ( 87s left) i915_suspend (basic-s2idle-without-i915)
[980.554314] Starting subtest: basic-s2idle-without-i915
[1000.325583] Subtest basic-s2idle-without-i915: SUCCESS (4.318s)
[1000.452562] [130/134] ( 67s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[1000.557427] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[1000.559684] Starting dynamic subtest: pipe-A-default-mode
[1003.280482] Dynamic subtest pipe-A-default-mode: SUCCESS (2.716s)
[1003.283251] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.717s)
[1004.129162] [131/134] ( 64s left) kms_big_fb (4-tiled-addfb)
[1004.233807] Starting subtest: 4-tiled-addfb
[1004.236126] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[1004.363779] [132/134] ( 63s left) kms_setmode (basic-clone-single-crtc)
[1004.430089] Starting subtest: basic-clone-single-crtc
[1004.436789] Subtest basic-clone-single-crtc: SKIP (0.000s)
[1004.554566] [133/134] ( 63s left) gem_exec_balancer (full-late-pulse)
[1004.622886] Starting subtest: full-late-pulse
[1009.426987] Subtest full-late-pulse: SUCCESS (4.797s)
[1009.801575] [134/134] ( 58s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[1009.911679] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[1009.915886] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[1010.015143] 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.