[58.704607] Initializing watchdogs
[58.704701]   /dev/watchdog0
[58.718117] [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]
[58.722174] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.724281] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.727599] [001/132] (960s left) gem_ctx_freq (sysfs)
[58.825981] Starting subtest: sysfs
[58.828383] Starting dynamic subtest: gt0
[64.396801] Dynamic subtest gt0: SUCCESS (5.602s)
[64.403636] Starting dynamic subtest: gt1
[105.876348] Dynamic subtest gt1: SUCCESS (41.498s)
[105.878896] Subtest sysfs: SUCCESS (47.101s)
[106.054879] [002/132] (913s left) prime_self_import (basic-llseek-bad)
[106.087843] Starting subtest: basic-llseek-bad
[106.113703] Subtest basic-llseek-bad: SUCCESS (0.026s)
[106.222816] [003/132] (913s left) core_setmaster (master-drop-set-shared-fd)
[106.321063] Starting subtest: master-drop-set-shared-fd
[106.343045] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[106.377246] [004/132] (912s left) syncobj_wait (multi-wait-all-submitted-signaled)
[106.433599] Starting subtest: multi-wait-all-submitted-signaled
[106.537578] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[106.642164] [005/132] (912s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[106.743701] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[106.746056] Starting dynamic subtest: pipe-A-default-mode
[107.923308] Dynamic subtest pipe-A-default-mode: SKIP (1.178s)
[107.927199] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.179s)
[108.112894] [006/132] (911s left) gem_mmap_gtt (basic-write)
[108.175962] Subtest basic-write: SKIP (0.000s)
[108.249815] [007/132] (911s left) kms_psr (pr-cursor-mmap-gtt)
[108.352173] Starting subtest: pr-cursor-mmap-gtt
[108.356506] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[108.447689] [008/132] (911s left) i915_pm_rpm (gem-mmap-type)
[110.096152] Starting subtest: gem-mmap-type
[110.098986] Starting dynamic subtest: wb-smem0
[114.133960] Dynamic subtest wb-smem0: SUCCESS (4.038s)
[114.141044] Starting dynamic subtest: wc-smem0
[119.958904] Dynamic subtest wc-smem0: SUCCESS (5.824s)
[119.967896] Starting dynamic subtest: uc-smem0
[122.848837] Dynamic subtest uc-smem0: SUCCESS (2.889s)
[122.851254] Subtest gem-mmap-type: SUCCESS (12.751s)
[122.952336] [009/132] (896s left) perf_pmu (rc6-suspend)
[122.992727] Starting subtest: rc6-suspend
[146.185124] Subtest rc6-suspend: SUCCESS (11.247s)
[147.067709] [010/132] (872s left) kms_plane_multiple (2x-tiling-x)
[147.185255] Starting subtest: 2x-tiling-x
[147.188753] Subtest 2x-tiling-x: SKIP (0.000s)
[147.274214] [011/132] (872s left) kms_getfb (getfb2-handle-protection)
[147.369166] Starting subtest: getfb2-handle-protection
[147.391210] Subtest getfb2-handle-protection: SUCCESS (0.023s)
[147.528589] [012/132] (872s left) gem_exec_params (dr1-dirt)
[147.592200] Starting subtest: DR1-dirt
[147.594509] Subtest DR1-dirt: SUCCESS (0.000s)
[147.703753] [013/132] (871s left) gem_mmap_wc (write-prefaulted)
[147.766028] Subtest write-prefaulted: SKIP (0.000s)
[147.833549] [014/132] (871s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[150.151441] Starting subtest: dpms-mode-unset-lpsp
[154.203887] Subtest dpms-mode-unset-lpsp: SUCCESS (4.100s)
[154.590676] [015/132] (865s left) perf (non-zero-reason)
[154.739847] Starting subtest: non-zero-reason
[154.741857] Starting dynamic subtest: 0-rcs0
[159.969268] Dynamic subtest 0-rcs0: SUCCESS (5.260s)
[159.980682] Starting dynamic subtest: 1-vcs0
[163.832729] Dynamic subtest 1-vcs0: SUCCESS (3.876s)
[163.835881] Subtest non-zero-reason: SUCCESS (9.136s)
[163.872540] [016/132] (855s left) gem_ctx_persistence (userptr)
[163.939539] Starting subtest: userptr
[164.190639] Subtest userptr: SUCCESS (0.252s)
[164.292212] [017/132] (855s left) gem_busy (close-race)
[164.367189] Starting subtest: close-race
[189.937580] Subtest close-race: SUCCESS (25.577s)
[190.391531] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[190.400216] [018/132] (829s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[190.557531] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[194.887325] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.334s)
[196.037753] [019/132] (823s left) perf (disabled-read-error)
[196.169818] Starting subtest: disabled-read-error
[196.182714] Subtest disabled-read-error: SUCCESS (0.013s)
[196.230479] [020/132] (823s left) kms_pm_backlight (fade)
[196.321696] Starting subtest: fade
[196.398778] Starting dynamic subtest: eDP-1
[199.062426] Dynamic subtest eDP-1: SUCCESS (2.662s)
[199.064742] Subtest fade: SUCCESS (2.741s)
[199.962922] [021/132] (820s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[200.065577] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[200.069856] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[200.207627] [022/132] (819s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[200.317194] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[200.319547] Starting dynamic subtest: pipe-A-eDP-1
[200.340367] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[200.350952] Starting dynamic subtest: pipe-B-eDP-1
[200.370257] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[200.378282] Starting dynamic subtest: pipe-C-eDP-1
[200.397110] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[200.404982] Starting dynamic subtest: pipe-D-eDP-1
[200.424281] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[200.426125] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.009s)
[202.608264] [023/132] (817s left) i915_drm_fdinfo (memory-info-shared)
[202.651237] Starting subtest: memory-info-shared
[202.653587] Starting dynamic subtest: smem0
[202.730531] Dynamic subtest smem0: SUCCESS (0.078s)
[202.733409] Subtest memory-info-shared: SUCCESS (0.079s)
[202.828069] [024/132] (817s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[202.927176] Starting subtest: multiplane-rotation-cropping-bottom
[220.224054] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.304s)
[220.376615] [025/132] (799s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[220.486265] Starting subtest: fbc-psr-cursor-mmap-cpu
[220.488103] Starting dynamic subtest: eDP-1
[220.491788] Dynamic subtest eDP-1: SKIP (0.000s)
[220.495370] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[220.586116] [026/132] (799s left) kms_plane_alpha_blend (alpha-opaque-fb)
[220.682266] Starting subtest: alpha-opaque-fb
[220.958581] Starting dynamic subtest: pipe-A-eDP-1
[221.555844] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.597s)
[221.564786] Starting dynamic subtest: pipe-D-eDP-1
[222.912158] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.353s)
[222.914498] Subtest alpha-opaque-fb: SUCCESS (2.230s)
[223.751425] [027/132] (796s left) kms_chamelium_color (ctm-limited-range)
[223.859187] Subtest ctm-limited-range: SKIP (0.000s)
[223.904626] [028/132] (796s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[223.961032] Starting subtest: multi-wait-for-submit-submitted-signaled
[223.963393] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[224.105385] [029/132] (796s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[224.204896] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[231.173018] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.972s)
[231.334726] [030/132] (789s left) syncobj_eventfd (invalid-bad-flags)
[231.388857] Starting subtest: invalid-bad-flags
[231.391039] Subtest invalid-bad-flags: SUCCESS (0.000s)
[231.524983] [031/132] (788s left) kms_pm_rpm (system-suspend-idle)
[233.051654] Starting subtest: system-suspend-idle
[253.515982] Subtest system-suspend-idle: SUCCESS (4.664s)
[253.899822] [032/132] (766s left) kms_dsc (dsc-with-output-formats-with-bpc)
[254.005746] Starting subtest: dsc-with-output-formats-with-bpc
[254.059852] Subtest dsc-with-output-formats-with-bpc: SKIP (0.002s)
[254.098752] [033/132] (766s left) gem_tiled_wc
[254.236337] [034/132] (766s left) gem_mmap_gtt (fault-concurrent-y)
[254.294060] Subtest fault-concurrent-Y: SKIP (0.000s)
[254.357765] [035/132] (766s left) kms_async_flips (invalid-async-flip)
[254.447798] Starting subtest: invalid-async-flip
[254.827231] Starting dynamic subtest: pipe-A-eDP-1
[255.643164] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.837s)
[255.994781] Starting dynamic subtest: pipe-B-eDP-1
[256.757877] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.788s)
[257.124438] Starting dynamic subtest: pipe-C-eDP-1
[257.872640] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.773s)
[258.222372] Starting dynamic subtest: pipe-D-eDP-1
[258.986877] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.785s)
[258.989237] Subtest invalid-async-flip: SUCCESS (4.633s)
[260.154665] [036/132] (760s left) gem_exec_basic (basic)
[260.214490] Starting subtest: basic
[260.216780] Starting dynamic subtest: rcs0-smem
[260.219153] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[260.229827] Starting dynamic subtest: bcs0-smem
[260.231721] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[260.239239] Starting dynamic subtest: vcs0-smem
[260.241147] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[260.248724] Starting dynamic subtest: vcs1-smem
[260.250615] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[260.258045] Starting dynamic subtest: vecs0-smem
[260.259874] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[260.267404] Starting dynamic subtest: ccs0-smem
[260.269330] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[260.271212] Subtest basic: SUCCESS (0.014s)
[261.937552] [037/132] (758s left) kms_psr (fbc-pr-suspend)
[262.037822] Starting subtest: fbc-pr-suspend
[262.042236] Subtest fbc-pr-suspend: SKIP (0.000s)
[262.134140] [038/132] (758s left) gem_sync (basic-store-all)
[262.198960] Starting subtest: basic-store-all
[264.519183] Subtest basic-store-all: SUCCESS (2.349s)
[264.728630] [039/132] (756s left) gem_partial_pwrite_pread (reads-snoop)
[264.801452] Subtest reads-snoop: SKIP (0.000s)
[264.881575] [040/132] (755s left) gem_pxp (verify-pxp-stale-ctx-execution)
[265.012552] Starting subtest: verify-pxp-stale-ctx-execution
[266.072606] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.069s)
[266.243291] [041/132] (754s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[266.346614] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[273.607809] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.292s)
[273.781165] [042/132] (747s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[273.882714] Starting subtest: y-tiled-addfb-size-offset-overflow
[273.887162] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[274.006409] [043/132] (746s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[274.102020] Starting subtest: bad-pixel-format-y-tiled-ccs
[274.104291] Starting dynamic subtest: pipe-A-eDP-1
[274.136995] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[274.147536] Starting dynamic subtest: pipe-B-eDP-1
[274.168083] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[274.177151] Starting dynamic subtest: pipe-C-eDP-1
[274.199428] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[274.209997] Starting dynamic subtest: pipe-D-eDP-1
[274.232338] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[274.236706] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[276.422032] [044/132] (744s left) kms_color_pipeline (plane-lut3d-green-only)
[276.536799] Starting subtest: plane-lut3d-green-only
[276.549580] Starting dynamic subtest: pipe-A-eDP-1
[276.554239] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[277.595414] Starting dynamic subtest: pipe-B-eDP-1
[277.599912] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[278.626894] Starting dynamic subtest: pipe-C-eDP-1
[278.631602] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[279.666513] Starting dynamic subtest: pipe-D-eDP-1
[279.671158] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[279.953462] Subtest plane-lut3d-green-only: SKIP (3.421s)
[280.775701] [045/132] (740s left) perf_pmu (rc6)
[280.815329] Starting subtest: rc6
[280.817229] Starting dynamic subtest: gt0
[287.196805] Dynamic subtest gt0: SUCCESS (6.381s)
[287.207269] Starting dynamic subtest: runtime-pm-gt0
[294.990919] Dynamic subtest runtime-pm-gt0: SUCCESS (7.789s)
[295.002448] Starting dynamic subtest: runtime-pm-long-gt0
[307.426998] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.432s)
[307.439803] Starting dynamic subtest: other-idle-gt0
[313.769324] Dynamic subtest other-idle-gt0: SUCCESS (6.340s)
[313.781895] Starting dynamic subtest: gt1
[320.111337] Dynamic subtest gt1: SUCCESS (6.341s)
[320.121149] Starting dynamic subtest: runtime-pm-gt1
[327.589919] Dynamic subtest runtime-pm-gt1: SUCCESS (7.478s)
[327.601765] Starting dynamic subtest: runtime-pm-long-gt1
[340.026598] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.438s)
[340.038699] Starting dynamic subtest: other-idle-gt1
[346.368757] Dynamic subtest other-idle-gt1: SUCCESS (6.342s)
[346.371954] Subtest rc6: SUCCESS (65.560s)
[347.504250] [046/132] (673s left) gem_exec_fence (syncobj-timeline-export)
[348.079586] Starting subtest: syncobj-timeline-export
[348.082520] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[349.859502] [047/132] (671s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[350.280429] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[351.128521] [048/132] (669s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[351.224283] Starting subtest: fbc-rgb101010-draw-pwrite
[356.593287] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.369s)
[357.729108] [049/132] (663s left) gem_softpin (invalid)
[357.797238] Starting subtest: invalid
[357.802323] Subtest invalid: SUCCESS (0.007s)
[357.929284] [050/132] (663s left) i915_getparams_basic (basic-subslice-total)
[357.981802] Starting subtest: basic-subslice-total
[357.986358] Subtest basic-subslice-total: SUCCESS (0.000s)
[358.051725] [051/132] (663s left) kms_content_protection (dp-mst-lic-type-1)
[358.227141] Starting subtest: dp-mst-lic-type-1
[358.231510] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[358.348678] [052/132] (662s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[358.451076] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[358.453345] Starting dynamic subtest: pipe-A-eDP-1
[359.257477] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s)
[359.277190] Starting dynamic subtest: pipe-B-eDP-1
[360.796215] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.536s)
[360.805316] Starting dynamic subtest: pipe-C-eDP-1
[362.264285] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.467s)
[362.273681] Starting dynamic subtest: pipe-D-eDP-1
[363.789367] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.520s)
[363.791622] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.327s)
[364.943877] [053/132] (656s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[365.044198] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[365.048463] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[365.135802] [054/132] (656s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[365.230163] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[365.232046] Starting dynamic subtest: pipe-A-eDP-1
[365.252298] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[365.262792] Starting dynamic subtest: pipe-B-eDP-1
[365.285025] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.024s)
[365.295601] Starting dynamic subtest: pipe-C-eDP-1
[365.315011] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[365.323629] Starting dynamic subtest: pipe-D-eDP-1
[365.342982] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.025s)
[365.345065] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.098s)
[367.607470] [055/132] (653s left) gem_barrier_race (remote-request)
[367.677530] Starting subtest: remote-request
[367.679516] Starting dynamic subtest: rcs0
[372.800324] Dynamic subtest rcs0: SUCCESS (5.122s)
[372.802769] Subtest remote-request: SUCCESS (5.124s)
[372.939540] [056/132] (648s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[373.045523] Starting subtest: extended-modeset-hang-oldfb-with-reset
[373.047514] Starting dynamic subtest: pipe-A
[384.721911] Dynamic subtest pipe-A: SUCCESS (11.674s)
[384.732538] Starting dynamic subtest: pipe-D
[396.987716] Dynamic subtest pipe-D: SUCCESS (12.262s)
[396.989739] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.941s)
[397.754419] [057/132] (623s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[397.882549] Starting subtest: cursorA-vs-flipB-toggle
[397.887045] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[398.001360] [058/132] (623s left) syncobj_basic (bad-flags-fd-to-handle)
[398.062547] Starting subtest: bad-flags-fd-to-handle
[398.064976] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[398.129415] [059/132] (623s left) gem_media_fill (media-fill)
[398.197345] Subtest media-fill: SKIP (0.000s)
[398.288973] [060/132] (623s left) kms_big_fb (linear-64bpp-rotate-270)
[398.389053] Starting subtest: linear-64bpp-rotate-270
[398.409162] Subtest linear-64bpp-rotate-270: SKIP (0.020s)
[398.526581] [061/132] (623s left) gem_watchdog (default-virtual)
[398.599870] Starting subtest: default-virtual
[404.877733] Subtest default-virtual: SUCCESS (6.287s)
[405.001558] [062/132] (616s left) gem_tiled_blits (normal)
[405.075691] Subtest normal: SKIP (0.000s)
[405.169195] [063/132] (616s left) syncobj_timeline (wait-for-submit-complex)
[405.224917] Starting subtest: wait-for-submit-complex
[405.285804] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[405.342064] [064/132] (616s left) gem_exec_whisper (basic-contexts-priority-all)
[405.403654] Starting subtest: basic-contexts-priority-all
[429.829773] Subtest basic-contexts-priority-all: SUCCESS (24.426s)
[429.985469] [065/132] (591s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[430.372668] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[431.170027] [066/132] (590s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[431.219010] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[431.319521] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[431.371592] [067/132] (590s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[431.468530] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[431.472481] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[431.573070] [068/132] (590s left) gem_ringfill (legacy-basic)
[436.855572] Starting subtest: legacy-basic
[436.858148] Starting dynamic subtest: default
[436.932227] Dynamic subtest default: SUCCESS (0.075s)
[436.944962] Starting dynamic subtest: render
[436.973658] Dynamic subtest render: SUCCESS (0.040s)
[436.983953] Starting dynamic subtest: bsd
[437.015782] Dynamic subtest bsd: SUCCESS (0.040s)
[437.026353] Starting dynamic subtest: bsd1
[437.055936] Dynamic subtest bsd1: SUCCESS (0.038s)
[437.067410] Starting dynamic subtest: bsd2
[437.098047] Dynamic subtest bsd2: SUCCESS (0.040s)
[437.110164] Starting dynamic subtest: blt
[437.144837] Dynamic subtest blt: SUCCESS (0.044s)
[437.157995] Starting dynamic subtest: vebox
[437.191102] Dynamic subtest vebox: SUCCESS (0.042s)
[437.194813] Subtest legacy-basic: SUCCESS (0.338s)
[437.250001] [069/132] (584s left) gem_pread (snoop)
[437.301134] Subtest snoop: SKIP (0.000s)
[437.376888] [070/132] (584s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[437.483685] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[437.487732] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[437.605828] [071/132] (584s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[437.710316] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[437.714208] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[437.766973] [072/132] (584s left) gem_eio (context-create)
[437.991172] Starting subtest: context-create
[438.297891] Subtest context-create: SUCCESS (0.307s)
[438.452193] [073/132] (583s left) gem_close_race (multigpu-basic-threads)
[438.543409] Starting subtest: multigpu-basic-threads
[438.546858] Subtest multigpu-basic-threads: SKIP (0.000s)
[438.771191] [074/132] (583s left) kms_psr (psr2-dpms)
[438.871670] Starting subtest: psr2-dpms
[438.873857] Starting dynamic subtest: eDP-1
[441.719286] Dynamic subtest eDP-1: SUCCESS (2.847s)
[441.721417] Subtest psr2-dpms: SUCCESS (2.849s)
[442.575072] [075/132] (579s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[442.685185] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[442.689684] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[442.778796] [076/132] (579s left) gem_lmem_swapping (heavy-verify-multi)
[442.822492] Subtest heavy-verify-multi: SKIP (0.000s)
[442.858588] [077/132] (579s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[442.951778] Starting subtest: plane-all-modeset-transition-internal-panels
[442.953967] Starting dynamic subtest: pipe-A-eDP-1
[444.245886] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.294s)
[444.533689] Starting dynamic subtest: pipe-B-eDP-1
[445.576965] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.051s)
[445.849302] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.899s)
[446.666026] [078/132] (575s left) gem_spin_batch (resubmit)
[446.736533] Starting subtest: resubmit
[446.740912] Starting dynamic subtest: rcs0
[446.743343] Dynamic subtest rcs0: SUCCESS (0.007s)
[446.754180] Starting dynamic subtest: bcs0
[446.756509] Dynamic subtest bcs0: SUCCESS (0.003s)
[446.766458] Starting dynamic subtest: vcs0
[446.768348] Dynamic subtest vcs0: SUCCESS (0.002s)
[446.777249] Starting dynamic subtest: vcs1
[446.779188] Dynamic subtest vcs1: SUCCESS (0.002s)
[446.788825] Starting dynamic subtest: vecs0
[446.790777] Dynamic subtest vecs0: SUCCESS (0.003s)
[446.799544] Starting dynamic subtest: ccs0
[446.801538] Dynamic subtest ccs0: SUCCESS (0.002s)
[446.803531] Subtest resubmit: SUCCESS (0.024s)
[446.956735] [079/132] (575s left) kms_vblank (query-idle-hang)
[447.055687] Starting subtest: query-idle-hang
[447.057938] Starting dynamic subtest: pipe-A-eDP-1
[474.606700] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.556s)
[474.622420] Starting dynamic subtest: pipe-D-eDP-1
[489.589001] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.973s)
[489.590983] Subtest query-idle-hang: SUCCESS (42.534s)
[490.418248] [080/132] (532s left) perf_pmu (interrupts)
[490.455998] Starting subtest: interrupts
[491.552814] Subtest interrupts: SUCCESS (1.096s)
[491.647120] [081/132] (530s left) gem_exec_reloc (basic-wc-gtt-active)
[491.700562] Subtest basic-wc-gtt-active: SKIP (0.000s)
[491.796283] [082/132] (530s left) gem_exec_reloc (basic-gtt-cpu)
[491.866820] Subtest basic-gtt-cpu: SKIP (0.000s)
[491.960595] [083/132] (530s left) sysfs_timeslice_duration (timeout)
[492.016588] Starting subtest: timeout
[492.019013] Starting dynamic subtest: vecs0
[501.833544] Dynamic subtest vecs0: SUCCESS (9.810s)
[501.846560] Starting dynamic subtest: ccs0
[503.118753] Dynamic subtest ccs0: SUCCESS (1.283s)
[503.131686] Starting dynamic subtest: vcs0
[512.832802] Dynamic subtest vcs0: SUCCESS (9.712s)
[512.844996] Starting dynamic subtest: bcs0
[513.503882] Dynamic subtest bcs0: SUCCESS (0.666s)
[513.519365] Starting dynamic subtest: rcs0
[514.688771] Dynamic subtest rcs0: SUCCESS (1.184s)
[514.700543] Starting dynamic subtest: vcs1
[523.852954] Dynamic subtest vcs1: SUCCESS (9.157s)
[523.855636] Subtest timeout: SUCCESS (31.826s)
[523.907036] [084/132] (498s left) core_auth (getclient-master-drop)
[523.947559] Starting subtest: getclient-master-drop
[523.963391] Subtest getclient-master-drop: SUCCESS (0.020s)
[524.051144] [085/132] (498s left) gem_exec_schedule (preempt-other)
[524.128821] Starting subtest: preempt-other
[524.130838] Starting dynamic subtest: rcs0
[524.246744] Dynamic subtest rcs0: SUCCESS (0.123s)
[524.260538] Starting dynamic subtest: bcs0
[524.360439] Dynamic subtest bcs0: SUCCESS (0.114s)
[524.376377] Starting dynamic subtest: vcs0
[524.475761] Dynamic subtest vcs0: SUCCESS (0.115s)
[524.488559] Starting dynamic subtest: vcs1
[524.593470] Dynamic subtest vcs1: SUCCESS (0.116s)
[524.605857] Starting dynamic subtest: vecs0
[524.703441] Dynamic subtest vecs0: SUCCESS (0.110s)
[524.716341] Starting dynamic subtest: ccs0
[524.819147] Dynamic subtest ccs0: SUCCESS (0.114s)
[524.822065] Subtest preempt-other: SUCCESS (0.696s)
[525.913378] [086/132] (496s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[526.019482] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[526.023911] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[526.122295] [087/132] (496s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[526.224193] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[532.972247] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.749s)
[533.107443] [088/132] (489s left) kms_lease (setcrtc-implicit-plane)
[533.216010] Starting subtest: setcrtc-implicit-plane
[533.217849] Starting dynamic subtest: pipe-A-eDP-1
[533.687696] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.471s)
[533.700887] Starting dynamic subtest: pipe-B-eDP-1
[534.790341] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.101s)
[534.801379] Starting dynamic subtest: pipe-C-eDP-1
[535.919422] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[535.930258] Starting dynamic subtest: pipe-D-eDP-1
[537.048582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[537.050472] Subtest setcrtc-implicit-plane: SUCCESS (3.832s)
[537.873798] [089/132] (484s left) kms_flip (2x-flip-vs-rmfb)
[537.963407] Starting subtest: 2x-flip-vs-rmfb
[537.972371] Subtest 2x-flip-vs-rmfb: SKIP (0.010s)
[538.064048] [090/132] (484s left) kms_vblank (wait-idle-hang)
[538.159222] Starting subtest: wait-idle-hang
[538.161078] Starting dynamic subtest: pipe-A-eDP-1
[564.598389] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.436s)
[564.613889] Starting dynamic subtest: pipe-D-eDP-1
[589.619729] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.022s)
[589.622192] Subtest wait-idle-hang: SUCCESS (51.462s)
[590.435883] [091/132] (432s left) gem_exec_params (cliprects_ptr-dirt)
[590.500210] Starting subtest: cliprects_ptr-dirt
[590.502624] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[590.596229] [092/132] (432s left) kms_cursor_legacy (forked-move)
[590.718074] Starting subtest: forked-move
[590.719838] Starting dynamic subtest: pipe-A
[596.187410] Dynamic subtest pipe-A: SUCCESS (5.467s)
[596.200481] Starting dynamic subtest: pipe-B
[601.659724] Dynamic subtest pipe-B: SUCCESS (5.469s)
[601.671804] Starting dynamic subtest: pipe-C
[607.136127] Dynamic subtest pipe-C: SUCCESS (5.475s)
[607.150665] Starting dynamic subtest: pipe-D
[612.609485] Dynamic subtest pipe-D: SUCCESS (5.471s)
[612.622188] Starting dynamic subtest: all-pipes
[618.125832] Dynamic subtest all-pipes: SUCCESS (5.513s)
[618.128439] Subtest forked-move: SUCCESS (27.407s)
[618.266054] [093/132] (404s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[618.392180] Starting subtest: cursorB-vs-flipA-legacy
[618.396494] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[618.510214] [094/132] (404s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[618.692055] Starting subtest: cursor-rapid-movement-32x10
[618.736066] Subtest cursor-rapid-movement-32x10: SKIP (0.041s)
[619.011554] [095/132] (404s left) kms_invalid_mode (uint-max-clock)
[619.110037] Starting subtest: uint-max-clock
[619.112298] Starting dynamic subtest: pipe-A-eDP-1
[619.415475] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.302s)
[619.426034] Starting dynamic subtest: pipe-B-eDP-1
[619.427903] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[619.437371] Starting dynamic subtest: pipe-C-eDP-1
[619.439589] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[619.448590] Starting dynamic subtest: pipe-D-eDP-1
[619.450824] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[619.453019] Subtest uint-max-clock: SUCCESS (0.309s)
[620.259921] [096/132] (402s left) api_intel_allocator (simple-allocator)
[620.315918] Starting subtest: simple-allocator
[620.318250] Starting dynamic subtest: basic
[620.320502] Dynamic subtest basic: SUCCESS (0.003s)
[620.336699] Starting dynamic subtest: parallel-one
[620.488621] Dynamic subtest parallel-one: SUCCESS (0.169s)
[620.500416] Starting dynamic subtest: print
[620.502570] Dynamic subtest print: SUCCESS (0.000s)
[620.511788] Starting dynamic subtest: reuse
[620.513698] Dynamic subtest reuse: SUCCESS (0.000s)
[620.522496] Starting dynamic subtest: reserve
[620.524598] Dynamic subtest reserve: SUCCESS (0.000s)
[620.533296] Starting dynamic subtest: fork-reopen-allocator
[620.535205] Dynamic subtest fork-reopen-allocator: SUCCESS (0.014s)
[620.537087] Subtest simple-allocator: SUCCESS (0.192s)
[620.618120] [097/132] (402s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[620.727872] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[620.732768] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[620.831407] [098/132] (402s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[620.890013] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[620.891965] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[620.976549] [099/132] (402s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[621.077570] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[621.081963] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[621.171867] [100/132] (402s left) kms_plane_scaling (planes-scaler-unity-scaling)
[621.252974] Starting subtest: planes-scaler-unity-scaling
[621.255262] Starting dynamic subtest: pipe-A
[621.315632] Dynamic subtest pipe-A: SUCCESS (0.064s)
[621.327955] Starting dynamic subtest: pipe-B
[622.500217] Dynamic subtest pipe-B: SUCCESS (1.183s)
[622.512012] Starting dynamic subtest: pipe-C
[623.614430] Dynamic subtest pipe-C: SUCCESS (1.113s)
[623.627395] Starting dynamic subtest: pipe-D
[624.758641] Dynamic subtest pipe-D: SUCCESS (1.141s)
[624.760973] Subtest planes-scaler-unity-scaling: SUCCESS (3.509s)
[625.991680] [101/132] (397s left) kms_psr (psr-primary-mmap-gtt)
[626.097337] Starting subtest: psr-primary-mmap-gtt
[626.099702] Starting dynamic subtest: eDP-1
[627.508404] Dynamic subtest eDP-1: SKIP (1.409s)
[627.513416] Subtest psr-primary-mmap-gtt: SKIP (1.411s)
[627.677460] [102/132] (395s left) kms_flip (flip-vs-suspend-interruptible)
[627.788266] Starting subtest: flip-vs-suspend-interruptible
[627.790549] Starting dynamic subtest: A-eDP1
[677.032167] Dynamic subtest A-eDP1: SUCCESS (4.587s)
[677.042924] Starting dynamic subtest: B-eDP1
[726.035852] Dynamic subtest B-eDP1: SUCCESS (4.554s)
[726.046600] Starting dynamic subtest: C-eDP1
[775.007137] Dynamic subtest C-eDP1: SUCCESS (4.544s)
[775.023657] Starting dynamic subtest: D-eDP1
[824.038804] Dynamic subtest D-eDP1: SUCCESS (4.737s)
[824.040958] Subtest flip-vs-suspend-interruptible: SUCCESS (18.431s)
[825.182848] [103/132] (198s left) kms_flip (flip-vs-blocking-wf-vblank)
[825.285694] Starting subtest: flip-vs-blocking-wf-vblank
[825.288054] Starting dynamic subtest: A-eDP1
[834.190139] Dynamic subtest A-eDP1: SUCCESS (8.964s)
[834.207275] Starting dynamic subtest: B-eDP1
[843.106388] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[843.126834] Starting dynamic subtest: C-eDP1
[852.025405] Dynamic subtest C-eDP1: SUCCESS (8.914s)
[852.044208] Starting dynamic subtest: D-eDP1
[872.874310] Dynamic subtest D-eDP1: SUCCESS (20.973s)
[872.876559] Subtest flip-vs-blocking-wf-vblank: SUCCESS (47.798s)
[874.074826] [104/132] (149s left) i915_suspend (fence-restore-untiled)
[874.133647] Starting subtest: fence-restore-untiled
[874.137973] Subtest fence-restore-untiled: SKIP (0.000s)
[874.217719] [105/132] (149s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[874.276070] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[874.381187] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[874.437760] [106/132] (149s left) gem_exec_balancer (bonded-dual)
[874.500272] Starting subtest: bonded-dual
[874.504845] Subtest bonded-dual: SKIP (0.000s)
[874.793561] [107/132] (148s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[879.774027] Starting subtest: pr-plane-move-sf-dmg-area
[879.778472] Subtest pr-plane-move-sf-dmg-area: SKIP (0.002s)
[880.614151] [108/132] (142s left) gem_exec_params (mmapped)
[880.674380] Starting subtest: mmapped
[880.676633] Subtest mmapped: SUCCESS (0.002s)
[880.787413] [109/132] (142s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[880.849187] Starting subtest: invalid-multi-wait-unsubmitted
[880.851338] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[880.921556] [110/132] (142s left) kms_flip (dpms-off-confusion)
[881.007862] Starting subtest: dpms-off-confusion
[881.010160] Starting dynamic subtest: A-eDP1
[889.666974] Dynamic subtest A-eDP1: SUCCESS (8.664s)
[889.677408] Starting dynamic subtest: B-eDP1
[898.309113] Dynamic subtest B-eDP1: SUCCESS (8.637s)
[898.329693] Starting dynamic subtest: C-eDP1
[906.924895] Dynamic subtest C-eDP1: SUCCESS (8.613s)
[906.934457] Starting dynamic subtest: D-eDP1
[915.567405] Dynamic subtest D-eDP1: SUCCESS (8.642s)
[915.572655] Subtest dpms-off-confusion: SUCCESS (34.569s)
[916.710374] [111/132] (106s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[916.813808] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[916.817748] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[916.927673] [112/132] (106s left) kms_addfb_basic (legacy-format)
[916.988492] Starting subtest: legacy-format
[917.020388] Subtest legacy-format: SUCCESS (0.028s)
[917.153753] [113/132] (106s left) syncobj_wait (multi-wait-for-submit-signaled)
[917.211163] Starting subtest: multi-wait-for-submit-signaled
[917.213284] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[917.301456] [114/132] (106s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[917.395692] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[918.208831] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.815s)
[918.338942] [115/132] (105s left) kms_cursor_crc (cursor-onscreen-512x170)
[918.635870] Starting subtest: cursor-onscreen-512x170
[918.640287] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[918.771815] [116/132] (105s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[923.689248] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[923.691638] Starting dynamic subtest: pipe-A-eDP-1
[924.497249] Dynamic subtest pipe-A-eDP-1: SKIP (0.806s)
[924.507456] Starting dynamic subtest: pipe-B-eDP-1
[925.633312] Dynamic subtest pipe-B-eDP-1: SKIP (1.133s)
[925.637869] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.943s)
[926.804851] [117/132] ( 97s left) syncobj_timeline (invalid-signal-bad-pad)
[926.866181] Starting subtest: invalid-signal-bad-pad
[926.868118] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[926.939867] [118/132] ( 97s left) kms_cursor_legacy (single-move)
[927.058882] Starting subtest: single-move
[927.061002] Starting dynamic subtest: pipe-A
[932.438118] Dynamic subtest pipe-A: SUCCESS (5.378s)
[932.451362] Starting dynamic subtest: pipe-B
[937.818994] Dynamic subtest pipe-B: SUCCESS (5.379s)
[937.831325] Starting dynamic subtest: pipe-C
[943.199363] Dynamic subtest pipe-C: SUCCESS (5.379s)
[943.211624] Starting dynamic subtest: pipe-D
[948.579406] Dynamic subtest pipe-D: SUCCESS (5.380s)
[948.591798] Starting dynamic subtest: all-pipes
[953.969037] Dynamic subtest all-pipes: SUCCESS (5.387s)
[953.971768] Subtest single-move: SUCCESS (26.915s)
[954.091701] [119/132] ( 69s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[954.198859] Starting subtest: flip-vs-dpms-off-vs-modeset
[954.201473] Starting dynamic subtest: A-eDP1
[957.849358] Dynamic subtest A-eDP1: SUCCESS (3.647s)
[957.860385] Starting dynamic subtest: B-eDP1
[961.043105] Dynamic subtest B-eDP1: SUCCESS (3.190s)
[961.053888] Starting dynamic subtest: C-eDP1
[964.144301] Dynamic subtest C-eDP1: SUCCESS (3.102s)
[964.155111] Starting dynamic subtest: D-eDP1
[967.255269] Dynamic subtest D-eDP1: SUCCESS (3.109s)
[967.257738] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.054s)
[968.038968] [120/132] ( 56s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[968.145213] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[974.927378] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.787s)
[975.042832] [121/132] ( 49s left) gem_exec_schedule (u-semaphore-resolve)
[975.120442] Starting subtest: u-semaphore-resolve
[975.151430] Subtest u-semaphore-resolve: SUCCESS (0.037s)
[975.481800] [122/132] ( 48s left) gem_exec_reloc (basic-wc-cpu)
[975.556791] Subtest basic-wc-cpu: SKIP (0.000s)
[975.652833] [123/132] ( 48s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[975.710854] Starting subtest: multi-wait-all-for-submit-available-signaled
[975.713173] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[975.780111] [124/132] ( 48s left) kms_big_fb (x-tiled-addfb)
[975.896231] Starting subtest: x-tiled-addfb
[975.898544] Subtest x-tiled-addfb: SUCCESS (0.000s)
[975.989239] [125/132] ( 48s left) kms_flip_tiling (flip-change-tiling)
[976.091386] Starting subtest: flip-change-tiling
[976.093742] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-mc-ccs
[976.252947] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (0.162s)
[976.265775] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs
[976.387338] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.131s)
[976.399465] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs-cc
[976.520789] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.131s)
[976.533850] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-4
[976.652741] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-4: SUCCESS (0.131s)
[976.665127] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-x
[976.796731] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-x: SUCCESS (0.142s)
[976.808919] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs-to-linear
[976.941261] Dynamic subtest pipe-A-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.142s)
[976.954166] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-mc-ccs
[977.075165] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.131s)
[977.088360] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs
[977.186182] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.109s)
[977.199245] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs-cc
[977.320975] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.131s)
[977.334159] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-4
[977.464424] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-4: SUCCESS (0.142s)
[977.477336] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-x
[977.608303] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-x: SUCCESS (0.142s)
[977.620436] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-to-linear
[977.763565] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.153s)
[977.775935] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-mc-ccs
[977.896990] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.131s)
[977.910423] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs
[978.031765] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.131s)
[978.043494] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc
[978.141659] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.109s)
[978.152366] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-4
[978.275403] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.131s)
[978.286940] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-x
[978.408131] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.131s)
[978.419351] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc-to-linear
[978.552345] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.142s)
[978.563702] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-mc-ccs
[978.686837] Dynamic subtest pipe-A-eDP-1-4-to-4-mc-ccs: SUCCESS (0.131s)
[978.699962] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-rc-ccs
[978.819516] Dynamic subtest pipe-A-eDP-1-4-to-4-rc-ccs: SUCCESS (0.131s)
[978.835656] Starting dynamic subtest: pipe-A-eDP-1-4-to-4-rc-ccs-cc
[978.953455] Dynamic subtest pipe-A-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.131s)
[978.966937] Starting dynamic subtest: pipe-A-eDP-1-4-to-4
[979.075032] Dynamic subtest pipe-A-eDP-1-4-to-4: SUCCESS (0.120s)
[979.087201] Starting dynamic subtest: pipe-A-eDP-1-4-to-x
[979.219297] Dynamic subtest pipe-A-eDP-1-4-to-x: SUCCESS (0.142s)
[979.231557] Starting dynamic subtest: pipe-A-eDP-1-4-to-linear
[979.363496] Dynamic subtest pipe-A-eDP-1-4-to-linear: SUCCESS (0.142s)
[979.375700] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-mc-ccs
[979.507744] Dynamic subtest pipe-A-eDP-1-x-to-4-mc-ccs: SUCCESS (0.142s)
[979.519938] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-rc-ccs
[979.640810] Dynamic subtest pipe-A-eDP-1-x-to-4-rc-ccs: SUCCESS (0.131s)
[979.654804] Starting dynamic subtest: pipe-A-eDP-1-x-to-4-rc-ccs-cc
[979.774171] Dynamic subtest pipe-A-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.131s)
[979.787971] Starting dynamic subtest: pipe-A-eDP-1-x-to-4
[979.918868] Dynamic subtest pipe-A-eDP-1-x-to-4: SUCCESS (0.142s)
[979.932725] Starting dynamic subtest: pipe-A-eDP-1-x-to-x
[980.041547] Dynamic subtest pipe-A-eDP-1-x-to-x: SUCCESS (0.120s)
[980.054044] Starting dynamic subtest: pipe-A-eDP-1-x-to-linear
[980.185999] Dynamic subtest pipe-A-eDP-1-x-to-linear: SUCCESS (0.142s)
[980.198765] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-mc-ccs
[980.329800] Dynamic subtest pipe-A-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.142s)
[980.348423] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-rc-ccs
[980.474360] Dynamic subtest pipe-A-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.142s)
[980.488004] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4-rc-ccs-cc
[980.619421] Dynamic subtest pipe-A-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.142s)
[980.633513] Starting dynamic subtest: pipe-A-eDP-1-linear-to-4
[980.763615] Dynamic subtest pipe-A-eDP-1-linear-to-4: SUCCESS (0.142s)
[980.778655] Starting dynamic subtest: pipe-A-eDP-1-linear-to-x
[980.908427] Dynamic subtest pipe-A-eDP-1-linear-to-x: SUCCESS (0.142s)
[980.920710] Starting dynamic subtest: pipe-A-eDP-1-linear-to-linear
[981.030359] Dynamic subtest pipe-A-eDP-1-linear-to-linear: SUCCESS (0.120s)
[981.084022] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-mc-ccs
[982.281212] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.204s)
[982.293427] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs
[982.426650] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.142s)
[982.438312] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs-cc
[982.560070] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.131s)
[982.571541] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-4
[982.703782] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-4: SUCCESS (0.142s)
[982.716671] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-x
[982.846912] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-x: SUCCESS (0.142s)
[982.863767] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs-to-linear
[982.991729] Dynamic subtest pipe-B-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.142s)
[983.008111] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-mc-ccs
[983.125559] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.131s)
[983.141037] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs
[983.236412] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.108s)
[983.251025] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs-cc
[983.371706] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.131s)
[983.384096] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-4
[983.514970] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-4: SUCCESS (0.142s)
[983.537553] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-x
[983.658489] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-x: SUCCESS (0.141s)
[983.671633] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-to-linear
[983.791775] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.131s)
[983.804252] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-mc-ccs
[983.925142] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.131s)
[983.937774] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs
[984.059584] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.131s)
[984.072379] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc
[984.169806] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.109s)
[984.182653] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-4
[984.303653] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.131s)
[984.316906] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-x
[984.447356] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.142s)
[984.460217] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc-to-linear
[984.580836] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.131s)
[984.593992] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-mc-ccs
[984.714513] Dynamic subtest pipe-B-eDP-1-4-to-4-mc-ccs: SUCCESS (0.131s)
[984.727901] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-rc-ccs
[984.847621] Dynamic subtest pipe-B-eDP-1-4-to-4-rc-ccs: SUCCESS (0.131s)
[984.861294] Starting dynamic subtest: pipe-B-eDP-1-4-to-4-rc-ccs-cc
[984.981348] Dynamic subtest pipe-B-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.131s)
[984.994401] Starting dynamic subtest: pipe-B-eDP-1-4-to-4
[985.114298] Dynamic subtest pipe-B-eDP-1-4-to-4: SUCCESS (0.131s)
[985.126910] Starting dynamic subtest: pipe-B-eDP-1-4-to-x
[985.257823] Dynamic subtest pipe-B-eDP-1-4-to-x: SUCCESS (0.142s)
[985.272531] Starting dynamic subtest: pipe-B-eDP-1-4-to-linear
[985.402546] Dynamic subtest pipe-B-eDP-1-4-to-linear: SUCCESS (0.142s)
[985.414665] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-mc-ccs
[985.535930] Dynamic subtest pipe-B-eDP-1-x-to-4-mc-ccs: SUCCESS (0.131s)
[985.547621] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-rc-ccs
[985.680001] Dynamic subtest pipe-B-eDP-1-x-to-4-rc-ccs: SUCCESS (0.142s)
[985.694114] Starting dynamic subtest: pipe-B-eDP-1-x-to-4-rc-ccs-cc
[985.813425] Dynamic subtest pipe-B-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.131s)
[985.827960] Starting dynamic subtest: pipe-B-eDP-1-x-to-4
[985.969117] Dynamic subtest pipe-B-eDP-1-x-to-4: SUCCESS (0.153s)
[985.984231] Starting dynamic subtest: pipe-B-eDP-1-x-to-x
[986.091612] Dynamic subtest pipe-B-eDP-1-x-to-x: SUCCESS (0.119s)
[986.104672] Starting dynamic subtest: pipe-B-eDP-1-x-to-linear
[986.236136] Dynamic subtest pipe-B-eDP-1-x-to-linear: SUCCESS (0.142s)
[986.248622] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-mc-ccs
[986.380437] Dynamic subtest pipe-B-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.142s)
[986.395767] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-rc-ccs
[986.524798] Dynamic subtest pipe-B-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.141s)
[986.539502] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4-rc-ccs-cc
[986.669189] Dynamic subtest pipe-B-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.142s)
[986.680979] Starting dynamic subtest: pipe-B-eDP-1-linear-to-4
[986.813964] Dynamic subtest pipe-B-eDP-1-linear-to-4: SUCCESS (0.143s)
[986.828507] Starting dynamic subtest: pipe-B-eDP-1-linear-to-x
[986.958797] Dynamic subtest pipe-B-eDP-1-linear-to-x: SUCCESS (0.142s)
[986.973548] Starting dynamic subtest: pipe-B-eDP-1-linear-to-linear
[987.091808] Dynamic subtest pipe-B-eDP-1-linear-to-linear: SUCCESS (0.130s)
[987.144521] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-mc-ccs
[988.410722] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.273s)
[988.424387] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs
[988.510680] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.098s)
[988.521481] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs-cc
[988.610934] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.098s)
[988.624567] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-4
[988.710148] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-4: SUCCESS (0.098s)
[988.722637] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-x
[988.820795] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-x: SUCCESS (0.109s)
[988.833349] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs-to-linear
[988.920809] Dynamic subtest pipe-C-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.098s)
[988.933260] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-mc-ccs
[989.021508] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.099s)
[989.033774] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs
[989.121557] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.098s)
[989.132485] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs-cc
[989.221825] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.098s)
[989.233651] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-4
[989.320871] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-4: SUCCESS (0.098s)
[989.330816] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-x
[989.431863] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-x: SUCCESS (0.109s)
[989.441540] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-to-linear
[989.542999] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.109s)
[989.555977] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-mc-ccs
[989.643491] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.098s)
[989.657107] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs
[989.743521] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.097s)
[989.754090] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc
[989.843466] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.099s)
[989.854159] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-4
[989.965666] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.121s)
[989.976307] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-x
[990.065085] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.098s)
[990.076548] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc-to-linear
[990.164936] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.099s)
[990.177089] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-mc-ccs
[990.265397] Dynamic subtest pipe-C-eDP-1-4-to-4-mc-ccs: SUCCESS (0.099s)
[990.276804] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-rc-ccs
[990.365488] Dynamic subtest pipe-C-eDP-1-4-to-4-rc-ccs: SUCCESS (0.099s)
[990.378066] Starting dynamic subtest: pipe-C-eDP-1-4-to-4-rc-ccs-cc
[990.465512] Dynamic subtest pipe-C-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.099s)
[990.477835] Starting dynamic subtest: pipe-C-eDP-1-4-to-4
[990.616661] Dynamic subtest pipe-C-eDP-1-4-to-4: SUCCESS (0.148s)
[990.631895] Starting dynamic subtest: pipe-C-eDP-1-4-to-x
[990.726344] Dynamic subtest pipe-C-eDP-1-4-to-x: SUCCESS (0.109s)
[990.737616] Starting dynamic subtest: pipe-C-eDP-1-4-to-linear
[990.839170] Dynamic subtest pipe-C-eDP-1-4-to-linear: SUCCESS (0.109s)
[990.850185] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-mc-ccs
[990.937630] Dynamic subtest pipe-C-eDP-1-x-to-4-mc-ccs: SUCCESS (0.098s)
[990.950142] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-rc-ccs
[991.037140] Dynamic subtest pipe-C-eDP-1-x-to-4-rc-ccs: SUCCESS (0.099s)
[991.049305] Starting dynamic subtest: pipe-C-eDP-1-x-to-4-rc-ccs-cc
[991.137210] Dynamic subtest pipe-C-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.099s)
[991.150166] Starting dynamic subtest: pipe-C-eDP-1-x-to-4
[991.248537] Dynamic subtest pipe-C-eDP-1-x-to-4: SUCCESS (0.110s)
[991.261330] Starting dynamic subtest: pipe-C-eDP-1-x-to-x
[991.360261] Dynamic subtest pipe-C-eDP-1-x-to-x: SUCCESS (0.110s)
[991.373522] Starting dynamic subtest: pipe-C-eDP-1-x-to-linear
[991.471441] Dynamic subtest pipe-C-eDP-1-x-to-linear: SUCCESS (0.109s)
[991.484068] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-mc-ccs
[991.581958] Dynamic subtest pipe-C-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.109s)
[991.594437] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-rc-ccs
[991.692683] Dynamic subtest pipe-C-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.109s)
[991.704728] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4-rc-ccs-cc
[991.803863] Dynamic subtest pipe-C-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.109s)
[991.814685] Starting dynamic subtest: pipe-C-eDP-1-linear-to-4
[991.914801] Dynamic subtest pipe-C-eDP-1-linear-to-4: SUCCESS (0.109s)
[991.925527] Starting dynamic subtest: pipe-C-eDP-1-linear-to-x
[992.026357] Dynamic subtest pipe-C-eDP-1-linear-to-x: SUCCESS (0.109s)
[992.038401] Starting dynamic subtest: pipe-C-eDP-1-linear-to-linear
[992.137340] Dynamic subtest pipe-C-eDP-1-linear-to-linear: SUCCESS (0.109s)
[992.178614] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-mc-ccs
[993.445957] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-mc-ccs: SUCCESS (1.274s)
[993.458648] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs
[993.546107] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs: SUCCESS (0.097s)
[993.558543] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs-cc
[993.646412] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4-rc-ccs-cc: SUCCESS (0.098s)
[993.656993] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-4
[993.746234] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-4: SUCCESS (0.098s)
[993.759244] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-x
[993.879119] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-x: SUCCESS (0.132s)
[993.895310] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs-to-linear
[993.990103] Dynamic subtest pipe-D-eDP-1-4-mc-ccs-to-linear: SUCCESS (0.110s)
[994.003892] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-mc-ccs
[994.090439] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-mc-ccs: SUCCESS (0.098s)
[994.103639] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs
[994.190709] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs: SUCCESS (0.098s)
[994.203348] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs-cc
[994.290663] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4-rc-ccs-cc: SUCCESS (0.098s)
[994.302548] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-4
[994.390086] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-4: SUCCESS (0.098s)
[994.401889] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-x
[994.489448] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-x: SUCCESS (0.098s)
[994.501092] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-to-linear
[994.589765] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-to-linear: SUCCESS (0.099s)
[994.601276] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-mc-ccs
[994.690073] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-mc-ccs: SUCCESS (0.098s)
[994.701845] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs
[994.790339] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs: SUCCESS (0.098s)
[994.801782] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc
[994.890147] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4-rc-ccs-cc: SUCCESS (0.098s)
[994.902660] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-4
[994.990149] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-4: SUCCESS (0.098s)
[995.001280] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-x
[995.089685] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-x: SUCCESS (0.098s)
[995.099623] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc-to-linear
[995.189501] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc-to-linear: SUCCESS (0.099s)
[995.199645] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-mc-ccs
[995.290053] Dynamic subtest pipe-D-eDP-1-4-to-4-mc-ccs: SUCCESS (0.099s)
[995.301113] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-rc-ccs
[995.401442] Dynamic subtest pipe-D-eDP-1-4-to-4-rc-ccs: SUCCESS (0.109s)
[995.413909] Starting dynamic subtest: pipe-D-eDP-1-4-to-4-rc-ccs-cc
[995.512456] Dynamic subtest pipe-D-eDP-1-4-to-4-rc-ccs-cc: SUCCESS (0.109s)
[995.525676] Starting dynamic subtest: pipe-D-eDP-1-4-to-4
[995.634348] Dynamic subtest pipe-D-eDP-1-4-to-4: SUCCESS (0.120s)
[995.647890] Starting dynamic subtest: pipe-D-eDP-1-4-to-x
[995.745151] Dynamic subtest pipe-D-eDP-1-4-to-x: SUCCESS (0.108s)
[995.756844] Starting dynamic subtest: pipe-D-eDP-1-4-to-linear
[995.856444] Dynamic subtest pipe-D-eDP-1-4-to-linear: SUCCESS (0.110s)
[995.868713] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-mc-ccs
[995.967462] Dynamic subtest pipe-D-eDP-1-x-to-4-mc-ccs: SUCCESS (0.109s)
[995.981387] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-rc-ccs
[996.130718] Dynamic subtest pipe-D-eDP-1-x-to-4-rc-ccs: SUCCESS (0.160s)
[996.143637] Starting dynamic subtest: pipe-D-eDP-1-x-to-4-rc-ccs-cc
[996.241599] Dynamic subtest pipe-D-eDP-1-x-to-4-rc-ccs-cc: SUCCESS (0.107s)
[996.253014] Starting dynamic subtest: pipe-D-eDP-1-x-to-4
[996.352605] Dynamic subtest pipe-D-eDP-1-x-to-4: SUCCESS (0.110s)
[996.363447] Starting dynamic subtest: pipe-D-eDP-1-x-to-x
[996.464539] Dynamic subtest pipe-D-eDP-1-x-to-x: SUCCESS (0.110s)
[996.476284] Starting dynamic subtest: pipe-D-eDP-1-x-to-linear
[996.575382] Dynamic subtest pipe-D-eDP-1-x-to-linear: SUCCESS (0.109s)
[996.587969] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-mc-ccs
[996.674580] Dynamic subtest pipe-D-eDP-1-linear-to-4-mc-ccs: SUCCESS (0.098s)
[996.684771] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-rc-ccs
[996.775478] Dynamic subtest pipe-D-eDP-1-linear-to-4-rc-ccs: SUCCESS (0.099s)
[996.785735] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4-rc-ccs-cc
[996.885751] Dynamic subtest pipe-D-eDP-1-linear-to-4-rc-ccs-cc: SUCCESS (0.109s)
[996.896267] Starting dynamic subtest: pipe-D-eDP-1-linear-to-4
[996.996831] Dynamic subtest pipe-D-eDP-1-linear-to-4: SUCCESS (0.109s)
[997.010101] Starting dynamic subtest: pipe-D-eDP-1-linear-to-x
[997.108240] Dynamic subtest pipe-D-eDP-1-linear-to-x: SUCCESS (0.109s)
[997.120772] Starting dynamic subtest: pipe-D-eDP-1-linear-to-linear
[997.219615] Dynamic subtest pipe-D-eDP-1-linear-to-linear: SUCCESS (0.109s)
[997.252508] Subtest flip-change-tiling: SUCCESS (21.163s)
[998.418850] [126/132] ( 25s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[998.521331] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[1003.135537] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.618s)
[1004.231986] [127/132] ( 20s left) kms_panel_fitting (legacy)
[1004.324513] Starting subtest: legacy
[1004.326776] Starting dynamic subtest: pipe-A-eDP-1
[1004.850242] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.527s)
[1004.880819] Starting dynamic subtest: pipe-B-eDP-1
[1006.408650] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.538s)
[1006.436570] Starting dynamic subtest: pipe-C-eDP-1
[1007.893598] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.465s)
[1007.927572] Starting dynamic subtest: pipe-D-eDP-1
[1009.437320] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.524s)
[1009.439504] Subtest legacy: SUCCESS (5.115s)
[1010.661079] [128/132] ( 13s left) kms_big_fb (4-tiled-addfb-size-overflow)
[1010.748477] Starting subtest: 4-tiled-addfb-size-overflow
[1010.751027] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[1010.837908] [129/132] ( 13s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[1010.942889] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[1015.405776] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.466s)
[1016.550874] [130/132] (  7s left) gem_flink_basic (basic)
[1016.605476] Starting subtest: basic
[1016.607372] Subtest basic: SUCCESS (0.000s)
[1016.692634] [131/132] (  7s left) gen7_exec_parse (chained-batch)
[1016.756014] Subtest chained-batch: SKIP (0.000s)
[1016.844444] [132/132] (  7s left) kms_addfb_basic (bad-pitch-32)
[1016.910022] Starting subtest: bad-pitch-32
[1016.912221] Subtest bad-pitch-32: SUCCESS (0.000s)
[1017.040996] Closing watchdogs
results: parsing output: 0/ for test: gem_ctx_freq
results: parsing output: 1/ for test: prime_self_import
results: parsing output: 2/ for test: core_setmaster
results: parsing output: 3/ for test: syncobj_wait
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: i915_pm_rpm
results: parsing output: 8/ for test: perf_pmu
results: parsing output: 9/ for test: kms_plane_multiple
results: parsing output: 10/ for test: kms_getfb
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: gem_mmap_wc
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: perf
results: parsing output: 15/ for test: gem_ctx_persistence
results: parsing output: 16/ for test: gem_busy
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: kms_pm_backlight
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: i915_drm_fdinfo
results: parsing output: 23/ for test: kms_rotation_crc
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_plane_alpha_blend
results: parsing output: 26/ for test: kms_chamelium_color
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: syncobj_eventfd
results: parsing output: 30/ for test: kms_pm_rpm
results: parsing output: 31/ for test: kms_dsc
results: parsing output: 32/ for test: gem_tiled_wc
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_async_flips
results: parsing output: 35/ for test: gem_exec_basic
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: gem_sync
results: parsing output: 38/ for test: gem_partial_pwrite_pread
results: parsing output: 39/ for test: gem_pxp
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_color_pipeline
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: gem_exec_fence
results: parsing output: 46/ for test: kms_chamelium_hpd
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_softpin
results: parsing output: 49/ for test: i915_getparams_basic
results: parsing output: 50/ for test: kms_content_protection
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: gem_barrier_race
results: parsing output: 55/ for test: kms_busy
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: syncobj_basic
results: parsing output: 58/ for test: gem_media_fill
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: gem_watchdog
results: parsing output: 61/ for test: gem_tiled_blits
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: gem_exec_whisper
results: parsing output: 64/ for test: kms_chamelium_edid
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_ringfill
results: parsing output: 68/ for test: gem_pread
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: gem_eio
results: parsing output: 72/ for test: gem_close_race
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_lmem_swapping
results: parsing output: 76/ for test: kms_atomic_transition
results: parsing output: 77/ for test: gem_spin_batch
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: perf_pmu
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: sysfs_timeslice_duration
results: parsing output: 83/ for test: core_auth
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_lease
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: kms_vblank
results: parsing output: 90/ for test: gem_exec_params
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: kms_cursor_crc
results: parsing output: 94/ for test: kms_invalid_mode
results: parsing output: 95/ for test: api_intel_allocator
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_plane_scaling
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: kms_flip
results: parsing output: 103/ for test: i915_suspend
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_exec_balancer
results: parsing output: 106/ for test: kms_psr2_sf
results: parsing output: 107/ for test: gem_exec_params
results: parsing output: 108/ for test: syncobj_wait
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_addfb_basic
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_cursor_crc
results: parsing output: 115/ for test: kms_psr2_sf
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_exec_schedule
results: parsing output: 121/ for test: gem_exec_reloc
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_flip_tiling
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_panel_fitting
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_flink_basic
results: parsing output: 130/ for test: gen7_exec_parse
results: parsing output: 131/ for test: kms_addfb_basic
Done.