[57.435270] Initializing watchdogs
[57.435316]   /dev/watchdog0
[57.449458] [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.453875] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.456121] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.459529] [001/132] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[57.576739] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[57.581296] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[57.680032] [002/132] (960s left) kms_cursor_crc (cursor-onscreen-64x21)
[57.872300] Starting subtest: cursor-onscreen-64x21
[57.927661] Subtest cursor-onscreen-64x21: SKIP (0.049s)
[58.197091] [003/132] (959s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[63.239184] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[63.241627] Starting dynamic subtest: pipe-A-eDP-1
[64.035386] Dynamic subtest pipe-A-eDP-1: SKIP (0.794s)
[64.043307] Starting dynamic subtest: pipe-B-eDP-1
[65.157511] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[65.161910] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.920s)
[66.378293] [004/132] (951s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[66.497370] Starting subtest: primary-y-tiled-reflect-x-90
[66.501894] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[66.590152] [005/132] (951s left) kms_vrr (negative-basic)
[66.688789] Starting subtest: negative-basic
[66.695178] Starting dynamic subtest: pipe-A-eDP-1
[72.110711] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.415s)
[72.408784] Subtest negative-basic: SUCCESS (5.720s)
[73.296631] [006/132] (944s left) gem_exec_whisper (basic-fds-priority)
[73.370152] Starting subtest: basic-fds-priority
[96.938671] Subtest basic-fds-priority: SUCCESS (23.567s)
[97.144503] [007/132] (921s left) kms_chamelium_edid (dp-edid-resolution-list)
[97.543656] Subtest dp-edid-resolution-list: SKIP (0.000s)
[98.365394] [008/132] (919s left) kms_frontbuffer_tracking (fbc-suspend)
[98.472540] Starting subtest: fbc-suspend
[136.774948] Subtest fbc-suspend: SUCCESS (11.321s)
[137.980826] [009/132] (880s left) syncobj_timeline (multi-wait-submitted-signaled)
[138.042834] Starting subtest: multi-wait-submitted-signaled
[138.045925] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[138.175512] [010/132] (880s left) syncobj_wait (multi-wait-all-submitted)
[138.224404] Starting subtest: multi-wait-all-submitted
[138.324698] Subtest multi-wait-all-submitted: SUCCESS (0.102s)
[138.430681] [011/132] (879s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[138.531961] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[138.537707] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[138.660134] [012/132] (879s left) gem_partial_pwrite_pread (write-uncached)
[138.731058] Subtest write-uncached: SKIP (0.000s)
[138.822136] [013/132] (879s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[138.905411] Starting subtest: fbc-pr-sprite-mmap-gtt
[138.909775] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[138.998198] [014/132] (879s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[139.091380] Starting subtest: x-tiled-32bpp-rotate-270
[139.110928] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[139.267461] [015/132] (879s left) gem_exec_fence (basic-wait)
[139.442634] Starting subtest: basic-wait
[139.445670] Starting dynamic subtest: rcs0
[139.523280] Dynamic subtest rcs0: SUCCESS (0.081s)
[139.534015] Starting dynamic subtest: bcs0
[139.575483] Dynamic subtest bcs0: SUCCESS (0.052s)
[139.587313] Starting dynamic subtest: vcs0
[139.635347] Dynamic subtest vcs0: SUCCESS (0.058s)
[139.645895] Starting dynamic subtest: vcs1
[139.691727] Dynamic subtest vcs1: SUCCESS (0.056s)
[139.702032] Starting dynamic subtest: vecs0
[139.745938] Dynamic subtest vecs0: SUCCESS (0.053s)
[139.756052] Starting dynamic subtest: ccs0
[139.801414] Dynamic subtest ccs0: SUCCESS (0.055s)
[139.804276] Subtest basic-wait: SUCCESS (0.361s)
[141.986961] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[141.990295] [016/132] (876s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[142.091383] Starting subtest: plane-lut1d-post-ctm3x4
[142.111785] Starting dynamic subtest: pipe-A-eDP-1
[142.923698] Dynamic subtest pipe-A-eDP-1: FAIL (0.356s)
[143.502647] Starting dynamic subtest: pipe-B-eDP-1
[144.435807] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[144.930508] Starting dynamic subtest: pipe-C-eDP-1
[145.879898] Dynamic subtest pipe-C-eDP-1: FAIL (0.290s)
[146.262232] Starting dynamic subtest: pipe-D-eDP-1
[147.222125] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[147.224761] Subtest plane-lut1d-post-ctm3x4: FAIL (4.731s)
[147.677850] [017/132] (870s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[147.776028] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[147.778568] Starting dynamic subtest: pipe-A-default-mode
[150.485035] Dynamic subtest pipe-A-default-mode: SUCCESS (2.710s)
[150.487887] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.711s)
[151.359169] [018/132] (867s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[151.466251] Starting subtest: 2x-absolute-wf_vblank-interruptible
[151.478072] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[151.578191] [019/132] (867s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[151.669836] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[158.147403] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.488s)
[158.286266] [020/132] (860s left) gem_exec_params (invalid-bsd2-flag-on-render)
[158.340037] Starting subtest: invalid-bsd2-flag-on-render
[158.342740] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[158.456128] [021/132] (860s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[158.565412] Starting subtest: psr-rgb101010-draw-blt
[165.885354] Subtest psr-rgb101010-draw-blt: SUCCESS (7.327s)
[166.036045] [022/132] (852s left) syncobj_timeline (invalid-wait-bad-flags)
[166.099756] Starting subtest: invalid-wait-bad-flags
[166.102362] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[166.217712] [023/132] (852s left) gem_mmap_gtt (cpuset-big-copy-odd)
[166.276874] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[166.333239] [024/132] (852s left) gem_mmap_wc (bad-size)
[166.405776] Subtest bad-size: SKIP (0.000s)
[166.474048] [025/132] (852s left) gem_exec_balancer (invalid-bonds)
[166.528457] Starting subtest: invalid-bonds
[166.533608] Subtest invalid-bonds: SKIP (0.000s)
[166.859094] [026/132] (852s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[166.968612] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[166.973765] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[167.076058] [027/132] (851s left) kms_lease (lease-get)
[167.179919] Starting subtest: lease-get
[167.182804] Starting dynamic subtest: pipe-A-eDP-1
[167.185531] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[167.199617] Starting dynamic subtest: pipe-B-eDP-1
[167.202003] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[167.215435] Starting dynamic subtest: pipe-C-eDP-1
[167.217769] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[167.228546] Starting dynamic subtest: pipe-D-eDP-1
[167.230738] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[167.233362] Subtest lease-get: SUCCESS (0.010s)
[167.325304] [028/132] (851s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[167.435031] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[171.652878] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.221s)
[172.814402] [029/132] (846s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[173.202577] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[173.984687] [030/132] (845s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[174.090282] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[177.740365] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.654s)
[178.926074] [031/132] (840s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[179.036699] Starting subtest: yf-tiled-64bpp-rotate-0
[179.042193] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[179.165457] [032/132] (840s left) gem_mmap_gtt (medium-copy-odd)
[179.235053] Subtest medium-copy-odd: SKIP (0.000s)
[179.305341] [033/132] (839s left) gem_pxp (protected-raw-src-copy-not-readible)
[179.441769] Starting subtest: protected-raw-src-copy-not-readible
[179.503843] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.065s)
[179.669439] [034/132] (839s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[179.793988] Starting subtest: flip-vs-cursor-crc-atomic
[180.452756] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.660s)
[181.346522] [035/132] (837s left) kms_atomic_transition (plane-all-transition-fencing)
[181.440934] Starting subtest: plane-all-transition-fencing
[181.443549] Starting dynamic subtest: pipe-A-eDP-1
[183.936681] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[184.244024] Starting dynamic subtest: pipe-B-eDP-1
[187.459822] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.226s)
[187.770034] Subtest plane-all-transition-fencing: SUCCESS (6.327s)
[188.614063] [036/132] (830s left) prime_self_import (basic-with_one_bo_two_files)
[188.655847] Starting subtest: basic-with_one_bo_two_files
[188.683737] Subtest basic-with_one_bo_two_files: SUCCESS (0.028s)
[188.796355] [037/132] (830s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[188.894855] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[188.897796] Starting dynamic subtest: pipe-A-eDP-1
[188.924077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[188.934319] Starting dynamic subtest: pipe-B-eDP-1
[188.958856] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[188.967014] Starting dynamic subtest: pipe-C-eDP-1
[188.992383] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[189.000649] Starting dynamic subtest: pipe-D-eDP-1
[189.026625] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[189.031785] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[191.169545] [038/132] (828s left) i915_drm_fdinfo (all-busy-check-all)
[191.211040] Starting subtest: all-busy-check-all
[191.219055] Subtest all-busy-check-all: SKIP (0.004s)
[191.305139] [039/132] (828s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[191.418772] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[191.421369] Starting dynamic subtest: pipe-A-default-mode
[191.712701] Dynamic subtest pipe-A-default-mode: SKIP (0.290s)
[191.717653] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.292s)
[192.601504] [040/132] (826s left) kms_addfb_basic (bad-pitch-0)
[192.657004] Starting subtest: bad-pitch-0
[192.659526] Subtest bad-pitch-0: SUCCESS (0.000s)
[192.828198] [041/132] (826s left) gem_ctx_persistence (heartbeat-stop)
[192.887872] Starting subtest: heartbeat-stop
[192.893689] Subtest heartbeat-stop: SKIP (0.000s)
[193.028098] [042/132] (826s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[193.145668] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[193.150572] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[193.309482] [043/132] (826s left) gem_exec_schedule (smoketest)
[193.490655] Starting subtest: smoketest
[193.492824] Starting dynamic subtest: rcs0
[199.062875] Dynamic subtest rcs0: SUCCESS (5.512s)
[199.073322] Starting dynamic subtest: bcs0
[204.573385] Dynamic subtest bcs0: SUCCESS (5.523s)
[204.586087] Starting dynamic subtest: vcs0
[210.099808] Dynamic subtest vcs0: SUCCESS (5.510s)
[210.111532] Starting dynamic subtest: vcs1
[215.588816] Dynamic subtest vcs1: SUCCESS (5.501s)
[215.600989] Starting dynamic subtest: vecs0
[221.118525] Dynamic subtest vecs0: SUCCESS (5.523s)
[221.128500] Starting dynamic subtest: ccs0
[226.636865] Dynamic subtest ccs0: SUCCESS (5.520s)
[226.639900] Subtest smoketest: SUCCESS (33.095s)
[226.785237] [044/132] (792s left) gem_exec_parallel (contexts)
[226.861901] Starting subtest: contexts
[226.864494] Starting dynamic subtest: rcs0
[231.430337] Dynamic subtest rcs0: SUCCESS (4.567s)
[231.444798] Starting dynamic subtest: bcs0
[236.236036] Dynamic subtest bcs0: SUCCESS (4.799s)
[236.246295] Starting dynamic subtest: vcs0
[240.465069] Dynamic subtest vcs0: SUCCESS (4.228s)
[240.476919] Starting dynamic subtest: vcs1
[246.273768] Dynamic subtest vcs1: SUCCESS (5.805s)
[246.285538] Starting dynamic subtest: vecs0
[252.247315] Dynamic subtest vecs0: SUCCESS (5.970s)
[252.257537] Starting dynamic subtest: ccs0
[256.767699] Dynamic subtest ccs0: SUCCESS (4.517s)
[256.770484] Subtest contexts: SUCCESS (29.900s)
[256.986263] [045/132] (762s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[257.042040] Starting subtest: invalid-bsd2-flag-on-blt
[257.044901] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[257.199902] [046/132] (762s left) kms_psr (fbc-psr2-primary-render)
[257.307625] Starting subtest: fbc-psr2-primary-render
[257.310142] Starting dynamic subtest: eDP-1
[257.314844] Dynamic subtest eDP-1: SKIP (0.000s)
[257.319786] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[257.405939] [047/132] (762s left) prime_vgem (coherency-gtt)
[257.475375] Starting subtest: coherency-gtt
[257.480847] Subtest coherency-gtt: SKIP (0.000s)
[257.603757] [048/132] (762s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[257.717790] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[257.723009] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[257.886428] [049/132] (762s left) kms_flip (flip-vs-expired-vblank)
[258.011662] Starting subtest: flip-vs-expired-vblank
[258.017322] Starting dynamic subtest: A-eDP1
[266.768715] Dynamic subtest A-eDP1: SUCCESS (8.754s)
[266.777773] Starting dynamic subtest: B-eDP1
[275.418011] Dynamic subtest B-eDP1: SUCCESS (8.648s)
[275.427771] Starting dynamic subtest: C-eDP1
[284.039947] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[284.049064] Starting dynamic subtest: D-eDP1
[292.672780] Dynamic subtest D-eDP1: SUCCESS (8.633s)
[292.675756] Subtest flip-vs-expired-vblank: SUCCESS (34.668s)
[293.849790] [050/132] (726s left) gem_mmap_wc (write-read)
[293.917607] Subtest write-read: SKIP (0.000s)
[293.986034] [051/132] (726s left) kms_psr (psr-sprite-render)
[294.085840] Starting subtest: psr-sprite-render
[294.088492] Starting dynamic subtest: eDP-1
[295.846868] Dynamic subtest eDP-1: SUCCESS (1.763s)
[295.849845] Subtest psr-sprite-render: SUCCESS (1.764s)
[296.698498] [052/132] (723s left) kms_psr (psr2-cursor-blt)
[296.796219] Starting subtest: psr2-cursor-blt
[296.798625] Starting dynamic subtest: eDP-1
[298.454715] Dynamic subtest eDP-1: SUCCESS (1.658s)
[298.456967] Subtest psr2-cursor-blt: SUCCESS (1.659s)
[299.362382] [053/132] (720s left) kms_prime (basic-modeset-hybrid)
[299.452330] Subtest basic-modeset-hybrid: SKIP (0.000s)
[299.571767] [054/132] (720s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[299.672808] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[299.677816] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[299.803384] [055/132] (720s left) kms_psr (fbc-psr-sprite-plane-onoff)
[299.906925] Starting subtest: fbc-psr-sprite-plane-onoff
[299.909319] Starting dynamic subtest: eDP-1
[299.914031] Dynamic subtest eDP-1: SKIP (0.000s)
[299.919071] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[300.003701] [056/132] (720s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[300.076871] Starting subtest: stress-mm-invalidate-close-overlap
[302.240709] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[302.395404] [057/132] (717s left) gem_exec_params (batch-first)
[302.454819] Starting subtest: batch-first
[302.466600] Subtest batch-first: SUCCESS (0.011s)
[302.637495] [058/132] (717s left) i915_pm_rpm (gem-mmap-type)
[304.162202] Starting subtest: gem-mmap-type
[304.164999] Starting dynamic subtest: wb-smem0
[307.951321] Dynamic subtest wb-smem0: SUCCESS (3.786s)
[307.965299] Starting dynamic subtest: wc-smem0
[311.426600] Dynamic subtest wc-smem0: SUCCESS (3.478s)
[311.436088] Starting dynamic subtest: uc-smem0
[314.615180] Dynamic subtest uc-smem0: SUCCESS (3.188s)
[314.617882] Subtest gem-mmap-type: SUCCESS (10.455s)
[314.727837] [059/132] (705s left) dumb_buffer (create-clear)
[314.779186] Starting subtest: create-clear
[348.633862] Subtest create-clear: SUCCESS (33.861s)
[348.782351] [060/132] (671s left) i915_module_load (resize-bar)
[348.816810] Starting subtest: resize-bar
[348.824766] Subtest resize-bar: SKIP (0.005s)
[348.869369] [061/132] (671s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[348.935551] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[348.940802] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[349.147401] [062/132] (671s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[349.272441] Starting subtest: basic-busy-flip-before-cursor-varying-size
[349.277461] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[349.440593] [063/132] (671s left) gem_eio (kms)
[349.718067] Starting subtest: kms
[370.456596] Subtest kms: SUCCESS (20.736s)
[371.659824] [064/132] (648s left) gem_exec_fence (keep-in-fence)
[371.874022] Starting subtest: keep-in-fence
[371.876721] Starting dynamic subtest: rcs0
[371.956616] Dynamic subtest rcs0: SUCCESS (0.085s)
[371.970894] Starting dynamic subtest: bcs0
[372.013387] Dynamic subtest bcs0: SUCCESS (0.054s)
[372.030233] Starting dynamic subtest: vcs0
[373.906358] Dynamic subtest vcs0: SUCCESS (1.889s)
[373.919744] Starting dynamic subtest: vcs1
[375.890077] Dynamic subtest vcs1: SUCCESS (1.981s)
[375.903093] Starting dynamic subtest: vecs0
[377.938758] Dynamic subtest vecs0: SUCCESS (2.045s)
[377.951286] Starting dynamic subtest: ccs0
[378.172589] Dynamic subtest ccs0: SUCCESS (0.232s)
[378.175426] Subtest keep-in-fence: SUCCESS (6.301s)
[379.962126] [065/132] (640s left) kms_big_fb (linear-8bpp-rotate-270)
[380.090606] Starting subtest: linear-8bpp-rotate-270
[380.114018] Subtest linear-8bpp-rotate-270: SKIP (0.022s)
[380.243706] [066/132] (640s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[380.347733] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[387.420435] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.074s)
[387.549960] [067/132] (633s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[387.644624] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[387.649706] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[388.848598] [068/132] (631s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[388.958867] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[388.963846] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[389.092163] [069/132] (631s left) i915_query (query-garbage)
[389.151776] Starting subtest: query-garbage
[389.154304] Subtest query-garbage: SUCCESS (0.000s)
[389.291873] [070/132] (631s left) gem_huc_copy (huc-copy)
[389.354800] Starting subtest: huc-copy
[389.358032] Subtest huc-copy: SUCCESS (0.003s)
[389.504371] [071/132] (631s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[389.602450] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[393.349697] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.750s)
[394.501636] [072/132] (626s left) gem_create (create-invalid-size)
[394.565611] Starting subtest: create-invalid-size
[394.568021] Subtest create-invalid-size: SUCCESS (0.000s)
[394.787095] [073/132] (626s left) kms_pm_rpm (drm-resources-equal)
[397.052647] Starting subtest: drm-resources-equal
[402.034527] Subtest drm-resources-equal: SUCCESS (4.985s)
[402.150125] [074/132] (618s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[402.242719] Starting subtest: random-ccs-data-yf-tiled-ccs
[402.245138] Starting dynamic subtest: pipe-A-eDP-1
[402.271041] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[402.281009] Starting dynamic subtest: pipe-B-eDP-1
[402.309047] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[402.318561] Starting dynamic subtest: pipe-C-eDP-1
[402.346165] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[402.355036] Starting dynamic subtest: pipe-D-eDP-1
[402.384788] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[402.389827] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[404.544481] [075/132] (616s left) kms_lease (empty-lease)
[404.640777] Starting subtest: empty-lease
[404.643500] Starting dynamic subtest: pipe-A-eDP-1
[404.646007] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[404.655584] Starting dynamic subtest: pipe-B-eDP-1
[404.657931] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[404.666886] Starting dynamic subtest: pipe-C-eDP-1
[404.669410] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[404.678019] Starting dynamic subtest: pipe-D-eDP-1
[404.680584] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[404.682880] Subtest empty-lease: SUCCESS (0.008s)
[404.776408] [076/132] (616s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[404.892423] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[404.897418] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[405.008231] [077/132] (616s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[405.076263] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[405.079028] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[405.214418] [078/132] (616s left) api_intel_allocator (gem-pool)
[405.267149] Starting subtest: gem-pool
[405.272863] Subtest gem-pool: SUCCESS (0.009s)
[405.433000] [079/132] (615s left) core_getclient (basic)
[405.486992] Starting subtest: basic
[405.489757] Subtest basic: SUCCESS (0.000s)
[405.611719] [080/132] (615s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[405.710376] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[405.712582] Starting dynamic subtest: pipe-A
[406.062632] Dynamic subtest pipe-A: SUCCESS (0.354s)
[406.072752] Starting dynamic subtest: pipe-B
[407.527549] Dynamic subtest pipe-B: SUCCESS (1.462s)
[407.537206] Starting dynamic subtest: pipe-C
[409.008173] Dynamic subtest pipe-C: SUCCESS (1.479s)
[409.017593] Starting dynamic subtest: pipe-D
[410.403500] Dynamic subtest pipe-D: SUCCESS (1.394s)
[410.405856] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.696s)
[411.599084] [081/132] (609s left) syncobj_timeline (etime-single-wait-submitted)
[411.654905] Starting subtest: etime-single-wait-submitted
[411.757664] Subtest etime-single-wait-submitted: SUCCESS (0.104s)
[411.859175] [082/132] (609s left) gem_basic (create-close)
[411.916868] Starting subtest: create-close
[411.921876] Subtest create-close: SUCCESS (0.000s)
[412.053535] [083/132] (609s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[412.159939] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[418.845349] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.689s)
[419.008555] [084/132] (602s left) gem_spin_batch (legacy-resubmit)
[419.070806] Starting subtest: legacy-resubmit
[419.073866] Starting dynamic subtest: default
[419.080030] Dynamic subtest default: SUCCESS (0.007s)
[419.090861] Starting dynamic subtest: render
[419.093688] Dynamic subtest render: SUCCESS (0.001s)
[419.103771] Starting dynamic subtest: bsd
[419.105931] Dynamic subtest bsd: SUCCESS (0.003s)
[419.114661] Starting dynamic subtest: bsd1
[419.116779] Dynamic subtest bsd1: SUCCESS (0.002s)
[419.125528] Starting dynamic subtest: bsd2
[419.127586] Dynamic subtest bsd2: SUCCESS (0.003s)
[419.136182] Starting dynamic subtest: blt
[419.138186] Dynamic subtest blt: SUCCESS (0.003s)
[419.146716] Starting dynamic subtest: vebox
[419.148749] Dynamic subtest vebox: SUCCESS (0.003s)
[419.150769] Subtest legacy-resubmit: SUCCESS (0.027s)
[419.370292] [085/132] (602s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[419.470028] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[419.472818] Starting dynamic subtest: pipe-A-eDP-1
[419.495819] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[419.506682] Starting dynamic subtest: pipe-B-eDP-1
[419.529009] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[419.537882] Starting dynamic subtest: pipe-C-eDP-1
[419.560683] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[419.569596] Starting dynamic subtest: pipe-D-eDP-1
[419.592514] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[419.594773] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[421.693272] [086/132] (599s left) perf_pmu (semaphore-wait-idle)
[421.739173] Starting subtest: semaphore-wait-idle
[421.742042] Starting dynamic subtest: rcs0
[422.269148] Dynamic subtest rcs0: SUCCESS (0.515s)
[422.282041] Starting dynamic subtest: bcs0
[422.768636] Dynamic subtest bcs0: SUCCESS (0.510s)
[422.781018] Starting dynamic subtest: vcs0
[423.282569] Dynamic subtest vcs0: SUCCESS (0.511s)
[423.295751] Starting dynamic subtest: vcs1
[423.796632] Dynamic subtest vcs1: SUCCESS (0.511s)
[423.809553] Starting dynamic subtest: vecs0
[424.310309] Dynamic subtest vecs0: SUCCESS (0.511s)
[424.323046] Starting dynamic subtest: ccs0
[424.827788] Dynamic subtest ccs0: SUCCESS (0.514s)
[424.830876] Subtest semaphore-wait-idle: SUCCESS (3.089s)
[426.037619] [087/132] (595s left) gem_wait (write-wait)
[426.107043] Starting subtest: write-wait
[426.109957] Starting dynamic subtest: all-engines
[427.278678] Dynamic subtest all-engines: SUCCESS (1.171s)
[427.296090] Starting dynamic subtest: rcs0
[428.341995] Dynamic subtest rcs0: SUCCESS (1.061s)
[428.359530] Starting dynamic subtest: bcs0
[429.404897] Dynamic subtest bcs0: SUCCESS (1.061s)
[429.422054] Starting dynamic subtest: vcs0
[430.463168] Dynamic subtest vcs0: SUCCESS (1.056s)
[430.480056] Starting dynamic subtest: vcs1
[431.523037] Dynamic subtest vcs1: SUCCESS (1.058s)
[431.540062] Starting dynamic subtest: vecs0
[432.581141] Dynamic subtest vecs0: SUCCESS (1.056s)
[432.598048] Starting dynamic subtest: ccs0
[433.641942] Dynamic subtest ccs0: SUCCESS (1.059s)
[433.644832] Subtest write-wait: SUCCESS (7.536s)
[433.868134] [088/132] (587s left) kms_atomic (plane-primary-legacy)
[433.970742] Starting subtest: plane-primary-legacy
[433.973465] Starting dynamic subtest: pipe-A-eDP-1
[434.415947] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.444s)
[434.418901] Subtest plane-primary-legacy: SUCCESS (0.446s)
[435.324270] [089/132] (586s left) sysfs_preempt_timeout (invalid)
[435.387826] Starting subtest: invalid
[435.390757] Starting dynamic subtest: vecs0
[435.393658] Dynamic subtest vecs0: SUCCESS (0.000s)
[435.410044] Starting dynamic subtest: ccs0
[435.412973] Dynamic subtest ccs0: SUCCESS (0.000s)
[435.424832] Starting dynamic subtest: vcs0
[435.427876] Dynamic subtest vcs0: SUCCESS (0.000s)
[435.437570] Starting dynamic subtest: bcs0
[435.440270] Dynamic subtest bcs0: SUCCESS (0.000s)
[435.450009] Starting dynamic subtest: rcs0
[435.452410] Dynamic subtest rcs0: SUCCESS (0.000s)
[435.461461] Starting dynamic subtest: vcs1
[435.463852] Dynamic subtest vcs1: SUCCESS (0.000s)
[435.466326] Subtest invalid: SUCCESS (0.005s)
[435.530261] [090/132] (586s left) gem_exec_fence (syncobj-timeline-signal)
[436.046062] Starting subtest: syncobj-timeline-signal
[436.054894] Subtest syncobj-timeline-signal: SUCCESS (0.010s)
[437.088788] [091/132] (584s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[437.311691] Starting subtest: cursor-rapid-movement-64x21
[437.361538] Subtest cursor-rapid-movement-64x21: SKIP (0.041s)
[437.654409] [092/132] (584s left) perf (oa-formats)
[437.783711] Starting subtest: oa-formats
[437.786282] Starting dynamic subtest: 0-rcs0
[437.796429] Dynamic subtest 0-rcs0: SUCCESS (0.014s)
[437.806354] Starting dynamic subtest: 1-vecs0
[437.819044] Dynamic subtest 1-vecs0: SUCCESS (0.022s)
[437.821877] Subtest oa-formats: SUCCESS (0.038s)
[437.869379] [093/132] (583s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[437.967950] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[437.970652] Starting dynamic subtest: pipe-A-eDP-1
[438.025703] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.060s)
[438.034825] Starting dynamic subtest: pipe-B-eDP-1
[438.085807] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.059s)
[438.095376] Starting dynamic subtest: pipe-C-eDP-1
[438.146494] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.059s)
[438.155586] Starting dynamic subtest: pipe-D-eDP-1
[438.207182] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.059s)
[438.209496] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.241s)
[440.411150] [094/132] (581s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[440.478933] Starting subtest: invalid-bsd1-flag-on-blt
[440.481908] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[440.599504] [095/132] (581s left) gem_ctx_persistence (engines-cleanup)
[440.649858] Starting subtest: engines-cleanup
[440.653052] Starting dynamic subtest: rcs0
[440.751207] Dynamic subtest rcs0: SUCCESS (0.101s)
[440.764046] Starting dynamic subtest: bcs0
[440.797150] Dynamic subtest bcs0: SUCCESS (0.044s)
[440.807805] Starting dynamic subtest: vcs0
[440.846225] Dynamic subtest vcs0: SUCCESS (0.048s)
[440.857848] Starting dynamic subtest: vcs1
[440.905235] Dynamic subtest vcs1: SUCCESS (0.057s)
[440.917771] Starting dynamic subtest: vecs0
[440.958143] Dynamic subtest vecs0: SUCCESS (0.051s)
[440.970033] Starting dynamic subtest: ccs0
[440.976858] Dynamic subtest ccs0: SUCCESS (0.017s)
[440.979572] Subtest engines-cleanup: SUCCESS (0.328s)
[441.135400] [096/132] (580s left) i915_suspend (debugfs-reader)
[441.206075] Starting subtest: debugfs-reader
[459.306983] Subtest debugfs-reader: SUCCESS (3.202s)
[459.477348] [097/132] (562s left) kms_draw_crc (draw-method-mmap-wc)
[459.574001] Starting subtest: draw-method-mmap-wc
[459.575903] Starting dynamic subtest: xrgb8888-untiled
[459.793358] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[459.803013] Starting dynamic subtest: rgb565-untiled
[460.003188] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[460.012842] Starting dynamic subtest: xrgb2101010-untiled
[460.223909] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[460.233644] Starting dynamic subtest: xbgr16161616f-untiled
[460.422658] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.199s)
[460.432473] Starting dynamic subtest: xrgb8888-xtiled
[460.565705] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.144s)
[460.574913] Starting dynamic subtest: rgb565-xtiled
[460.698427] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[460.714207] Starting dynamic subtest: xrgb2101010-xtiled
[460.831190] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.132s)
[460.840668] Starting dynamic subtest: xbgr16161616f-xtiled
[460.952514] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[460.963135] Starting dynamic subtest: xrgb8888-4tiled
[461.117690] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s)
[461.126802] Starting dynamic subtest: rgb565-4tiled
[461.261672] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[461.270386] Starting dynamic subtest: xrgb2101010-4tiled
[461.404849] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.144s)
[461.414080] Starting dynamic subtest: xbgr16161616f-4tiled
[461.581883] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[461.583727] Subtest draw-method-mmap-wc: SUCCESS (2.023s)
[461.715070] [098/132] (560s left) gem_userptr_blits (sync-unmap)
[461.780955] Starting subtest: sync-unmap
[461.804777] Subtest sync-unmap: SUCCESS (0.026s)
[461.967811] [099/132] (560s left) kms_content_protection (dp-mst-suspend-resume)
[462.113949] Starting subtest: dp-mst-suspend-resume
[462.117588] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[462.279782] [100/132] (559s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[462.377808] Starting subtest: addfb25-y-tiled-small-legacy
[462.381308] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[462.515709] [101/132] (559s left) syncobj_timeline (wait-for-submit-snapshot)
[462.580693] Starting subtest: wait-for-submit-snapshot
[462.661543] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[462.760059] [102/132] (559s left) gem_eio (in-flight-internal-1us)
[462.976595] Starting subtest: in-flight-internal-1us
[464.915381] Subtest in-flight-internal-1us: SUCCESS (1.945s)
[465.116046] [103/132] (557s left) gem_create (create-size-update)
[465.170923] Starting subtest: create-size-update
[465.172781] Subtest create-size-update: SUCCESS (0.000s)
[465.416978] [104/132] (557s left) kms_draw_crc (fill-fb)
[465.526952] Starting subtest: fill-fb
[466.010204] Subtest fill-fb: SUCCESS (0.486s)
[466.161937] [105/132] (556s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[466.263695] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[466.266100] Starting dynamic subtest: pipe-A-eDP-1
[466.725593] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.436s)
[466.735924] Starting dynamic subtest: pipe-B-eDP-1
[468.136447] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.408s)
[468.148138] Starting dynamic subtest: pipe-C-eDP-1
[469.532964] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s)
[469.543814] Starting dynamic subtest: pipe-D-eDP-1
[470.845766] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s)
[470.847735] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.568s)
[472.056463] [106/132] (550s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[472.174612] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[472.179224] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[472.290317] [107/132] (550s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[472.402738] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[472.407121] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[472.531441] [108/132] (550s left) gem_mmap_offset (bad-flags)
[472.594923] Starting subtest: bad-flags
[472.597343] Subtest bad-flags: SUCCESS (0.000s)
[472.720930] [109/132] (549s left) gem_mmap_gtt (big-copy)
[472.780447] Subtest big-copy: SKIP (0.000s)
[472.843128] [110/132] (549s left) kms_plane_multiple (tiling-y)
[472.938359] Starting subtest: tiling-y
[472.942519] Subtest tiling-y: SKIP (0.000s)
[473.030724] [111/132] (549s left) kms_plane_scaling (planes-upscale-factor-0-25)
[473.124091] Starting subtest: planes-upscale-factor-0-25
[473.126240] Starting dynamic subtest: pipe-A
[473.184959] Dynamic subtest pipe-A: SUCCESS (0.062s)
[473.196526] Starting dynamic subtest: pipe-B
[474.354569] Dynamic subtest pipe-B: SUCCESS (1.170s)
[474.366599] Starting dynamic subtest: pipe-C
[475.464157] Dynamic subtest pipe-C: SUCCESS (1.109s)
[475.479409] Starting dynamic subtest: pipe-D
[476.597142] Dynamic subtest pipe-D: SUCCESS (1.133s)
[476.599533] Subtest planes-upscale-factor-0-25: SUCCESS (3.479s)
[477.776184] [112/132] (544s left) gem_ctx_param (set-priority-range)
[477.836689] Starting subtest: set-priority-range
[477.856270] Subtest set-priority-range: SUCCESS (0.019s)
[478.012211] [113/132] (544s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[478.120422] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[478.124713] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[478.276605] [114/132] (544s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[478.386256] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[484.924296] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.545s)
[485.090061] [115/132] (537s left) kms_scaling_modes (scaling-mode-center)
[485.195038] Starting subtest: scaling-mode-center
[485.197197] Starting dynamic subtest: pipe-A-eDP-1
[485.288559] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[485.302699] Starting dynamic subtest: pipe-B-eDP-1
[486.501690] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.212s)
[486.512719] Starting dynamic subtest: pipe-C-eDP-1
[487.599624] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.098s)
[487.609333] Starting dynamic subtest: pipe-D-eDP-1
[488.734153] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[488.736602] Subtest scaling-mode-center: SUCCESS (3.545s)
[489.930601] [116/132] (533s left) gen9_exec_parse (secure-batches)
[489.993852] Subtest secure-batches: SKIP (0.000s)
[490.069061] [117/132] (532s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[490.173219] Starting subtest: 4-tiled-64bpp-rotate-180
[491.651744] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.478s)
[491.877765] [118/132] (531s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[496.890089] Starting subtest: psr2-plane-move-sf-dmg-area
[496.892482] Starting dynamic subtest: pipe-A-eDP-1
[502.160348] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.266s)
[502.174438] Starting dynamic subtest: pipe-B-eDP-1
[507.479039] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.314s)
[507.481332] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.584s)
[508.300496] [119/132] (514s left) kms_vblank (query-forked-busy)
[508.414216] Starting subtest: query-forked-busy
[508.416354] Starting dynamic subtest: pipe-A-eDP-1
[510.291128] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.879s)
[510.305836] Starting dynamic subtest: pipe-D-eDP-1
[512.831910] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.538s)
[512.834261] Subtest query-forked-busy: SUCCESS (4.421s)
[513.667324] [120/132] (509s left) gem_ccs (block-copy-uncompressed)
[513.738018] Starting subtest: block-copy-uncompressed
[513.740432] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[513.760603] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[513.772128] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[513.777669] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[513.787384] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[513.794452] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[513.803247] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[513.811281] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[513.813587] Subtest block-copy-uncompressed: SUCCESS (0.074s)
[513.983053] [121/132] (509s left) syncobj_eventfd (binary-wait-signaled)
[514.045164] Starting subtest: binary-wait-signaled
[514.047515] Subtest binary-wait-signaled: SUCCESS (0.000s)
[514.168429] [122/132] (509s left) kms_draw_crc (draw-method-mmap-gtt)
[514.264103] Starting subtest: draw-method-mmap-gtt
[514.268550] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[514.383667] [123/132] (508s left) gem_exec_schedule (preempt-other)
[514.459589] Starting subtest: preempt-other
[514.461574] Starting dynamic subtest: rcs0
[514.523475] Dynamic subtest rcs0: SUCCESS (0.072s)
[514.538446] Starting dynamic subtest: bcs0
[514.588094] Dynamic subtest bcs0: SUCCESS (0.064s)
[514.602007] Starting dynamic subtest: vcs0
[514.654120] Dynamic subtest vcs0: SUCCESS (0.065s)
[514.665839] Starting dynamic subtest: vcs1
[514.726835] Dynamic subtest vcs1: SUCCESS (0.072s)
[514.736874] Starting dynamic subtest: vecs0
[514.795363] Dynamic subtest vecs0: SUCCESS (0.068s)
[514.805353] Starting dynamic subtest: ccs0
[514.863253] Dynamic subtest ccs0: SUCCESS (0.067s)
[514.865490] Subtest preempt-other: SUCCESS (0.413s)
[515.978319] [124/132] (507s left) kms_plane_lowres (tiling-4)
[516.069645] Starting subtest: tiling-4
[516.071950] Starting dynamic subtest: pipe-A-eDP-1
[516.076450] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[516.365663] Starting dynamic subtest: pipe-B-eDP-1
[516.370144] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[516.379481] Starting dynamic subtest: pipe-C-eDP-1
[516.383831] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[516.392721] Starting dynamic subtest: pipe-D-eDP-1
[516.396583] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[516.400608] Subtest tiling-4: SKIP (0.296s)
[517.198671] [125/132] (506s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[517.313782] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[523.525221] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.216s)
[524.679302] [126/132] (498s left) vgem_basic (setversion)
[524.729393] Starting subtest: setversion
[524.733591] Subtest setversion: SUCCESS (0.000s)
[524.811300] [127/132] (498s left) gem_exec_suspend (basic-s3-devices)
[524.874277] Starting subtest: basic-S3-devices
[524.876611] Starting dynamic subtest: smem
[536.844635] Dynamic subtest smem: SUCCESS (11.970s)
[536.847752] Subtest basic-S3-devices: SUCCESS (11.972s)
[537.055424] [128/132] (486s left) gem_eio (in-flight-suspend)
[537.319586] Starting subtest: in-flight-suspend
[580.626184] Subtest in-flight-suspend: SUCCESS (13.522s)
[580.798637] [129/132] (442s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[580.907358] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[588.504472] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.622s)
[588.688947] [130/132] (434s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[588.797701] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[593.241251] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.460s)
[594.480127] [131/132] (429s left) perf (non-zero-reason)
[594.590194] Starting subtest: non-zero-reason
[594.592727] Starting dynamic subtest: 0-rcs0
[599.968343] Dynamic subtest 0-rcs0: SUCCESS (5.384s)
[599.981081] Starting dynamic subtest: 1-vcs0
[603.870924] Dynamic subtest 1-vcs0: SUCCESS (3.905s)
[603.873820] Subtest non-zero-reason: SUCCESS (9.291s)
[603.923235] [132/132] (419s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[603.973867] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[604.074351] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[604.179812] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_cursor_crc
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_rotation_crc
results: parsing output: 4/ for test: kms_vrr
results: parsing output: 5/ for test: gem_exec_whisper
results: parsing output: 6/ for test: kms_chamelium_edid
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_partial_pwrite_pread
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: kms_color_pipeline
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_mmap_gtt
results: parsing output: 23/ for test: gem_mmap_wc
results: parsing output: 24/ for test: gem_exec_balancer
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_lease
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_chamelium_hpd
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: gem_pxp
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_atomic_transition
results: parsing output: 35/ for test: prime_self_import
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: gem_exec_parallel
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: prime_vgem
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_mmap_wc
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_prime
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_userptr_blits
results: parsing output: 56/ for test: gem_exec_params
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: dumb_buffer
results: parsing output: 59/ for test: i915_module_load
results: parsing output: 60/ for test: gem_render_copy
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: gem_eio
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: i915_query
results: parsing output: 69/ for test: gem_huc_copy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_create
results: parsing output: 72/ for test: kms_pm_rpm
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_lease
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: api_intel_allocator
results: parsing output: 78/ for test: core_getclient
results: parsing output: 79/ for test: kms_plane_scaling
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_basic
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_spin_batch
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: gem_wait
results: parsing output: 87/ for test: kms_atomic
results: parsing output: 88/ for test: sysfs_preempt_timeout
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: kms_cursor_crc
results: parsing output: 91/ for test: perf
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: i915_suspend
results: parsing output: 96/ for test: kms_draw_crc
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_content_protection
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: gem_create
results: parsing output: 103/ for test: kms_draw_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_mmap_offset
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_plane_multiple
results: parsing output: 110/ for test: kms_plane_scaling
results: parsing output: 111/ for test: gem_ctx_param
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_scaling_modes
results: parsing output: 115/ for test: gen9_exec_parse
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_vblank
results: parsing output: 119/ for test: gem_ccs
results: parsing output: 120/ for test: syncobj_eventfd
results: parsing output: 121/ for test: kms_draw_crc
results: parsing output: 122/ for test: gem_exec_schedule
results: parsing output: 123/ for test: kms_plane_lowres
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: vgem_basic
results: parsing output: 126/ for test: gem_exec_suspend
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: perf
results: parsing output: 131/ for test: syncobj_timeline
Done.