[57.066737] Initializing watchdogs
[57.066786]   /dev/watchdog0
[57.081478] [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]
[57.086260] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.088396] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.091812] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[57.221417] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[61.435925] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.258s)
[62.642818] [002/133] (954s left) kms_selftest (drm_cmdline_parser)
[62.682290] Starting subtest: drm_cmdline_parser
[62.695106] Starting dynamic subtest: drm_test_cmdline_force_d_only
[62.788377] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.096s)
[62.797869] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[62.888349] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.099s)
[62.897885] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[62.992659] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.106s)
[63.000492] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[63.084525] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.091s)
[63.093975] Starting dynamic subtest: drm_test_cmdline_force_e_only
[63.180509] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.094s)
[63.187333] Starting dynamic subtest: drm_test_cmdline_res
[63.276250] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.096s)
[63.284994] Starting dynamic subtest: drm_test_cmdline_res_vesa
[63.365240] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.089s)
[63.372040] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[63.448081] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.083s)
[63.454919] Starting dynamic subtest: drm_test_cmdline_res_rblank
[63.539243] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.091s)
[63.546050] Starting dynamic subtest: drm_test_cmdline_res_bpp
[63.620579] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.082s)
[63.627419] Starting dynamic subtest: drm_test_cmdline_res_refresh
[63.717496] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.097s)
[63.724201] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[63.829209] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.111s)
[63.836451] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[63.915873] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.088s)
[63.922761] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[64.005072] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.089s)
[64.011854] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[64.100165] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.095s)
[64.108614] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[64.193321] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.094s)
[64.201847] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[64.279328] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.086s)
[64.287846] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[64.387185] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.109s)
[64.396180] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[64.488205] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.101s)
[64.496788] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[64.571141] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.082s)
[64.579941] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[64.675345] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.105s)
[64.684317] Starting dynamic subtest: drm_test_cmdline_name
[64.782344] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.108s)
[64.791129] Starting dynamic subtest: drm_test_cmdline_name_bpp
[64.909260] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.128s)
[64.916922] Starting dynamic subtest: drm_test_cmdline_name_option
[65.003355] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.094s)
[65.010182] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[65.093029] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.090s)
[65.100438] Starting dynamic subtest: drm_test_cmdline_rotate_0
[65.195391] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.102s)
[65.202107] Starting dynamic subtest: drm_test_cmdline_rotate_90
[65.275347] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.080s)
[65.283742] Starting dynamic subtest: drm_test_cmdline_rotate_180
[65.374257] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.099s)
[65.383019] Starting dynamic subtest: drm_test_cmdline_rotate_270
[65.466281] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.092s)
[65.472924] Starting dynamic subtest: drm_test_cmdline_hmirror
[65.561620] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.095s)
[65.568658] Starting dynamic subtest: drm_test_cmdline_vmirror
[65.652651] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.091s)
[65.660617] Starting dynamic subtest: drm_test_cmdline_margin_options
[65.736089] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.083s)
[65.742570] Starting dynamic subtest: drm_test_cmdline_multiple_options
[65.827736] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.092s)
[65.835335] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[65.915174] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.088s)
[65.921629] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[66.009723] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.094s)
[66.016481] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[66.105203] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.096s)
[66.111606] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[66.193243] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.088s)
[66.199638] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[66.302444] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.108s)
[66.309593] Starting dynamic subtest: drm_test_cmdline_invalid
[66.404197] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.103s)
[66.411352] Starting dynamic subtest: drm_test_cmdline_tv_options
[66.514245] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.110s)
[66.516165] Subtest drm_cmdline_parser: SUCCESS (3.850s)
[66.687235] [003/133] (950s left) perf_pmu (busy-accuracy-98)
[66.732610] Starting subtest: busy-accuracy-98
[66.735254] Starting dynamic subtest: rcs0
[69.788978] Dynamic subtest rcs0: SUCCESS (3.060s)
[69.799574] Starting dynamic subtest: bcs0
[72.835829] Dynamic subtest bcs0: SUCCESS (3.054s)
[72.844062] Starting dynamic subtest: vcs0
[75.881647] Dynamic subtest vcs0: SUCCESS (3.049s)
[75.891596] Starting dynamic subtest: vcs1
[78.924485] Dynamic subtest vcs1: SUCCESS (3.045s)
[78.934869] Starting dynamic subtest: vecs0
[81.966539] Dynamic subtest vecs0: SUCCESS (3.042s)
[81.976992] Starting dynamic subtest: ccs0
[85.017827] Dynamic subtest ccs0: SUCCESS (3.051s)
[85.020231] Subtest busy-accuracy-98: SUCCESS (18.306s)
[85.167193] [004/133] (932s left) gem_eio (reset-stress)
[85.378353] Starting subtest: reset-stress
[85.380580] Starting dynamic subtest: render
[89.750919] Dynamic subtest render: SUCCESS (4.370s)
[89.765791] Starting dynamic subtest: bsd1
[107.712990] Dynamic subtest bsd1: SUCCESS (17.960s)
[107.728525] Starting dynamic subtest: bsd2
[125.770048] Dynamic subtest bsd2: SUCCESS (18.056s)
[125.785916] Starting dynamic subtest: blt
[125.789811] Dynamic subtest blt: SKIP (0.000s)
[125.797943] Starting dynamic subtest: vebox
[143.742990] Dynamic subtest vebox: SUCCESS (17.972s)
[143.745050] Subtest reset-stress: SUCCESS (58.361s)
[145.337441] [FACT gem_eio (reset-stress)] new: kernel.kmod_is_loaded.vgem: true
[145.341057] [005/133] (872s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[145.444160] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[145.446465] Starting dynamic subtest: pipe-A-default-mode
[148.122855] Dynamic subtest pipe-A-default-mode: SUCCESS (2.680s)
[148.125816] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.681s)
[148.995310] [006/133] (868s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[149.088536] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[149.092986] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[149.178973] [007/133] (868s left) gem_ccs (block-copy-uncompressed)
[149.239768] Starting subtest: block-copy-uncompressed
[149.242157] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[149.261446] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s)
[149.272145] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[149.278491] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[149.287160] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[149.296599] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[149.305469] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[149.315032] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[149.317320] Subtest block-copy-uncompressed: SUCCESS (0.076s)
[149.460670] [008/133] (868s left) gem_exec_fence (basic-busy)
[149.648783] Starting subtest: basic-busy
[149.651397] Starting dynamic subtest: rcs0
[149.730737] Dynamic subtest rcs0: SUCCESS (0.085s)
[149.741990] Starting dynamic subtest: bcs0
[149.787638] Dynamic subtest bcs0: SUCCESS (0.056s)
[149.798677] Starting dynamic subtest: vcs0
[149.846226] Dynamic subtest vcs0: SUCCESS (0.058s)
[149.856582] Starting dynamic subtest: vcs1
[149.911257] Dynamic subtest vcs1: SUCCESS (0.064s)
[149.925065] Starting dynamic subtest: vecs0
[149.973214] Dynamic subtest vecs0: SUCCESS (0.061s)
[149.984216] Starting dynamic subtest: ccs0
[150.037895] Dynamic subtest ccs0: SUCCESS (0.064s)
[150.040401] Subtest basic-busy: SUCCESS (0.393s)
[151.389577] [009/133] (866s left) kms_psr (pr-cursor-blt)
[151.493096] Starting subtest: pr-cursor-blt
[151.497238] Subtest pr-cursor-blt: SKIP (0.000s)
[151.568243] [010/133] (866s left) gem_partial_pwrite_pread (reads-uncached)
[151.630540] Subtest reads-uncached: SKIP (0.000s)
[151.710827] [011/133] (866s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[151.812979] Starting subtest: fbcpsr-2p-rte
[151.817117] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[151.917842] [012/133] (866s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[152.025210] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[152.029365] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[152.138698] [013/133] (865s left) gem_exec_schedule (pi-shared-iova)
[152.340227] Starting subtest: pi-shared-iova
[152.342738] Starting dynamic subtest: rcs0
[152.424116] Dynamic subtest rcs0: SUCCESS (0.088s)
[152.432829] Starting dynamic subtest: vcs0
[152.495486] Dynamic subtest vcs0: SUCCESS (0.070s)
[152.507697] Starting dynamic subtest: vcs1
[152.551680] Dynamic subtest vcs1: SUCCESS (0.057s)
[152.563529] Starting dynamic subtest: vecs0
[152.611516] Dynamic subtest vecs0: SUCCESS (0.060s)
[152.623927] Starting dynamic subtest: ccs0
[152.682946] Dynamic subtest ccs0: SUCCESS (0.070s)
[152.685237] Subtest pi-shared-iova: SUCCESS (0.346s)
[154.403055] [014/133] (863s left) gem_create (hog-create)
[154.466491] Starting subtest: hog-create
[154.468930] Starting dynamic subtest: smem0
[188.289274] Dynamic subtest smem0: SUCCESS (33.819s)
[188.291936] Subtest hog-create: SUCCESS (33.820s)
[188.490351] [015/133] (829s left) kms_flip (bo-too-big-interruptible)
[188.594409] Starting subtest: bo-too-big-interruptible
[188.596855] Starting dynamic subtest: A-eDP1
[189.950209] Dynamic subtest A-eDP1: SUCCESS (1.354s)
[189.963961] Starting dynamic subtest: B-eDP1
[191.270354] Dynamic subtest B-eDP1: SUCCESS (1.318s)
[191.279626] Starting dynamic subtest: C-eDP1
[192.508827] Dynamic subtest C-eDP1: SUCCESS (1.238s)
[192.518211] Starting dynamic subtest: D-eDP1
[193.752063] Dynamic subtest D-eDP1: SUCCESS (1.242s)
[193.754896] Subtest bo-too-big-interruptible: SUCCESS (5.160s)
[194.967790] [016/133] (823s left) gem_exec_reloc (basic-cpu)
[195.030886] Subtest basic-cpu: SKIP (0.000s)
[195.096824] [017/133] (823s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[195.191964] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[202.234956] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.046s)
[202.361905] [018/133] (815s left) kms_flip (wf_vblank-ts-check-interruptible)
[202.462186] Starting subtest: wf_vblank-ts-check-interruptible
[202.464520] Starting dynamic subtest: A-eDP1
[211.348167] Dynamic subtest A-eDP1: SUCCESS (8.885s)
[211.357254] Starting dynamic subtest: B-eDP1
[220.283334] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[220.295164] Starting dynamic subtest: C-eDP1
[229.201409] Dynamic subtest C-eDP1: SUCCESS (8.916s)
[229.212387] Starting dynamic subtest: D-eDP1
[238.136393] Dynamic subtest D-eDP1: SUCCESS (8.934s)
[238.141590] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.679s)
[239.383537] [019/133] (778s left) syncobj_timeline (wait-for-submit-delayed-submit)
[239.455197] Starting subtest: wait-for-submit-delayed-submit
[239.560059] Subtest wait-for-submit-delayed-submit: SUCCESS (0.104s)
[239.646934] [020/133] (778s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[239.757499] Starting subtest: cursorB-vs-flipA-atomic
[239.762178] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[239.917072] [021/133] (778s left) kms_getfb (getfb-handle-closed)
[240.012631] Starting subtest: getfb-handle-closed
[240.014759] Subtest getfb-handle-closed: SUCCESS (0.000s)
[240.109164] [022/133] (778s left) kms_color (ctm-0-25)
[240.209039] Starting subtest: ctm-0-25
[240.211223] Starting dynamic subtest: pipe-A-eDP-1
[243.784904] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.577s)
[243.797927] Starting dynamic subtest: pipe-B-eDP-1
[248.077492] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.291s)
[248.088869] Starting dynamic subtest: pipe-C-eDP-1
[252.134869] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.058s)
[252.143806] Starting dynamic subtest: pipe-D-eDP-1
[256.100499] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.963s)
[256.102496] Subtest ctm-0-25: SUCCESS (15.891s)
[256.938335] [023/133] (761s left) kms_plane (plane-panning-top-left)
[257.036293] Starting subtest: plane-panning-top-left
[257.038568] Starting dynamic subtest: pipe-A
[258.695767] Dynamic subtest pipe-A: SUCCESS (1.661s)
[258.705057] Starting dynamic subtest: pipe-B
[261.059603] Dynamic subtest pipe-B: SUCCESS (2.361s)
[261.061712] Subtest plane-panning-top-left: SUCCESS (4.024s)
[261.874936] [024/133] (756s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[261.996680] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[262.001087] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[262.095971] [025/133] (756s left) syncobj_eventfd (timeline-wait-signaled)
[262.152786] Starting subtest: timeline-wait-signaled
[262.155075] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[262.255164] [026/133] (756s left) kms_async_flips (alternate-sync-async-flip)
[262.345078] Starting subtest: alternate-sync-async-flip
[262.717076] Starting dynamic subtest: pipe-A-eDP-1
[265.551654] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.835s)
[265.948321] Starting dynamic subtest: pipe-B-eDP-1
[268.690276] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.752s)
[269.075793] Starting dynamic subtest: pipe-C-eDP-1
[271.824630] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.758s)
[272.193640] Starting dynamic subtest: pipe-D-eDP-1
[274.953060] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.767s)
[274.955134] Subtest alternate-sync-async-flip: SUCCESS (12.611s)
[276.129890] [027/133] (742s left) perf_pmu (invalid-init)
[276.176636] Starting subtest: invalid-init
[276.178876] Subtest invalid-init: SUCCESS (0.000s)
[276.275532] [028/133] (742s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[276.375165] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[276.387327] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[276.464581] [029/133] (742s left) kms_prop_blob (blob-prop-lifetime)
[276.516176] Starting subtest: blob-prop-lifetime
[276.518515] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[276.610738] [030/133] (742s left) kms_psr (fbc-pr-primary-mmap-gtt)
[276.707785] Starting subtest: fbc-pr-primary-mmap-gtt
[276.711849] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[276.792385] [031/133] (742s left) i915_pm_rpm (gem-idle)
[278.432724] Starting subtest: gem-idle
[284.632263] Subtest gem-idle: SUCCESS (6.202s)
[284.959531] [032/133] (733s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[286.301811] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[286.306196] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[286.416954] [033/133] (732s left) gem_ctx_persistence (many-contexts)
[286.470310] Starting subtest: many-contexts
[324.125039] Subtest many-contexts: SUCCESS (37.652s)
[324.221790] [034/133] (694s left) kms_psr (fbc-psr-dpms)
[324.329277] Starting subtest: fbc-psr-dpms
[324.331445] Starting dynamic subtest: eDP-1
[324.335943] Dynamic subtest eDP-1: SKIP (0.000s)
[324.340102] Subtest fbc-psr-dpms: SKIP (0.002s)
[324.416273] [035/133] (694s left) gem_exec_balancer (bonded-dual)
[324.477925] Starting subtest: bonded-dual
[324.482682] Subtest bonded-dual: SKIP (0.000s)
[324.786359] [036/133] (694s left) testdisplay
[325.082573] [037/133] (694s left) kms_flip (2x-flip-vs-fences-interruptible)
[325.171354] Starting subtest: 2x-flip-vs-fences-interruptible
[325.175681] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[325.261423] [038/133] (693s left) i915_drm_fdinfo (memory-info-shared)
[325.309091] Starting subtest: memory-info-shared
[325.311322] Starting dynamic subtest: smem0
[325.361842] Dynamic subtest smem0: SUCCESS (0.054s)
[325.364542] Subtest memory-info-shared: SUCCESS (0.054s)
[325.449860] [039/133] (693s left) kms_vblank (query-forked-hang)
[325.542161] Starting subtest: query-forked-hang
[325.544459] Starting dynamic subtest: pipe-A-eDP-1
[352.091164] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.544s)
[352.106243] Starting dynamic subtest: pipe-D-eDP-1
[377.055065] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.963s)
[377.057426] Subtest query-forked-hang: SUCCESS (51.512s)
[377.924659] [040/133] (641s left) kms_plane_cursor (viewport)
[378.033350] Starting subtest: viewport
[378.037886] Starting dynamic subtest: pipe-A-eDP-1-size-64
[380.681724] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.647s)
[380.740349] Starting dynamic subtest: pipe-A-eDP-1-size-128
[383.180652] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.449s)
[383.241036] Starting dynamic subtest: pipe-A-eDP-1-size-256
[385.682058] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.449s)
[386.041960] Starting dynamic subtest: pipe-B-eDP-1-size-64
[389.268583] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.237s)
[389.327138] Starting dynamic subtest: pipe-B-eDP-1-size-128
[391.802951] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.482s)
[391.863205] Starting dynamic subtest: pipe-B-eDP-1-size-256
[394.436334] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.582s)
[394.797624] Starting dynamic subtest: pipe-C-eDP-1-size-64
[397.609448] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.826s)
[397.646097] Starting dynamic subtest: pipe-C-eDP-1-size-128
[399.752267] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.114s)
[399.790941] Starting dynamic subtest: pipe-C-eDP-1-size-256
[401.908585] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.125s)
[402.236230] Starting dynamic subtest: pipe-D-eDP-1-size-64
[404.978453] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.753s)
[405.016786] Starting dynamic subtest: pipe-D-eDP-1-size-128
[407.190704] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.181s)
[407.227790] Starting dynamic subtest: pipe-D-eDP-1-size-256
[409.467485] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.247s)
[409.792509] Subtest viewport: SUCCESS (31.759s)
[410.662309] [041/133] (608s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[410.768327] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[414.764621] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.999s)
[415.954784] [042/133] (603s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[416.058074] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[416.062256] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[416.152176] [043/133] (603s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[416.243760] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[416.247958] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[416.340423] [044/133] (603s left) gem_exec_schedule (u-submit-late-slice)
[416.406701] Starting subtest: u-submit-late-slice
[416.408793] Starting dynamic subtest: rcs0
[416.433164] Dynamic subtest rcs0: SUCCESS (0.036s)
[416.444956] Starting dynamic subtest: bcs0
[416.477085] Dynamic subtest bcs0: SUCCESS (0.043s)
[416.487864] Starting dynamic subtest: vcs0
[416.506268] Dynamic subtest vcs0: SUCCESS (0.029s)
[416.517178] Starting dynamic subtest: vcs1
[416.537536] Dynamic subtest vcs1: SUCCESS (0.031s)
[416.546937] Starting dynamic subtest: vecs0
[416.570821] Dynamic subtest vecs0: SUCCESS (0.033s)
[416.580282] Starting dynamic subtest: ccs0
[416.606076] Dynamic subtest ccs0: SUCCESS (0.034s)
[416.608216] Subtest u-submit-late-slice: SUCCESS (0.210s)
[418.396306] [045/133] (601s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[418.448251] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[418.514146] [046/133] (600s left) kms_psr (psr-sprite-plane-onoff)
[418.603272] Starting subtest: psr-sprite-plane-onoff
[418.605569] Starting dynamic subtest: eDP-1
[420.383997] Dynamic subtest eDP-1: SUCCESS (1.779s)
[420.386282] Subtest psr-sprite-plane-onoff: SUCCESS (1.780s)
[421.204244] [047/133] (598s left) syncobj_timeline (multi-wait-signaled)
[421.263109] Starting subtest: multi-wait-signaled
[421.265479] Subtest multi-wait-signaled: SUCCESS (0.000s)
[421.385528] [048/133] (598s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[421.506077] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[422.674276] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.168s)
[422.796038] [049/133] (596s left) gem_mmap_wc (write-read-distinct)
[422.852044] Subtest write-read-distinct: SKIP (0.000s)
[422.913847] [050/133] (596s left) dmabuf_sync_file (export-basic)
[422.958182] Starting subtest: export-basic
[422.960147] Subtest export-basic: SUCCESS (0.001s)
[423.016233] [051/133] (596s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[423.082076] Subtest untiled-to-tiled: SKIP (0.000s)
[423.164503] [052/133] (596s left) gem_userptr_blits (nohangcheck)
[423.238448] Starting subtest: nohangcheck
[423.454643] Subtest nohangcheck: SUCCESS (0.214s)
[423.582878] [053/133] (596s left) kms_properties (crtc-properties-legacy)
[423.677293] Starting subtest: crtc-properties-legacy
[423.679486] Starting dynamic subtest: pipe-A-eDP-1
[424.116427] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.437s)
[424.133833] Starting dynamic subtest: pipe-B-eDP-1
[425.245269] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.130s)
[425.256069] Starting dynamic subtest: pipe-C-eDP-1
[426.358773] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.111s)
[426.368570] Starting dynamic subtest: pipe-D-eDP-1
[427.502263] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[427.504557] Subtest crtc-properties-legacy: SUCCESS (3.827s)
[428.316715] [054/133] (591s left) kms_chamelium_frames (dp-crc-fast)
[428.706085] Subtest dp-crc-fast: SKIP (0.000s)
[429.543797] [055/133] (590s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[429.648603] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[429.652948] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[429.755624] [056/133] (590s left) syncobj_basic (create-signaled)
[429.822513] Starting subtest: create-signaled
[429.824456] Subtest create-signaled: SUCCESS (0.000s)
[429.918095] [057/133] (590s left) gem_exec_reloc (basic-write-cpu)
[429.984595] Subtest basic-write-cpu: SKIP (0.000s)
[430.057368] [058/133] (589s left) tools_test (tools_test)
[430.106471] Starting subtest: tools_test
[430.160253] Subtest tools_test: SUCCESS (0.067s)
[430.222257] [059/133] (589s left) gem_exec_fence (syncobj-invalid-flags)
[430.658786] Starting subtest: syncobj-invalid-flags
[430.661719] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[432.412045] [060/133] (587s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[432.754862] Starting subtest: cursor-rapid-movement-512x170
[432.758796] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[432.910735] [061/133] (587s left) kms_atomic_interruptible (atomic-setmode)
[433.004343] Starting subtest: atomic-setmode
[433.006669] Starting dynamic subtest: pipe-A-eDP-1
[439.882607] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.878s)
[439.884848] Subtest atomic-setmode: SUCCESS (6.879s)
[440.680868] [062/133] (579s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[440.782242] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[440.784364] Starting dynamic subtest: pipe-A-eDP-1
[440.939171] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.148s)
[440.950421] Starting dynamic subtest: pipe-B-eDP-1
[440.969502] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[440.979171] Starting dynamic subtest: pipe-C-eDP-1
[440.999255] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[441.008401] Starting dynamic subtest: pipe-D-eDP-1
[441.027846] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[441.030064] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.187s)
[443.244711] [063/133] (576s left) kms_big_fb (linear-32bpp-rotate-180)
[443.344073] Starting subtest: linear-32bpp-rotate-180
[444.901127] Subtest linear-32bpp-rotate-180: SUCCESS (1.557s)
[445.043688] [064/133] (575s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[445.162540] Starting subtest: short-busy-flip-before-cursor-toggle
[445.166424] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[445.308869] [065/133] (574s left) gem_create (create-valid-nonaligned)
[445.369915] Starting subtest: create-valid-nonaligned
[445.371581] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[445.561809] [066/133] (574s left) kms_cursor_edge_walk (128x128-left-edge)
[445.667464] Starting subtest: 128x128-left-edge
[445.669826] Starting dynamic subtest: pipe-A-eDP-1
[449.118563] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[449.128324] Starting dynamic subtest: pipe-D-eDP-1
[452.242872] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[452.245179] Subtest 128x128-left-edge: SUCCESS (6.577s)
[453.060313] [067/133] (567s left) vgem_basic (bad-flag)
[453.112603] Starting subtest: bad-flag
[453.114501] Subtest bad-flag: SUCCESS (0.000s)
[453.167425] [068/133] (567s left) gem_mmap (bad-size)
[453.217619] Subtest bad-size: SKIP (0.000s)
[453.273909] [069/133] (567s left) gem_ctx_shared (exec-shared-gtt)
[453.336694] Starting subtest: exec-shared-gtt
[453.338893] Starting dynamic subtest: rcs0
[453.348099] Dynamic subtest rcs0: SUCCESS (0.010s)
[453.357900] Starting dynamic subtest: bcs0
[453.359701] Dynamic subtest bcs0: SUCCESS (0.005s)
[453.369102] Starting dynamic subtest: vcs0
[453.371196] Dynamic subtest vcs0: SUCCESS (0.006s)
[453.380301] Starting dynamic subtest: vcs1
[453.382422] Dynamic subtest vcs1: SUCCESS (0.007s)
[453.391418] Starting dynamic subtest: vecs0
[453.393260] Dynamic subtest vecs0: SUCCESS (0.007s)
[453.401760] Starting dynamic subtest: ccs0
[453.403678] Dynamic subtest ccs0: SUCCESS (0.007s)
[453.405545] Subtest exec-shared-gtt: SUCCESS (0.047s)
[453.570282] [070/133] (566s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[453.700384] Starting subtest: basic-busy-flip-before-cursor-atomic
[453.704661] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[453.856497] [071/133] (566s left) kms_cursor_crc (cursor-onscreen-256x256)
[454.144013] Starting subtest: cursor-onscreen-256x256
[454.148460] Starting dynamic subtest: pipe-A-eDP-1
[456.312624] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.161s)
[456.343303] Starting dynamic subtest: pipe-D-eDP-1
[459.208228] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.872s)
[459.210083] Subtest cursor-onscreen-256x256: SUCCESS (5.062s)
[460.008988] [072/133] (560s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[460.105321] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[467.337166] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.234s)
[467.462876] [073/133] (553s left) gem_tiled_fence_blits (basic)
[467.535555] Subtest basic: SKIP (0.000s)
[467.597976] [074/133] (553s left) api_intel_allocator (standalone)
[467.655809] Starting subtest: standalone
[467.670973] Subtest standalone: SUCCESS (0.018s)
[467.805397] [075/133] (552s left) syncobj_wait (invalid-wait-illegal-handle)
[467.861258] Starting subtest: invalid-wait-illegal-handle
[467.863586] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[467.958869] [076/133] (552s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[468.057126] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[468.061058] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[468.151227] [077/133] (552s left) kms_plane (pixel-format-linear-modifier)
[468.239012] Starting subtest: pixel-format-linear-modifier
[468.264897] Starting dynamic subtest: pipe-A-plane-0
[471.864455] Dynamic subtest pipe-A-plane-0: SUCCESS (3.600s)
[471.873960] Starting dynamic subtest: pipe-A-plane-3
[473.587283] Dynamic subtest pipe-A-plane-3: SUCCESS (1.721s)
[473.606367] Starting dynamic subtest: pipe-A-plane-5
[473.797789] Dynamic subtest pipe-A-plane-5: SUCCESS (0.209s)
[474.915973] Starting dynamic subtest: pipe-B-plane-0
[478.473220] Dynamic subtest pipe-B-plane-0: SUCCESS (3.566s)
[478.484790] Starting dynamic subtest: pipe-B-plane-3
[480.240483] Dynamic subtest pipe-B-plane-3: SUCCESS (1.764s)
[480.250413] Starting dynamic subtest: pipe-B-plane-5
[480.462137] Dynamic subtest pipe-B-plane-5: SUCCESS (0.220s)
[480.783611] Subtest pixel-format-linear-modifier: SUCCESS (12.543s)
[481.593203] [078/133] (539s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[481.693898] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[481.698108] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[481.803548] [079/133] (539s left) kms_flip (nonexisting-fb)
[481.898639] Starting subtest: nonexisting-fb
[481.900620] Starting dynamic subtest: A-eDP1
[483.087940] Dynamic subtest A-eDP1: SUCCESS (1.191s)
[483.098476] Starting dynamic subtest: B-eDP1
[484.228408] Dynamic subtest B-eDP1: SUCCESS (1.139s)
[484.238172] Starting dynamic subtest: C-eDP1
[485.367227] Dynamic subtest C-eDP1: SUCCESS (1.137s)
[485.385933] Starting dynamic subtest: D-eDP1
[486.506277] Dynamic subtest D-eDP1: SUCCESS (1.136s)
[486.512233] Subtest nonexisting-fb: SUCCESS (4.615s)
[487.684550] [080/133] (533s left) kms_content_protection (uevent)
[487.827954] Starting subtest: uevent
[487.832385] Subtest uevent: SKIP (0.000s)
[487.942464] [081/133] (533s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[488.036057] Starting subtest: crc-primary-basic-y-tiled-ccs
[488.038247] Starting dynamic subtest: pipe-A-eDP-1
[488.061323] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[488.073970] Starting dynamic subtest: pipe-B-eDP-1
[488.095483] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[488.104741] Starting dynamic subtest: pipe-C-eDP-1
[488.127305] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[488.136106] Starting dynamic subtest: pipe-D-eDP-1
[488.158073] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[488.162149] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s)
[490.362453] [082/133] (530s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[490.427851] Starting subtest: map-fixed-invalidate-overlap-busy
[490.432115] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[490.552113] [083/133] (530s left) kms_frontbuffer_tracking (psr-slowdraw)
[490.654755] Starting subtest: psr-slowdraw
[515.887259] Subtest psr-slowdraw: SUCCESS (25.236s)
[516.018991] [084/133] (505s left) gem_mmap_gtt (ptrace)
[516.076827] Subtest ptrace: SKIP (0.000s)
[516.152853] [085/133] (505s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[516.248117] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[516.250416] Starting dynamic subtest: pipe-A
[516.316136] Dynamic subtest pipe-A: SUCCESS (0.069s)
[516.327432] Starting dynamic subtest: pipe-B
[517.495978] Dynamic subtest pipe-B: SUCCESS (1.179s)
[517.505700] Starting dynamic subtest: pipe-C
[518.619907] Dynamic subtest pipe-C: SUCCESS (1.123s)
[518.629773] Starting dynamic subtest: pipe-D
[519.743135] Dynamic subtest pipe-D: SUCCESS (1.122s)
[519.745400] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.497s)
[520.910827] [086/133] (500s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[521.019631] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[528.409468] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.392s)
[528.571307] [087/133] (492s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[528.683969] Starting subtest: 4-tiled-32bpp-rotate-90
[528.703840] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s)
[528.819871] [088/133] (492s left) kms_draw_crc (draw-method-blt)
[528.918182] Starting subtest: draw-method-blt
[528.920433] Starting dynamic subtest: xrgb8888-untiled
[529.173056] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[529.182970] Starting dynamic subtest: rgb565-untiled
[529.416293] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[529.427860] Starting dynamic subtest: xrgb2101010-untiled
[529.627296] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.209s)
[529.637318] Starting dynamic subtest: xbgr16161616f-untiled
[529.838451] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[529.848352] Starting dynamic subtest: xrgb8888-xtiled
[529.960578] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[529.972454] Starting dynamic subtest: rgb565-xtiled
[530.072097] Dynamic subtest rgb565-xtiled: SUCCESS (0.109s)
[530.083955] Starting dynamic subtest: xrgb2101010-xtiled
[530.183021] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[530.194587] Starting dynamic subtest: xbgr16161616f-xtiled
[530.305607] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.120s)
[530.314563] Starting dynamic subtest: xrgb8888-4tiled
[530.427506] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[530.439436] Starting dynamic subtest: rgb565-4tiled
[530.538471] Dynamic subtest rgb565-4tiled: SUCCESS (0.109s)
[530.547571] Starting dynamic subtest: xrgb2101010-4tiled
[530.638486] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.099s)
[530.652075] Starting dynamic subtest: xbgr16161616f-4tiled
[530.760895] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[530.762798] Subtest draw-method-blt: SUCCESS (1.845s)
[530.878959] [089/133] (490s left) syncobj_wait (invalid-signal-bad-pad)
[530.933478] Starting subtest: invalid-signal-bad-pad
[530.935330] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[531.030337] [090/133] (490s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[531.150225] Starting subtest: cursorB-vs-flipA-toggle
[531.153783] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[531.293895] [091/133] (490s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[531.390689] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[532.205185] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.815s)
[532.358457] [092/133] (489s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[532.454865] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[532.459397] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[533.553091] [093/133] (487s left) kms_pm_dc (dc6-psr)
[534.642724] Starting subtest: dc6-psr
[537.526348] Subtest dc6-psr: SUCCESS (2.883s)
[538.378751] [094/133] (483s left) kms_chamelium_color (ctm-0-50)
[538.464539] Subtest ctm-0-50: SKIP (0.000s)
[538.514375] [095/133] (483s left) syncobj_timeline (invalid-transfer-illegal-handle)
[538.571165] Starting subtest: invalid-transfer-illegal-handle
[538.573065] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[538.666116] [096/133] (482s left) gem_exec_flush (basic-wb-rw-default)
[538.730636] Starting subtest: basic-wb-rw-default
[544.187040] Subtest basic-wb-rw-default: SUCCESS (5.456s)
[544.376690] [097/133] (477s left) gen9_exec_parse (batch-without-end)
[544.439823] Subtest batch-without-end: SKIP (0.000s)
[544.517491] [098/133] (477s left) gem_ppgtt (shrink-vs-evict-pinned)
[544.571689] Starting subtest: shrink-vs-evict-pinned
[550.303973] Subtest shrink-vs-evict-pinned: SUCCESS (5.730s)
[550.392119] [099/133] (471s left) gem_exec_reloc (basic-write-cpu-noreloc)
[550.452802] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[550.529476] [100/133] (471s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[550.638275] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[550.642185] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[550.709073] [101/133] (471s left) kms_hdr (static-toggle-suspend)
[550.816095] Starting subtest: static-toggle-suspend
[550.822575] Subtest static-toggle-suspend: SKIP (0.000s)
[550.891014] [102/133] (470s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[550.977504] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[558.091917] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.116s)
[558.209440] [103/133] (463s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[558.308261] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[558.310444] Starting dynamic subtest: pipe-A-eDP-1
[558.330692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[558.342337] Starting dynamic subtest: pipe-B-eDP-1
[558.360627] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[558.369771] Starting dynamic subtest: pipe-C-eDP-1
[558.388149] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[558.397349] Starting dynamic subtest: pipe-D-eDP-1
[558.416328] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[558.418177] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[560.505057] [104/133] (461s left) kms_flip (2x-flip-vs-dpms)
[560.599200] Starting subtest: 2x-flip-vs-dpms
[560.608074] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[560.709780] [105/133] (461s left) kms_plane_alpha_blend (alpha-transparent-fb)
[560.802334] Starting subtest: alpha-transparent-fb
[561.093992] Starting dynamic subtest: pipe-A-eDP-1
[561.752735] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.656s)
[561.762836] Starting dynamic subtest: pipe-D-eDP-1
[563.172314] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.421s)
[563.174556] Subtest alpha-transparent-fb: SUCCESS (2.373s)
[564.057951] [106/133] (457s left) kms_pm_rpm (system-suspend-modeset)
[565.599371] Starting subtest: system-suspend-modeset
[585.511561] Subtest system-suspend-modeset: SUCCESS (4.522s)
[585.897088] [107/133] (436s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[585.974366] Starting subtest: etime-single-wait-available-unsubmitted
[586.084124] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.110s)
[586.163556] [108/133] (435s left) kms_async_flips (test-cursor-atomic)
[586.264490] Starting subtest: test-cursor-atomic
[586.643917] Starting dynamic subtest: pipe-A-eDP-1
[587.446217] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.815s)
[587.841292] Starting dynamic subtest: pipe-B-eDP-1
[588.588279] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[588.973265] Starting dynamic subtest: pipe-C-eDP-1
[589.713867] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.764s)
[590.082662] Starting dynamic subtest: pipe-D-eDP-1
[590.835585] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[590.837743] Subtest test-cursor-atomic: SUCCESS (4.631s)
[592.000232] [109/133] (430s left) kms_chamelium_frames (vga-frame-dump)
[592.391562] Subtest vga-frame-dump: SKIP (0.000s)
[593.197594] [110/133] (429s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[593.297013] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[593.301478] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[593.391805] [111/133] (428s left) gem_ctx_exec (basic-norecovery)
[593.452058] Starting subtest: basic-norecovery
[593.761283] Subtest basic-norecovery: SUCCESS (0.312s)
[593.892078] [112/133] (428s left) gem_userptr_blits (map-fixed-invalidate)
[593.950777] Starting subtest: map-fixed-invalidate
[593.954879] Subtest map-fixed-invalidate: SKIP (0.000s)
[594.079344] [113/133] (428s left) perf (mi-rpc)
[594.211503] Starting subtest: mi-rpc
[594.214881] Subtest mi-rpc: SKIP (0.000s)
[594.263080] [114/133] (428s left) kms_addfb_basic (basic)
[594.310784] Starting subtest: basic
[594.312751] Subtest basic: SUCCESS (0.000s)
[594.451128] [115/133] (427s left) gem_exec_fence (syncobj-unused-fence)
[594.908168] Starting subtest: syncobj-unused-fence
[594.913852] Subtest syncobj-unused-fence: SUCCESS (0.004s)
[596.832698] [116/133] (425s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[596.937435] Starting subtest: yf-tiled-64bpp-rotate-0
[596.941702] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[597.022555] [117/133] (425s left) gem_gpgpu_fill (basic)
[597.085022] Starting subtest: basic
[597.087252] Starting dynamic subtest: smem
[597.092220] Dynamic subtest smem: SUCCESS (0.007s)
[597.094466] Subtest basic: SUCCESS (0.007s)
[597.233108] [118/133] (425s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[597.289118] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[597.293669] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[597.486811] [119/133] (425s left) kms_psr (psr2-sprite-plane-move)
[597.580621] Starting subtest: psr2-sprite-plane-move
[597.582891] Starting dynamic subtest: eDP-1
[599.321568] Dynamic subtest eDP-1: SUCCESS (1.748s)
[599.323801] Subtest psr2-sprite-plane-move: SUCCESS (1.749s)
[600.139857] [120/133] (422s left) i915_pm_freq_api (freq-reset-multiple)
[600.202372] Starting subtest: freq-reset-multiple
[600.204267] Starting dynamic subtest: gt0
[609.177541] Dynamic subtest gt0: SUCCESS (8.992s)
[609.188478] Starting dynamic subtest: gt1
[617.553201] Dynamic subtest gt1: SUCCESS (8.377s)
[617.555957] Subtest freq-reset-multiple: SUCCESS (17.371s)
[617.639636] [121/133] (405s left) core_auth (getclient-master-drop)
[617.677537] Starting subtest: getclient-master-drop
[617.694883] Subtest getclient-master-drop: SUCCESS (0.017s)
[617.797210] [122/133] (404s left) i915_suspend (forcewake)
[617.852563] Starting subtest: forcewake
[650.241429] Subtest forcewake: SUCCESS (2.266s)
[650.314084] [123/133] (372s left) kms_psr (fbc-pr-cursor-render)
[650.422191] Starting subtest: fbc-pr-cursor-render
[650.426619] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[650.508288] [124/133] (372s left) prime_vgem (wait)
[650.573252] Starting subtest: wait
[650.575597] Starting dynamic subtest: rcs0
[650.609520] Dynamic subtest rcs0: SUCCESS (0.036s)
[650.620111] Starting dynamic subtest: bcs0
[650.656572] Dynamic subtest bcs0: SUCCESS (0.047s)
[650.667508] Starting dynamic subtest: vcs0
[650.703897] Dynamic subtest vcs0: SUCCESS (0.046s)
[650.714312] Starting dynamic subtest: vcs1
[650.749862] Dynamic subtest vcs1: SUCCESS (0.045s)
[650.760275] Starting dynamic subtest: vecs0
[650.795505] Dynamic subtest vecs0: SUCCESS (0.044s)
[650.805813] Starting dynamic subtest: ccs0
[650.843762] Dynamic subtest ccs0: SUCCESS (0.047s)
[650.846096] Subtest wait: SUCCESS (0.271s)
[650.969257] [125/133] (371s left) gem_exec_schedule (u-fairslice-all)
[651.052478] Starting subtest: u-fairslice-all
[653.110354] Subtest u-fairslice-all: SUCCESS (2.074s)
[654.843156] [126/133] (368s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[654.942875] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[654.947336] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[656.121358] [127/133] (366s left) vgem_basic (dmabuf-fence)
[656.172551] Starting subtest: dmabuf-fence
[656.174666] Subtest dmabuf-fence: SUCCESS (0.000s)
[656.232553] [128/133] (366s left) perf_pmu (rc6-all-gts)
[656.272837] Starting subtest: rc6-all-gts
[662.646861] Subtest rc6-all-gts: SUCCESS (6.371s)
[663.545085] [129/133] (359s left) kms_legacy_colorkey (invalid-plane)
[663.640066] Starting subtest: invalid-plane
[663.642305] Starting dynamic subtest: zero-id
[663.644158] Dynamic subtest zero-id: SUCCESS (0.000s)
[663.655231] Starting dynamic subtest: outrange-id
[663.657445] Dynamic subtest outrange-id: SUCCESS (0.000s)
[663.659658] Subtest invalid-plane: SUCCESS (0.001s)
[663.713635] [130/133] (359s left) kms_flip (2x-flip-vs-rmfb)
[663.821302] Starting subtest: 2x-flip-vs-rmfb
[663.830593] Subtest 2x-flip-vs-rmfb: SKIP (0.011s)
[663.912110] [131/133] (359s left) kms_getfb (getfb2-handle-zero)
[664.012632] Starting subtest: getfb2-handle-zero
[664.014582] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[664.107217] [132/133] (359s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[664.238278] Starting subtest: verify-pxp-key-change-after-suspend-resume
[671.453202] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.217s)
[671.590463] [133/133] (351s left) gem_spin_batch (resubmit)
[671.649852] Starting subtest: resubmit
[671.652189] Starting dynamic subtest: rcs0
[671.656873] Dynamic subtest rcs0: SUCCESS (0.006s)
[671.668611] Starting dynamic subtest: bcs0
[671.670824] Dynamic subtest bcs0: SUCCESS (0.002s)
[671.681208] Starting dynamic subtest: vcs0
[671.683091] Dynamic subtest vcs0: SUCCESS (0.002s)
[671.693950] Starting dynamic subtest: vcs1
[671.696180] Dynamic subtest vcs1: SUCCESS (0.002s)
[671.706391] Starting dynamic subtest: vecs0
[671.708626] Dynamic subtest vecs0: SUCCESS (0.004s)
[671.718912] Starting dynamic subtest: ccs0
[671.721149] Dynamic subtest ccs0: SUCCESS (0.006s)
[671.723307] Subtest resubmit: SUCCESS (0.028s)
[671.909391] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_selftest
results: parsing output: 2/ for test: perf_pmu
results: parsing output: 3/ for test: gem_eio
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: gem_partial_pwrite_pread
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_exec_schedule
results: parsing output: 13/ for test: gem_create
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_exec_reloc
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_getfb
results: parsing output: 21/ for test: kms_color
results: parsing output: 22/ for test: kms_plane
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_eventfd
results: parsing output: 25/ for test: kms_async_flips
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: kms_prop_blob
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: i915_pm_rpm
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: gem_ctx_persistence
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: gem_exec_balancer
results: parsing output: 35/ for test: testdisplay
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: kms_vblank
results: parsing output: 39/ for test: kms_plane_cursor
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: gem_exec_reloc
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: gem_mmap_wc
results: parsing output: 49/ for test: dmabuf_sync_file
results: parsing output: 50/ for test: gem_set_tiling_vs_blt
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_properties
results: parsing output: 53/ for test: kms_chamelium_frames
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: syncobj_basic
results: parsing output: 56/ for test: gem_exec_reloc
results: parsing output: 57/ for test: tools_test
results: parsing output: 58/ for test: gem_exec_fence
results: parsing output: 59/ for test: kms_cursor_crc
results: parsing output: 60/ for test: kms_atomic_interruptible
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: gem_create
results: parsing output: 65/ for test: kms_cursor_edge_walk
results: parsing output: 66/ for test: vgem_basic
results: parsing output: 67/ for test: gem_mmap
results: parsing output: 68/ for test: gem_ctx_shared
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_tiled_fence_blits
results: parsing output: 73/ for test: api_intel_allocator
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_plane
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: kms_content_protection
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_mmap_gtt
results: parsing output: 84/ for test: kms_plane_scaling
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_draw_crc
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_pm_dc
results: parsing output: 93/ for test: kms_chamelium_color
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: gem_exec_flush
results: parsing output: 96/ for test: gen9_exec_parse
results: parsing output: 97/ for test: gem_ppgtt
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_psr2_sf
results: parsing output: 100/ for test: kms_hdr
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: kms_plane_alpha_blend
results: parsing output: 105/ for test: kms_pm_rpm
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_async_flips
results: parsing output: 108/ for test: kms_chamelium_frames
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_ctx_exec
results: parsing output: 111/ for test: gem_userptr_blits
results: parsing output: 112/ for test: perf
results: parsing output: 113/ for test: kms_addfb_basic
results: parsing output: 114/ for test: gem_exec_fence
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: gem_gpgpu_fill
results: parsing output: 117/ for test: gem_render_copy
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: i915_pm_freq_api
results: parsing output: 120/ for test: core_auth
results: parsing output: 121/ for test: i915_suspend
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: prime_vgem
results: parsing output: 124/ for test: gem_exec_schedule
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: vgem_basic
results: parsing output: 127/ for test: perf_pmu
results: parsing output: 128/ for test: kms_legacy_colorkey
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: kms_getfb
results: parsing output: 131/ for test: gem_pxp
results: parsing output: 132/ for test: gem_spin_batch
Done.