[56.836199] Initializing watchdogs
[56.836310]   /dev/watchdog0
[56.851405] [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]
[56.857328] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.859584] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.862292] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-128x42)
[57.110136] Starting subtest: cursor-offscreen-128x42
[57.162560] Subtest cursor-offscreen-128x42: SKIP (0.049s)
[57.372207] [002/134] (960s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[57.475569] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[58.545302] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.077s)
[58.718302] [003/134] (958s left) gem_exec_reloc (basic-write-gtt-active)
[58.783645] Subtest basic-write-gtt-active: SKIP (0.000s)
[58.858592] [004/134] (958s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[58.948455] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[58.950289] Starting dynamic subtest: pipe-A-eDP-1
[58.968924] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[58.977131] Starting dynamic subtest: pipe-B-eDP-1
[58.994924] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[59.001266] Starting dynamic subtest: pipe-C-eDP-1
[59.018889] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[59.025320] Starting dynamic subtest: pipe-D-eDP-1
[59.043691] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[59.047425] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s)
[61.225618] [005/134] (956s left) kms_psr (psr2-cursor-blt)
[61.320473] Starting subtest: psr2-cursor-blt
[61.322833] Starting dynamic subtest: eDP-1
[63.263416] Dynamic subtest eDP-1: SUCCESS (1.953s)
[63.265661] Subtest psr2-cursor-blt: SUCCESS (1.953s)
[64.149444] [006/134] (953s left) i915_query (engine-info-invalid)
[64.204064] Starting subtest: engine-info-invalid
[64.206464] Subtest engine-info-invalid: SUCCESS (0.000s)
[64.325197] [007/134] (953s left) core_hotunplug (unbind-rebind)
[64.417064] Starting subtest: unbind-rebind
[70.451616] Subtest unbind-rebind: SUCCESS (6.049s)
[70.488296] [008/134] (947s left) kms_psr (psr-primary-render)
[71.900362] Starting subtest: psr-primary-render
[71.902803] Starting dynamic subtest: eDP-1
[73.640077] Dynamic subtest eDP-1: SUCCESS (1.743s)
[73.642540] Subtest psr-primary-render: SUCCESS (1.743s)
[74.521341] [009/134] (943s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[74.622067] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[74.626405] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[74.739101] [010/134] (942s left) sysfs_timeslice_duration (idempotent)
[74.799328] Starting subtest: idempotent
[74.801696] Starting dynamic subtest: vecs0
[74.803968] Dynamic subtest vecs0: SUCCESS (0.000s)
[74.813312] Starting dynamic subtest: ccs0
[74.815230] Dynamic subtest ccs0: SUCCESS (0.000s)
[74.822374] Starting dynamic subtest: vcs0
[74.824290] Dynamic subtest vcs0: SUCCESS (0.000s)
[74.831608] Starting dynamic subtest: bcs0
[74.833545] Dynamic subtest bcs0: SUCCESS (0.000s)
[74.841072] Starting dynamic subtest: rcs0
[74.842937] Dynamic subtest rcs0: SUCCESS (0.000s)
[74.849783] Starting dynamic subtest: vcs1
[74.852860] Dynamic subtest vcs1: SUCCESS (0.000s)
[74.855604] Subtest idempotent: SUCCESS (0.003s)
[74.932082] [011/134] (942s left) gem_gpgpu_fill (offset-16x16)
[74.985911] Starting subtest: offset-16x16
[74.992655] Subtest offset-16x16: SUCCESS (0.009s)
[75.157507] [012/134] (942s left) gem_readwrite (read-bad-handle)
[75.211305] Subtest read-bad-handle: SKIP (0.000s)
[75.271240] [013/134] (942s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[75.374209] Starting subtest: 2x-flip-vs-modeset-vs-hang
[75.383177] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.011s)
[75.499796] [014/134] (942s left) gem_mmap_gtt (fault-concurrent)
[75.556435] Subtest fault-concurrent: SKIP (0.000s)
[75.609868] [015/134] (942s left) syncobj_timeline (invalid-signal-bad-pad)
[75.669772] Starting subtest: invalid-signal-bad-pad
[75.671854] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[75.792203] [016/134] (942s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[75.842445] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[75.902785] [017/134] (942s left) kms_chamelium_color (ctm-green-to-red)
[75.998462] Subtest ctm-green-to-red: SKIP (0.000s)
[76.039613] [018/134] (941s left) perf_pmu (multi-client)
[76.076551] Starting subtest: multi-client
[76.078662] Starting dynamic subtest: rcs0
[77.158381] Dynamic subtest rcs0: SUCCESS (1.080s)
[77.167711] Starting dynamic subtest: bcs0
[78.208011] Dynamic subtest bcs0: SUCCESS (1.049s)
[78.217896] Starting dynamic subtest: vcs0
[79.258203] Dynamic subtest vcs0: SUCCESS (1.049s)
[79.269761] Starting dynamic subtest: vcs1
[80.907531] Dynamic subtest vcs1: SUCCESS (1.650s)
[80.918326] Starting dynamic subtest: vecs0
[82.891515] Dynamic subtest vecs0: SUCCESS (1.984s)
[82.902998] Starting dynamic subtest: ccs0
[84.939085] Dynamic subtest ccs0: SUCCESS (2.047s)
[84.941712] Subtest multi-client: SUCCESS (8.864s)
[85.070924] [019/134] (932s left) gem_reg_read (timestamp-monotonic)
[85.129868] Starting subtest: timestamp-monotonic
[89.823576] Subtest timestamp-monotonic: SUCCESS (4.692s)
[89.905573] [020/134] (928s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[90.006565] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[90.011085] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[90.126423] [021/134] (927s left) kms_atomic (atomic-invalid-params)
[90.224605] Starting subtest: atomic-invalid-params
[90.226972] Starting dynamic subtest: pipe-A-eDP-1
[90.614776] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[90.617266] Subtest atomic-invalid-params: SUCCESS (0.391s)
[91.474263] [022/134] (926s left) gem_userptr_blits (userfault)
[91.540823] Starting subtest: userfault
[91.543257] Subtest userfault: SUCCESS (0.007s)
[91.700240] [023/134] (926s left) prime_mmap (test_forked_cpu_write)
[91.756846] Starting subtest: test_forked_cpu_write
[91.759610] Starting dynamic subtest: test_forked_cpu_write-smem
[91.763849] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.007s)
[91.766146] Subtest test_forked_cpu_write: SUCCESS (0.007s)
[91.905357] [024/134] (926s left) gem_flink_basic (basic)
[91.966934] Starting subtest: basic
[91.969329] Subtest basic: SUCCESS (0.000s)
[92.098686] [025/134] (926s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[92.191279] Starting subtest: fbcpsr-rgb565-draw-pwrite
[99.266664] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.078s)
[99.426096] [026/134] (918s left) kms_psr (fbc-psr2-suspend)
[99.518070] Starting subtest: fbc-psr2-suspend
[99.520353] Starting dynamic subtest: eDP-1
[99.524462] Dynamic subtest eDP-1: SKIP (0.000s)
[99.528624] Subtest fbc-psr2-suspend: SKIP (0.000s)
[99.598783] [027/134] (918s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[99.854722] Starting subtest: parallel-dmabuf-import-out-fence
[100.897578] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.043s)
[100.997655] [028/134] (917s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[101.096725] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[108.491435] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.397s)
[108.613057] [029/134] (909s left) kms_setmode (clone-exclusive-crtc)
[108.670390] Starting subtest: clone-exclusive-crtc
[108.677286] Subtest clone-exclusive-crtc: SKIP (0.000s)
[108.806069] [030/134] (909s left) kms_vrr (flip-basic)
[108.907777] Starting subtest: flip-basic
[108.913757] Subtest flip-basic: SKIP (0.000s)
[108.989377] [031/134] (909s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[109.080260] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[109.082591] Starting dynamic subtest: pipe-A-default-mode
[109.383230] Dynamic subtest pipe-A-default-mode: SKIP (0.298s)
[109.387921] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.300s)
[110.228673] [032/134] (908s left) gem_mmap (bad-offset)
[110.284836] Subtest bad-offset: SKIP (0.000s)
[110.357325] [033/134] (908s left) syncobj_basic (bad-destroy)
[110.413698] Starting subtest: bad-destroy
[110.415986] Subtest bad-destroy: SUCCESS (0.000s)
[110.535852] [034/134] (908s left) api_intel_allocator (simple-allocator)
[110.595068] Starting subtest: simple-allocator
[110.597423] Starting dynamic subtest: basic
[110.599536] Dynamic subtest basic: SUCCESS (0.004s)
[110.607792] Starting dynamic subtest: parallel-one
[110.768098] Dynamic subtest parallel-one: SUCCESS (0.170s)
[110.776264] Starting dynamic subtest: print
[110.778366] Dynamic subtest print: SUCCESS (0.000s)
[110.787043] Starting dynamic subtest: reuse
[110.789173] Dynamic subtest reuse: SUCCESS (0.000s)
[110.797134] Starting dynamic subtest: reserve
[110.799270] Dynamic subtest reserve: SUCCESS (0.000s)
[110.807138] Starting dynamic subtest: fork-reopen-allocator
[110.809244] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[110.811398] Subtest simple-allocator: SUCCESS (0.189s)
[110.918061] [035/134] (907s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[111.015660] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[111.017896] Starting dynamic subtest: pipe-A-default-mode
[111.306203] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[111.310876] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.288s)
[112.147687] [036/134] (906s left) syncobj_eventfd (invalid-illegal-handle)
[112.200330] Starting subtest: invalid-illegal-handle
[112.202735] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[112.308144] [037/134] (906s left) kms_cursor_edge_walk (64x64-top-edge)
[112.403061] Starting subtest: 64x64-top-edge
[112.405330] Starting dynamic subtest: pipe-A-eDP-1
[115.894084] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.492s)
[115.903435] Starting dynamic subtest: pipe-D-eDP-1
[119.018326] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[119.020855] Subtest 64x64-top-edge: SUCCESS (6.615s)
[119.833266] [038/134] (898s left) gem_exec_reloc (basic-wc-cpu)
[119.897878] Subtest basic-wc-cpu: SKIP (0.000s)
[119.980797] [039/134] (898s left) kms_colorop (plane-xr30-xr30-srgb_eotf)
[120.120823] Subtest plane-XR30-XR30-srgb_eotf: SKIP (0.000s)
[120.197224] [040/134] (898s left) gem_userptr_blits (stress-mm-invalidate-close)
[120.266321] Starting subtest: stress-mm-invalidate-close
[122.415581] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[122.567506] [041/134] (896s left) gem_partial_pwrite_pread (reads-uncached)
[122.620641] Subtest reads-uncached: SKIP (0.000s)
[122.704808] [042/134] (896s left) kms_flip (flip-vs-expired-vblank-interruptible)
[122.810739] Starting subtest: flip-vs-expired-vblank-interruptible
[122.813019] Starting dynamic subtest: A-eDP1
[131.435933] Dynamic subtest A-eDP1: SUCCESS (8.624s)
[131.446331] Starting dynamic subtest: B-eDP1
[140.064925] Dynamic subtest B-eDP1: SUCCESS (8.628s)
[140.076334] Starting dynamic subtest: C-eDP1
[148.685827] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[148.699086] Starting dynamic subtest: D-eDP1
[157.319550] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[157.321943] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.510s)
[158.490300] [043/134] (860s left) api_intel_bb (object-noreloc-keep-cache-simple)
[158.549615] Starting subtest: object-noreloc-keep-cache-simple
[158.554033] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.007s)
[158.697700] [044/134] (860s left) kms_colorop (plane-xr24-xr24-gamma_2_2_inv_oetf)
[158.821305] Subtest plane-XR24-XR24-gamma_2_2_inv_oetf: SKIP (0.000s)
[158.898636] [045/134] (860s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[158.995358] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[158.999400] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[159.117672] [046/134] (859s left) perf (polling)
[159.252658] Starting subtest: polling
[159.254989] Starting dynamic subtest: 0-rcs0
[169.262708] Dynamic subtest 0-rcs0: SUCCESS (10.010s)
[169.271897] Starting dynamic subtest: 1-vecs0
[179.284572] Dynamic subtest 1-vecs0: SUCCESS (10.020s)
[179.286827] Subtest polling: SUCCESS (20.031s)
[179.329838] [047/134] (839s left) syncobj_wait (wait-all-snapshot)
[179.392442] Starting subtest: wait-all-snapshot
[179.472880] Subtest wait-all-snapshot: SUCCESS (0.081s)
[179.574474] [048/134] (839s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[179.672912] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[179.675170] Starting dynamic subtest: pipe-A-eDP-1
[179.732259] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[179.740635] Starting dynamic subtest: pipe-B-eDP-1
[179.796271] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[179.806274] Starting dynamic subtest: pipe-C-eDP-1
[179.865496] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[179.875141] Starting dynamic subtest: pipe-D-eDP-1
[179.930713] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[179.935299] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.008s)
[181.936047] [049/134] (837s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[182.040313] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[182.044584] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[182.153407] [050/134] (837s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[182.248766] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[189.024228] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.778s)
[189.192417] [051/134] (830s left) gem_mmap_offset (partial-remap)
[189.241389] Starting subtest: partial-remap
[189.243604] Starting dynamic subtest: smem0
[189.252964] Dynamic subtest smem0: SUCCESS (0.006s)
[189.254915] Subtest partial-remap: SUCCESS (0.007s)
[189.362740] [052/134] (829s left) kms_cursor_crc (cursor-random-128x128)
[189.558170] Starting subtest: cursor-random-128x128
[189.563167] Starting dynamic subtest: pipe-A-eDP-1
[192.898391] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.336s)
[192.929051] Starting dynamic subtest: pipe-D-eDP-1
[196.866120] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.943s)
[196.867979] Subtest cursor-random-128x128: SUCCESS (7.305s)
[197.731863] [053/134] (821s left) gem_render_copy_redux (interruptible)
[197.799864] Starting subtest: interruptible
[198.061051] Subtest interruptible: SUCCESS (0.264s)
[198.231635] [054/134] (821s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[198.344962] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[198.349204] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[198.485295] [055/134] (820s left) dumb_buffer (map-uaf)
[198.545557] Starting subtest: map-uaf
[198.550620] Subtest map-uaf: SUCCESS (0.006s)
[198.668425] [056/134] (820s left) kms_flip (2x-flip-vs-panning)
[198.755599] Starting subtest: 2x-flip-vs-panning
[198.764849] Subtest 2x-flip-vs-panning: SKIP (0.011s)
[198.862845] [057/134] (820s left) perf (create-destroy-userspace-config)
[198.965282] Starting subtest: create-destroy-userspace-config
[198.984287] Subtest create-destroy-userspace-config: SUCCESS (0.017s)
[199.026881] [058/134] (820s left) gem_ctx_persistence (heartbeat-hang)
[199.074679] Starting subtest: heartbeat-hang
[199.078735] Subtest heartbeat-hang: SKIP (0.000s)
[199.200883] [059/134] (820s left) kms_dsc (dsc-with-output-formats)
[199.288474] Starting subtest: dsc-with-output-formats
[199.306749] Subtest dsc-with-output-formats: SKIP (0.002s)
[199.374329] [060/134] (820s left) i915_pm_rps (engine-order)
[199.436681] Starting subtest: engine-order
[204.489529] Subtest engine-order: SUCCESS (5.052s)
[204.657574] [061/134] (814s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[204.703353] Starting subtest: multi-wait-for-submit-submitted-signaled
[204.705768] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[204.820414] [062/134] (814s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[204.914676] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[204.916827] Starting dynamic subtest: pipe-A
[205.050677] Dynamic subtest pipe-A: SKIP (0.130s)
[205.059767] Starting dynamic subtest: pipe-B
[206.213783] Dynamic subtest pipe-B: SKIP (1.164s)
[206.225339] Starting dynamic subtest: pipe-C
[207.429722] Dynamic subtest pipe-C: SUCCESS (1.221s)
[207.440869] Starting dynamic subtest: pipe-D
[208.664030] Dynamic subtest pipe-D: SUCCESS (1.234s)
[208.666478] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.751s)
[209.868322] [063/134] (809s left) syncobj_wait (wait-all-interrupted)
[209.930073] Starting subtest: wait-all-interrupted
[210.031507] Subtest wait-all-interrupted: SUCCESS (0.102s)
[210.121171] [064/134] (809s left) gem_exec_schedule (u-submit-early-slice)
[210.195593] Starting subtest: u-submit-early-slice
[210.197818] Starting dynamic subtest: rcs0
[210.230851] Dynamic subtest rcs0: SUCCESS (0.039s)
[210.240215] Starting dynamic subtest: bcs0
[210.266129] Dynamic subtest bcs0: SUCCESS (0.034s)
[210.274648] Starting dynamic subtest: vcs0
[210.300404] Dynamic subtest vcs0: SUCCESS (0.034s)
[210.308947] Starting dynamic subtest: vcs1
[210.335972] Dynamic subtest vcs1: SUCCESS (0.035s)
[210.344585] Starting dynamic subtest: vecs0
[210.365109] Dynamic subtest vecs0: SUCCESS (0.029s)
[210.373595] Starting dynamic subtest: ccs0
[210.401460] Dynamic subtest ccs0: SUCCESS (0.036s)
[210.403763] Subtest u-submit-early-slice: SUCCESS (0.210s)
[212.047270] [065/134] (807s left) kms_cursor_crc (cursor-sliding-32x32)
[212.190265] Starting subtest: cursor-sliding-32x32
[212.239316] Subtest cursor-sliding-32x32: SKIP (0.044s)
[212.523958] [066/134] (807s left) gem_exec_parallel (fds)
[212.578161] Starting subtest: fds
[212.580585] Starting dynamic subtest: rcs0
[215.369029] Dynamic subtest rcs0: SUCCESS (2.789s)
[215.378301] Starting dynamic subtest: bcs0
[218.515056] Dynamic subtest bcs0: SUCCESS (3.144s)
[218.529909] Starting dynamic subtest: vcs0
[222.206128] Dynamic subtest vcs0: SUCCESS (3.689s)
[222.217380] Starting dynamic subtest: vcs1
[226.153977] Dynamic subtest vcs1: SUCCESS (3.945s)
[226.165200] Starting dynamic subtest: vecs0
[230.173458] Dynamic subtest vecs0: SUCCESS (4.017s)
[230.182473] Starting dynamic subtest: ccs0
[233.608247] Dynamic subtest ccs0: SUCCESS (3.432s)
[233.611166] Subtest fds: SUCCESS (21.029s)
[233.815633] [067/134] (786s left) kms_sequence (queue-busy)
[233.916301] Starting subtest: queue-busy
[233.918852] Starting dynamic subtest: pipe-A-eDP-1
[235.844257] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s)
[235.854670] Starting dynamic subtest: pipe-B-eDP-1
[238.473235] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.629s)
[238.482848] Starting dynamic subtest: pipe-C-eDP-1
[241.083229] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.610s)
[241.092695] Starting dynamic subtest: pipe-D-eDP-1
[243.694257] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.609s)
[243.696671] Subtest queue-busy: SUCCESS (9.776s)
[244.506335] [068/134] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[244.614259] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[244.618428] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[244.756925] [069/134] (775s left) kms_big_fb (y-tiled-addfb-size-overflow)
[244.870130] Starting subtest: y-tiled-addfb-size-overflow
[244.874579] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[245.005666] [070/134] (774s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[245.115601] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[245.120059] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[245.258098] [071/134] (774s left) kms_colorop (plane-xr24-xr24-ctm_3x4_overdrive)
[245.392219] Subtest plane-XR24-XR24-ctm_3x4_overdrive: SKIP (0.000s)
[245.468102] [072/134] (774s left) gem_eio (in-flight-immediate)
[245.687873] Starting subtest: in-flight-immediate
[254.014872] Subtest in-flight-immediate: SUCCESS (8.325s)
[254.230227] [FACT gem_eio (in-flight-immediate)] new: kernel.kmod_is_loaded.vgem: true
[254.232951] [073/134] (765s left) i915_drm_fdinfo (virtual-busy-idle-all)
[254.271336] Starting subtest: virtual-busy-idle-all
[254.275740] Subtest virtual-busy-idle-all: SKIP (0.000s)
[254.348565] [074/134] (765s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[254.450526] Starting subtest: x-tiled-8bpp-rotate-180
[255.723311] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.272s)
[255.868930] [075/134] (764s left) gen3_render_mixed_blits
[255.987341] [076/134] (764s left) kms_invalid_mode (overflow-vrefresh)
[256.083305] Starting subtest: overflow-vrefresh
[256.085222] Starting dynamic subtest: pipe-A-eDP-1
[256.372016] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[256.381429] Starting dynamic subtest: pipe-B-eDP-1
[256.383406] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[256.391958] Starting dynamic subtest: pipe-C-eDP-1
[256.394579] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[256.405757] Starting dynamic subtest: pipe-D-eDP-1
[256.408101] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[256.410354] Subtest overflow-vrefresh: SUCCESS (0.295s)
[257.240190] [077/134] (763s left) gem_softpin (allocator-nopin-reserve)
[257.296077] Starting subtest: allocator-nopin-reserve
[257.374739] Subtest allocator-nopin-reserve: SUCCESS (0.078s)
[257.534322] [078/134] (762s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[257.623295] Starting subtest: plane-downscale-factor-0-5-with-rotation
[257.625643] Starting dynamic subtest: pipe-A
[257.643590] Dynamic subtest pipe-A: SKIP (0.017s)
[257.653154] Starting dynamic subtest: pipe-B
[257.675830] Dynamic subtest pipe-B: SKIP (0.031s)
[257.686254] Starting dynamic subtest: pipe-C
[257.706704] Dynamic subtest pipe-C: SKIP (0.031s)
[257.715663] Starting dynamic subtest: pipe-D
[257.738596] Dynamic subtest pipe-D: SKIP (0.031s)
[257.742339] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.112s)
[257.860994] [079/134] (762s left) gem_exec_params (invalid-fence-in-submit)
[257.922519] Starting subtest: invalid-fence-in-submit
[257.927643] Subtest invalid-fence-in-submit: SUCCESS (0.005s)
[258.072516] [080/134] (762s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[263.121386] Starting subtest: pr-overlay-plane-update-continuous-sf
[263.126259] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.003s)
[263.968202] [081/134] (756s left) kms_flip (2x-plain-flip-interruptible)
[264.068099] Starting subtest: 2x-plain-flip-interruptible
[264.081682] Subtest 2x-plain-flip-interruptible: SKIP (0.012s)
[264.218978] [082/134] (756s left) kms_async_flips (async-flip-dpms)
[264.316675] Starting subtest: async-flip-dpms
[264.688596] Starting dynamic subtest: pipe-A-eDP-1
[265.928084] Dynamic subtest pipe-A-eDP-1: FAIL (0.760s)
[265.938872] Starting dynamic subtest: pipe-B-eDP-1
[266.841371] Dynamic subtest pipe-B-eDP-1: FAIL (0.744s)
[266.946982] Starting dynamic subtest: pipe-C-eDP-1
[270.849912] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.910s)
[271.226404] Starting dynamic subtest: pipe-D-eDP-1
[274.022592] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.804s)
[274.025041] Subtest async-flip-dpms: FAIL (9.707s)
[275.218849] [083/134] (745s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[275.270670] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[275.376097] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[275.466276] [084/134] (745s left) kms_big_fb (linear-32bpp-rotate-270)
[275.566255] Starting subtest: linear-32bpp-rotate-270
[275.586084] Subtest linear-32bpp-rotate-270: SKIP (0.019s)
[275.733393] [085/134] (744s left) gem_exec_reloc (basic-write-read-active)
[275.788459] Subtest basic-write-read-active: SKIP (0.000s)
[275.859679] [086/134] (744s left) gem_exec_schedule (preempt-queue-contexts-chain)
[275.937544] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[276.238242] [087/134] (744s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[276.335915] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[276.340330] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[276.468932] [088/134] (744s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[276.567257] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[276.571436] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[276.679133] [089/134] (744s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[276.782291] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[276.786604] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[276.898973] [090/134] (743s left) kms_setmode (invalid-clone-single-crtc-stealing)
[276.964558] Starting subtest: invalid-clone-single-crtc-stealing
[276.972326] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s)
[277.121270] [091/134] (743s left) kms_getfb (getfb2-handle-not-fb)
[277.218780] Starting subtest: getfb2-handle-not-fb
[277.220617] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[277.353367] [092/134] (743s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[277.439298] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[277.451611] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[277.542245] [093/134] (743s left) kms_chamelium_frames (hdmi-crc-fast)
[277.920491] Subtest hdmi-crc-fast: SKIP (0.000s)
[278.711811] [094/134] (742s left) kms_psr (fbc-psr2-cursor-blt)
[278.811256] Starting subtest: fbc-psr2-cursor-blt
[278.813176] Starting dynamic subtest: eDP-1
[278.817095] Dynamic subtest eDP-1: SKIP (0.000s)
[278.821113] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[278.909457] [095/134] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[278.999976] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[279.003997] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[279.138930] [096/134] (741s left) kms_draw_crc (draw-method-blt)
[279.222984] Starting subtest: draw-method-blt
[279.224923] Starting dynamic subtest: xrgb8888-untiled
[279.487871] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[279.497570] Starting dynamic subtest: rgb565-untiled
[279.722596] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[279.731275] Starting dynamic subtest: xrgb2101010-untiled
[279.943324] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[279.955140] Starting dynamic subtest: xbgr16161616f-untiled
[280.176329] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.230s)
[280.187967] Starting dynamic subtest: xrgb8888-xtiled
[280.287665] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.110s)
[280.296536] Starting dynamic subtest: rgb565-xtiled
[280.387629] Dynamic subtest rgb565-xtiled: SUCCESS (0.099s)
[280.396925] Starting dynamic subtest: xrgb2101010-xtiled
[280.500104] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[280.511078] Starting dynamic subtest: xbgr16161616f-xtiled
[280.598798] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.099s)
[280.609045] Starting dynamic subtest: xrgb8888-4tiled
[280.720957] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[280.730274] Starting dynamic subtest: rgb565-4tiled
[280.821021] Dynamic subtest rgb565-4tiled: SUCCESS (0.099s)
[280.829584] Starting dynamic subtest: xrgb2101010-4tiled
[280.932810] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[280.943736] Starting dynamic subtest: xbgr16161616f-4tiled
[281.053979] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[281.055970] Subtest draw-method-blt: SUCCESS (1.833s)
[281.207032] [097/134] (739s left) kms_psr (fbc-pr-cursor-blt)
[281.309544] Starting subtest: fbc-pr-cursor-blt
[281.313138] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[281.394117] [098/134] (739s left) sw_sync (sync_multi_timeline_wait)
[281.428563] Starting subtest: sync_multi_timeline_wait
[281.430309] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[281.504279] [099/134] (739s left) gem_create (create-ext-placement-sanity-check)
[281.661550] Starting subtest: create-ext-placement-sanity-check
[281.663930] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[281.790707] [100/134] (739s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[281.852755] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[281.855289] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[281.955091] [101/134] (739s left) gem_exec_fence (syncobj-channel)
[282.384995] Starting subtest: syncobj-channel
[283.406917] Subtest syncobj-channel: SUCCESS (1.019s)
[284.038385] [102/134] (737s left) syncobj_basic (bad-pad-fd-to-handle)
[284.098563] Starting subtest: bad-pad-fd-to-handle
[284.100736] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[284.217880] [103/134] (737s left) gem_eio (kms)
[284.490101] Starting subtest: kms
[305.102738] Subtest kms: SUCCESS (20.612s)
[306.283648] [104/134] (715s left) kms_plane_cursor (viewport)
[306.382361] Starting subtest: viewport
[306.386885] Starting dynamic subtest: pipe-A-eDP-1-size-64
[309.243844] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.861s)
[309.305130] Starting dynamic subtest: pipe-A-eDP-1-size-128
[312.188446] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.892s)
[312.249153] Starting dynamic subtest: pipe-A-eDP-1-size-256
[315.165995] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.926s)
[315.515833] Starting dynamic subtest: pipe-B-eDP-1-size-64
[318.980518] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.473s)
[319.039380] Starting dynamic subtest: pipe-B-eDP-1-size-128
[321.925122] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.893s)
[321.985920] Starting dynamic subtest: pipe-B-eDP-1-size-256
[324.826860] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.848s)
[325.177945] Starting dynamic subtest: pipe-C-eDP-1-size-64
[328.384470] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.218s)
[328.423387] Starting dynamic subtest: pipe-C-eDP-1-size-128
[330.951437] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.536s)
[330.989507] Starting dynamic subtest: pipe-C-eDP-1-size-256
[333.540135] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.557s)
[333.872845] Starting dynamic subtest: pipe-D-eDP-1-size-64
[337.088603] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.228s)
[337.126798] Starting dynamic subtest: pipe-D-eDP-1-size-128
[339.633129] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.513s)
[339.670538] Starting dynamic subtest: pipe-D-eDP-1-size-256
[342.189136] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.524s)
[342.514770] Subtest viewport: SUCCESS (36.132s)
[343.377977] [105/134] (678s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[343.439566] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[343.442061] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[343.568465] [106/134] (677s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[343.664455] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[343.668856] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[343.789105] [107/134] (677s left) kms_lease (master-vs-lease)
[343.891200] Starting subtest: master-vs-lease
[343.893528] Subtest master-vs-lease: SUCCESS (0.002s)
[344.021119] [108/134] (677s left) gem_ctx_isolation (preservation)
[344.084706] Starting subtest: preservation
[344.086686] Starting dynamic subtest: rcs0
[344.214600] Dynamic subtest rcs0: SUCCESS (0.132s)
[344.226524] Starting dynamic subtest: bcs0
[344.388697] Dynamic subtest bcs0: SUCCESS (0.174s)
[344.400796] Starting dynamic subtest: vcs0
[344.560256] Dynamic subtest vcs0: SUCCESS (0.171s)
[344.570040] Starting dynamic subtest: vcs1
[346.011931] Dynamic subtest vcs1: SUCCESS (1.451s)
[346.021176] Starting dynamic subtest: vecs0
[348.058949] Dynamic subtest vecs0: SUCCESS (2.046s)
[348.075354] Starting dynamic subtest: ccs0
[350.035063] Dynamic subtest ccs0: SUCCESS (1.974s)
[350.038082] Subtest preservation: SUCCESS (5.953s)
[350.303698] [109/134] (671s left) gem_ringfill (basic-all)
[355.926589] Starting subtest: basic-all
[357.078557] Subtest basic-all: SUCCESS (1.150s)
[357.242874] [110/134] (664s left) gem_exec_schedule (pi-distinct-iova)
[357.429235] Starting subtest: pi-distinct-iova
[357.431282] Starting dynamic subtest: rcs0
[357.562756] Dynamic subtest rcs0: SUCCESS (0.136s)
[357.579418] Starting dynamic subtest: vcs0
[357.644948] Dynamic subtest vcs0: SUCCESS (0.081s)
[357.657723] Starting dynamic subtest: vcs1
[357.724707] Dynamic subtest vcs1: SUCCESS (0.077s)
[357.737661] Starting dynamic subtest: vecs0
[357.812364] Dynamic subtest vecs0: SUCCESS (0.085s)
[357.824757] Starting dynamic subtest: ccs0
[357.924976] Dynamic subtest ccs0: SUCCESS (0.110s)
[357.927636] Subtest pi-distinct-iova: SUCCESS (0.501s)
[359.065303] [111/134] (662s left) syncobj_timeline (wait-all-delayed-signal)
[359.121934] Starting subtest: wait-all-delayed-signal
[359.222820] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[359.316119] [112/134] (662s left) gen9_exec_parse (bb-chained)
[359.383129] Subtest bb-chained: SKIP (0.000s)
[359.466988] [113/134] (662s left) kms_psr (pr-sprite-mmap-gtt)
[359.577166] Starting subtest: pr-sprite-mmap-gtt
[359.581443] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[359.664080] [114/134] (662s left) gem_eio (in-flight-internal-immediate)
[359.883974] Starting subtest: in-flight-internal-immediate
[360.995993] Subtest in-flight-internal-immediate: SUCCESS (1.111s)
[361.172692] [115/134] (660s left) kms_vblank (query-idle-hang)
[361.263137] Starting subtest: query-idle-hang
[361.264984] Starting dynamic subtest: pipe-A-eDP-1
[363.064079] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.801s)
[363.080824] Starting dynamic subtest: pipe-D-eDP-1
[365.549196] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.481s)
[365.551557] Subtest query-idle-hang: SUCCESS (4.286s)
[366.350633] [116/134] (655s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[371.354600] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[371.359728] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[372.204011] [117/134] (649s left) kms_frontbuffer_tracking (fbc-1p-rte)
[372.296833] Starting subtest: fbc-1p-rte
[377.775212] Subtest fbc-1p-rte: SUCCESS (5.483s)
[379.003990] [118/134] (642s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[379.101282] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[379.103625] Starting dynamic subtest: pipe-A-eDP-1
[379.126986] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[379.138690] Starting dynamic subtest: pipe-B-eDP-1
[379.160778] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[379.171967] Starting dynamic subtest: pipe-C-eDP-1
[379.192742] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[379.201658] Starting dynamic subtest: pipe-D-eDP-1
[379.223375] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[379.227317] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.004s)
[381.279659] [119/134] (640s left) prime_mmap (test_userptr)
[381.338330] Starting subtest: test_userptr
[381.340585] Starting dynamic subtest: test_userptr-smem
[381.342783] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[381.344624] Subtest test_userptr: SUCCESS (0.002s)
[381.449975] [120/134] (640s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[381.554638] Starting subtest: 4-tiled-32bpp-rotate-180
[382.955924] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.401s)
[383.113225] [121/134] (638s left) gem_exec_balancer (bonded-chain)
[383.168586] Starting subtest: bonded-chain
[390.144782] Subtest bonded-chain: SUCCESS (6.973s)
[390.516846] [122/134] (631s left) kms_color (ctm-blue-to-red)
[390.611798] Starting subtest: ctm-blue-to-red
[390.613701] Starting dynamic subtest: pipe-A-eDP-1
[391.320786] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[391.330798] Starting dynamic subtest: pipe-B-eDP-1
[392.758746] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.436s)
[392.769334] Starting dynamic subtest: pipe-C-eDP-1
[394.134227] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[394.144948] Starting dynamic subtest: pipe-D-eDP-1
[395.440324] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.304s)
[395.442333] Subtest ctm-blue-to-red: SUCCESS (4.828s)
[396.300544] [123/134] (625s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[396.397348] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[396.399427] Starting dynamic subtest: pipe-A-eDP-1
[396.817533] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[396.826955] Starting dynamic subtest: pipe-B-eDP-1
[396.846644] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.005s)
[396.856071] Starting dynamic subtest: pipe-C-eDP-1
[396.875825] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.005s)
[396.885435] Starting dynamic subtest: pipe-D-eDP-1
[396.904143] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[396.906250] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.442s)
[399.162999] [124/134] (623s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[399.254192] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[406.296484] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.044s)
[406.466873] [125/134] (615s left) gem_exec_gttfill (basic)
[406.540634] Starting subtest: basic
[409.875459] Subtest basic: SUCCESS (3.336s)
[410.085888] [126/134] (612s left) perf_pmu (enable-race)
[410.170595] Starting subtest: enable-race
[410.173066] Starting dynamic subtest: rcs0
[422.954914] Dynamic subtest rcs0: SUCCESS (12.784s)
[422.964416] Starting dynamic subtest: bcs0
[435.699088] Dynamic subtest bcs0: SUCCESS (12.741s)
[435.710828] Starting dynamic subtest: vcs0
[450.899047] Dynamic subtest vcs0: SUCCESS (15.196s)
[450.911738] Starting dynamic subtest: vcs1
[466.899281] Dynamic subtest vcs1: SUCCESS (15.997s)
[466.919159] Starting dynamic subtest: vecs0
[482.899862] Dynamic subtest vecs0: SUCCESS (15.997s)
[482.911556] Starting dynamic subtest: ccs0
[495.662630] Dynamic subtest ccs0: SUCCESS (12.760s)
[495.665094] Subtest enable-race: SUCCESS (85.489s)
[495.806417] [127/134] (526s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[495.920622] Starting subtest: psr-rgb565-draw-mmap-gtt
[495.924772] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[496.027446] [128/134] (526s left) kms_addfb_basic (too-high)
[496.120634] Starting subtest: too-high
[496.122649] Subtest too-high: SUCCESS (0.000s)
[496.244226] [129/134] (526s left) kms_pm_rpm (fences)
[498.098967] Starting subtest: fences
[498.103950] Subtest fences: SKIP (0.000s)
[498.538619] [130/134] (523s left) gem_mmap (pf-nonblock)
[498.593388] Subtest pf-nonblock: SKIP (0.000s)
[498.659524] [131/134] (523s left) i915_drm_fdinfo (memory-info-idle)
[498.699573] Starting subtest: memory-info-idle
[498.701591] Starting dynamic subtest: smem0
[498.771728] Dynamic subtest smem0: SUCCESS (0.074s)
[498.774261] Subtest memory-info-idle: SUCCESS (0.075s)
[498.859013] [132/134] (523s left) gem_pxp (hw-rejects-pxp-buffer)
[498.980907] Starting subtest: hw-rejects-pxp-buffer
[498.985186] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[499.109411] [133/134] (523s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[499.211698] Starting subtest: fbc-rgb101010-draw-mmap-wc
[503.813544] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.605s)
[505.041069] [134/134] (517s left) kms_pm_backlight (fade-with-dpms)
[505.130605] Starting subtest: fade-with-dpms
[507.390153] Starting dynamic subtest: eDP-1
[510.094927] Dynamic subtest eDP-1: SUCCESS (2.703s)
[510.097432] Subtest fade-with-dpms: SUCCESS (4.964s)
[510.982635] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: gem_exec_reloc
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: i915_query
results: parsing output: 6/ for test: core_hotunplug
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: sysfs_timeslice_duration
results: parsing output: 10/ for test: gem_gpgpu_fill
results: parsing output: 11/ for test: gem_readwrite
results: parsing output: 12/ for test: kms_flip
results: parsing output: 13/ for test: gem_mmap_gtt
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_pipe_b_c_ivb
results: parsing output: 16/ for test: kms_chamelium_color
results: parsing output: 17/ for test: perf_pmu
results: parsing output: 18/ for test: gem_reg_read
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_atomic
results: parsing output: 21/ for test: gem_userptr_blits
results: parsing output: 22/ for test: prime_mmap
results: parsing output: 23/ for test: gem_flink_basic
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: gem_exec_balancer
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_setmode
results: parsing output: 29/ for test: kms_vrr
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: gem_mmap
results: parsing output: 32/ for test: syncobj_basic
results: parsing output: 33/ for test: api_intel_allocator
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: syncobj_eventfd
results: parsing output: 36/ for test: kms_cursor_edge_walk
results: parsing output: 37/ for test: gem_exec_reloc
results: parsing output: 38/ for test: kms_colorop
results: parsing output: 39/ for test: gem_userptr_blits
results: parsing output: 40/ for test: gem_partial_pwrite_pread
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: api_intel_bb
results: parsing output: 43/ for test: kms_colorop
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: perf
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_mmap_offset
results: parsing output: 51/ for test: kms_cursor_crc
results: parsing output: 52/ for test: gem_render_copy_redux
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: dumb_buffer
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: perf
results: parsing output: 57/ for test: gem_ctx_persistence
results: parsing output: 58/ for test: kms_dsc
results: parsing output: 59/ for test: i915_pm_rps
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_plane_scaling
results: parsing output: 62/ for test: syncobj_wait
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: gem_exec_parallel
results: parsing output: 66/ for test: kms_sequence
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_colorop
results: parsing output: 71/ for test: gem_eio
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: gen3_render_mixed_blits
results: parsing output: 75/ for test: kms_invalid_mode
results: parsing output: 76/ for test: gem_softpin
results: parsing output: 77/ for test: kms_plane_scaling
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_psr2_sf
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_async_flips
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_setmode
results: parsing output: 90/ for test: kms_getfb
results: parsing output: 91/ for test: kms_flip
results: parsing output: 92/ for test: kms_chamelium_frames
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_draw_crc
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: sw_sync
results: parsing output: 98/ for test: gem_create
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: gem_exec_fence
results: parsing output: 101/ for test: syncobj_basic
results: parsing output: 102/ for test: gem_eio
results: parsing output: 103/ for test: kms_plane_cursor
results: parsing output: 104/ for test: syncobj_wait
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_lease
results: parsing output: 107/ for test: gem_ctx_isolation
results: parsing output: 108/ for test: gem_ringfill
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gen9_exec_parse
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_eio
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: kms_psr2_sf
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: prime_mmap
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: gem_exec_balancer
results: parsing output: 121/ for test: kms_color
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_gttfill
results: parsing output: 125/ for test: perf_pmu
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_addfb_basic
results: parsing output: 128/ for test: kms_pm_rpm
results: parsing output: 129/ for test: gem_mmap
results: parsing output: 130/ for test: i915_drm_fdinfo
results: parsing output: 131/ for test: gem_pxp
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_pm_backlight
Done.