[55.604159] Initializing watchdogs
[55.604226]   /dev/watchdog0
[55.619240] [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]
[55.624228] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.626263] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.628216] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-128x42)
[55.845402] Starting subtest: cursor-offscreen-128x42
[55.890302] Subtest cursor-offscreen-128x42: SKIP (0.043s)
[56.114341] [002/134] (960s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[56.214458] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[57.226148] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.021s)
[57.355837] [003/134] (958s left) gem_exec_reloc (basic-write-gtt-active)
[57.439182] Subtest basic-write-gtt-active: SKIP (0.000s)
[57.497429] [004/134] (958s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[57.583984] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[57.584882] Starting dynamic subtest: pipe-A-eDP-1
[57.593371] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.599208] Starting dynamic subtest: pipe-B-eDP-1
[57.608596] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.614056] Starting dynamic subtest: pipe-C-eDP-1
[57.622888] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.628187] Starting dynamic subtest: pipe-D-eDP-1
[57.636462] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.638079] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s)
[59.815600] [005/134] (956s left) kms_psr (psr2-cursor-blt)
[59.904625] Starting subtest: psr2-cursor-blt
[59.905768] Starting dynamic subtest: eDP-1
[61.582047] Dynamic subtest eDP-1: SUCCESS (1.685s)
[61.582958] Subtest psr2-cursor-blt: SUCCESS (1.686s)
[62.374725] [006/134] (953s left) i915_query (engine-info-invalid)
[62.421361] Starting subtest: engine-info-invalid
[62.422354] Subtest engine-info-invalid: SUCCESS (0.000s)
[62.543864] [007/134] (953s left) core_hotunplug (unbind-rebind)
[62.617589] Starting subtest: unbind-rebind
[67.048588] Subtest unbind-rebind: SUCCESS (4.443s)
[67.077237] [008/134] (949s left) kms_psr (psr-primary-render)
[68.900830] Starting subtest: psr-primary-render
[68.901705] Starting dynamic subtest: eDP-1
[70.662079] Dynamic subtest eDP-1: SUCCESS (1.763s)
[70.663203] Subtest psr-primary-render: SUCCESS (1.764s)
[71.549844] [009/134] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[71.769083] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[71.771065] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[71.960764] [010/134] (944s left) sysfs_timeslice_duration (idempotent)
[72.126294] Starting subtest: idempotent
[72.127418] Starting dynamic subtest: vecs0
[72.128410] Dynamic subtest vecs0: SUCCESS (0.000s)
[72.134969] Starting dynamic subtest: ccs0
[72.135919] Dynamic subtest ccs0: SUCCESS (0.000s)
[72.141902] Starting dynamic subtest: vcs0
[72.142852] Dynamic subtest vcs0: SUCCESS (0.000s)
[72.148702] Starting dynamic subtest: bcs0
[72.149632] Dynamic subtest bcs0: SUCCESS (0.000s)
[72.155442] Starting dynamic subtest: rcs0
[72.156362] Dynamic subtest rcs0: SUCCESS (0.000s)
[72.162143] Starting dynamic subtest: vcs1
[72.163085] Dynamic subtest vcs1: SUCCESS (0.001s)
[72.164026] Subtest idempotent: SUCCESS (0.006s)
[72.306173] [011/134] (944s left) gem_gpgpu_fill (offset-16x16)
[72.370455] Starting subtest: offset-16x16
[72.378184] Subtest offset-16x16: SUCCESS (0.008s)
[72.549457] [012/134] (943s left) gem_readwrite (read-bad-handle)
[72.602624] Subtest read-bad-handle: SKIP (0.000s)
[72.657193] [013/134] (943s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[72.737057] Starting subtest: 2x-flip-vs-modeset-vs-hang
[72.747723] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.011s)
[72.830511] [014/134] (943s left) gem_mmap_gtt (fault-concurrent)
[72.877075] Subtest fault-concurrent: SKIP (0.000s)
[72.930889] [015/134] (943s left) syncobj_timeline (invalid-signal-bad-pad)
[72.972098] Starting subtest: invalid-signal-bad-pad
[72.973076] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[73.079871] [016/134] (943s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[73.132382] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[73.185388] [017/134] (943s left) kms_chamelium_color (ctm-green-to-red)
[73.265998] Subtest ctm-green-to-red: SKIP (0.000s)
[73.300834] [018/134] (943s left) perf_pmu (multi-client)
[73.333328] Starting subtest: multi-client
[73.334438] Starting dynamic subtest: rcs0
[74.387549] Dynamic subtest rcs0: SUCCESS (1.054s)
[74.398529] Starting dynamic subtest: bcs0
[75.438998] Dynamic subtest bcs0: SUCCESS (1.048s)
[75.447454] Starting dynamic subtest: vcs0
[76.489426] Dynamic subtest vcs0: SUCCESS (1.050s)
[76.499211] Starting dynamic subtest: vcs1
[77.902861] Dynamic subtest vcs1: SUCCESS (1.413s)
[77.911410] Starting dynamic subtest: vecs0
[79.948849] Dynamic subtest vecs0: SUCCESS (2.046s)
[79.957409] Starting dynamic subtest: ccs0
[81.934671] Dynamic subtest ccs0: SUCCESS (1.986s)
[81.936402] Subtest multi-client: SUCCESS (8.604s)
[82.078621] [019/134] (934s left) gem_reg_read (timestamp-monotonic)
[82.134365] Starting subtest: timestamp-monotonic
[86.323641] Subtest timestamp-monotonic: SUCCESS (4.190s)
[86.411606] [020/134] (930s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[86.510984] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[86.512823] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[86.612883] [021/134] (930s left) kms_atomic (atomic-invalid-params)
[86.704863] Starting subtest: atomic-invalid-params
[86.705734] Starting dynamic subtest: pipe-A-eDP-1
[87.107619] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.402s)
[87.108854] Subtest atomic-invalid-params: SUCCESS (0.403s)
[87.956874] [022/134] (928s left) gem_userptr_blits (userfault)
[88.014838] Starting subtest: userfault
[88.018205] Subtest userfault: SUCCESS (0.006s)
[88.153896] [023/134] (928s left) prime_mmap (test_forked_cpu_write)
[88.208044] Starting subtest: test_forked_cpu_write
[88.208932] Starting dynamic subtest: test_forked_cpu_write-smem
[88.219293] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.011s)
[88.220202] Subtest test_forked_cpu_write: SUCCESS (0.012s)
[88.338136] [024/134] (928s left) gem_flink_basic (basic)
[88.376991] Starting subtest: basic
[88.378151] Subtest basic: SUCCESS (0.000s)
[88.459504] [025/134] (928s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[88.552162] Starting subtest: fbcpsr-rgb565-draw-pwrite
[95.469206] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (6.920s)
[95.641853] [026/134] (921s left) kms_psr (fbc-psr2-suspend)
[95.731639] Starting subtest: fbc-psr2-suspend
[95.732700] Starting dynamic subtest: eDP-1
[95.734594] Dynamic subtest eDP-1: SKIP (0.000s)
[95.736376] Subtest fbc-psr2-suspend: SKIP (0.000s)
[95.805903] [027/134] (921s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[96.062342] Starting subtest: parallel-dmabuf-import-out-fence
[97.135862] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.074s)
[97.285110] [028/134] (919s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[97.375642] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[104.707953] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.335s)
[104.834858] [029/134] (912s left) kms_setmode (clone-exclusive-crtc)
[104.884998] Starting subtest: clone-exclusive-crtc
[104.887774] Subtest clone-exclusive-crtc: SKIP (0.001s)
[104.988689] [030/134] (912s left) kms_vrr (flip-basic)
[105.064322] Starting subtest: flip-basic
[105.066964] Subtest flip-basic: SKIP (0.000s)
[105.151901] [031/134] (911s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[105.251296] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[105.252263] Starting dynamic subtest: pipe-A-default-mode
[105.537536] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[105.539312] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.286s)
[106.340964] [032/134] (910s left) gem_mmap (bad-offset)
[106.389528] Subtest bad-offset: SKIP (0.000s)
[106.439936] [033/134] (910s left) syncobj_basic (bad-destroy)
[106.486596] Starting subtest: bad-destroy
[106.487749] Subtest bad-destroy: SUCCESS (0.000s)
[106.608983] [034/134] (910s left) api_intel_allocator (simple-allocator)
[106.657704] Starting subtest: simple-allocator
[106.658969] Starting dynamic subtest: basic
[106.660185] Dynamic subtest basic: SUCCESS (0.003s)
[106.671300] Starting dynamic subtest: parallel-one
[106.828065] Dynamic subtest parallel-one: SUCCESS (0.167s)
[106.835528] Starting dynamic subtest: print
[106.836572] Dynamic subtest print: SUCCESS (0.000s)
[106.843093] Starting dynamic subtest: reuse
[106.844217] Dynamic subtest reuse: SUCCESS (0.000s)
[106.850865] Starting dynamic subtest: reserve
[106.851834] Dynamic subtest reserve: SUCCESS (0.000s)
[106.858160] Starting dynamic subtest: fork-reopen-allocator
[106.859051] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[106.859924] Subtest simple-allocator: SUCCESS (0.187s)
[106.982383] [035/134] (910s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[107.069059] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[107.070494] Starting dynamic subtest: pipe-A-default-mode
[107.366453] Dynamic subtest pipe-A-default-mode: SKIP (0.296s)
[107.368255] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.297s)
[108.172324] [036/134] (909s left) syncobj_eventfd (invalid-illegal-handle)
[108.226063] Starting subtest: invalid-illegal-handle
[108.227114] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[108.345891] [037/134] (908s left) kms_cursor_edge_walk (64x64-top-edge)
[108.437481] Starting subtest: 64x64-top-edge
[108.438432] Starting dynamic subtest: pipe-A-eDP-1
[111.935060] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.497s)
[111.942137] Starting dynamic subtest: pipe-D-eDP-1
[115.055119] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.119s)
[115.056493] Subtest 64x64-top-edge: SUCCESS (6.619s)
[115.898284] [038/134] (901s left) gem_exec_reloc (basic-wc-cpu)
[115.963453] Subtest basic-wc-cpu: SKIP (0.000s)
[116.022855] [039/134] (901s left) kms_colorop (plane-xr30-xr30-srgb_eotf)
[116.146727] Subtest plane-XR30-XR30-srgb_eotf: SKIP (0.000s)
[116.233053] [040/134] (901s left) gem_userptr_blits (stress-mm-invalidate-close)
[116.291277] Starting subtest: stress-mm-invalidate-close
[118.439599] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[118.596614] [041/134] (898s left) gem_partial_pwrite_pread (reads-uncached)
[118.650596] Subtest reads-uncached: SKIP (0.000s)
[118.736480] [042/134] (898s left) kms_flip (flip-vs-expired-vblank-interruptible)
[118.832075] Starting subtest: flip-vs-expired-vblank-interruptible
[118.834290] Starting dynamic subtest: A-eDP1
[127.494059] Dynamic subtest A-eDP1: SUCCESS (8.660s)
[127.507284] Starting dynamic subtest: B-eDP1
[136.120570] Dynamic subtest B-eDP1: SUCCESS (8.627s)
[136.129623] Starting dynamic subtest: C-eDP1
[144.736794] Dynamic subtest C-eDP1: SUCCESS (8.616s)
[144.744888] Starting dynamic subtest: D-eDP1
[153.361780] Dynamic subtest D-eDP1: SUCCESS (8.620s)
[153.364314] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.532s)
[154.538008] [043/134] (862s left) api_intel_bb (object-noreloc-keep-cache-simple)
[154.593601] Starting subtest: object-noreloc-keep-cache-simple
[154.597905] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.005s)
[154.736242] [044/134] (862s left) kms_colorop (plane-xr24-xr24-gamma_2_2_inv_oetf)
[154.860594] Subtest plane-XR24-XR24-gamma_2_2_inv_oetf: SKIP (0.000s)
[154.948271] [045/134] (862s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[155.039418] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[155.041296] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[155.157199] [046/134] (862s left) perf (polling)
[155.278892] Starting subtest: polling
[155.279771] Starting dynamic subtest: 0-rcs0
[165.291676] Dynamic subtest 0-rcs0: SUCCESS (10.008s)
[165.299167] Starting dynamic subtest: 1-vecs0
[175.319096] Dynamic subtest 1-vecs0: SUCCESS (10.023s)
[175.320600] Subtest polling: SUCCESS (20.032s)
[175.356796] [047/134] (842s left) syncobj_wait (wait-all-snapshot)
[175.410707] Starting subtest: wait-all-snapshot
[175.492987] Subtest wait-all-snapshot: SUCCESS (0.081s)
[175.585913] [048/134] (841s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[175.678425] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[175.679391] Starting dynamic subtest: pipe-A-eDP-1
[175.714042] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[175.721803] Starting dynamic subtest: pipe-B-eDP-1
[175.743077] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[175.749757] Starting dynamic subtest: pipe-C-eDP-1
[175.770568] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[175.776944] Starting dynamic subtest: pipe-D-eDP-1
[175.798248] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[175.799868] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[177.929341] [049/134] (839s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[178.027955] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[178.029884] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[178.137904] [050/134] (839s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[178.229598] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[184.912591] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.683s)
[185.097049] [051/134] (832s left) gem_mmap_offset (partial-remap)
[185.152501] Starting subtest: partial-remap
[185.153524] Starting dynamic subtest: smem0
[185.160957] Dynamic subtest smem0: SUCCESS (0.007s)
[185.161889] Subtest partial-remap: SUCCESS (0.008s)
[185.320613] [052/134] (832s left) kms_cursor_crc (cursor-random-128x128)
[185.526711] Starting subtest: cursor-random-128x128
[185.530491] Starting dynamic subtest: pipe-A-eDP-1
[188.850288] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.319s)
[188.876669] Starting dynamic subtest: pipe-D-eDP-1
[192.813349] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.940s)
[192.814391] Subtest cursor-random-128x128: SUCCESS (7.284s)
[193.668845] [053/134] (824s left) gem_render_copy_redux (interruptible)
[193.726014] Starting subtest: interruptible
[193.987800] Subtest interruptible: SUCCESS (0.262s)
[194.119072] [054/134] (823s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[194.216633] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[194.218454] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[194.313844] [055/134] (823s left) dumb_buffer (map-uaf)
[194.369852] Starting subtest: map-uaf
[194.375092] Subtest map-uaf: SUCCESS (0.005s)
[194.510200] [056/134] (823s left) kms_flip (2x-flip-vs-panning)
[194.597989] Starting subtest: 2x-flip-vs-panning
[194.609022] Subtest 2x-flip-vs-panning: SKIP (0.011s)
[194.733136] [057/134] (823s left) perf (create-destroy-userspace-config)
[194.819995] Starting subtest: create-destroy-userspace-config
[194.839124] Subtest create-destroy-userspace-config: SUCCESS (0.019s)
[194.877158] [058/134] (823s left) gem_ctx_persistence (heartbeat-hang)
[194.928251] Starting subtest: heartbeat-hang
[194.930148] Subtest heartbeat-hang: SKIP (0.000s)
[195.039463] [059/134] (822s left) kms_dsc (dsc-with-output-formats)
[195.126672] Starting subtest: dsc-with-output-formats
[195.134899] Subtest dsc-with-output-formats: SKIP (0.001s)
[195.202699] [060/134] (822s left) i915_pm_rps (engine-order)
[195.263273] Starting subtest: engine-order
[201.339051] Subtest engine-order: SUCCESS (6.076s)
[201.466786] [061/134] (816s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[201.516837] Starting subtest: multi-wait-for-submit-submitted-signaled
[201.517924] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[201.640172] [062/134] (816s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[201.744095] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[201.745072] Starting dynamic subtest: pipe-A
[201.852925] Dynamic subtest pipe-A: SKIP (0.106s)
[201.860051] Starting dynamic subtest: pipe-B
[203.033459] Dynamic subtest pipe-B: SKIP (1.180s)
[203.040703] Starting dynamic subtest: pipe-C
[204.181967] Dynamic subtest pipe-C: SUCCESS (1.150s)
[204.190039] Starting dynamic subtest: pipe-D
[205.333884] Dynamic subtest pipe-D: SUCCESS (1.151s)
[205.334842] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.591s)
[206.529940] [063/134] (811s left) syncobj_wait (wait-all-interrupted)
[206.585051] Starting subtest: wait-all-interrupted
[206.689651] Subtest wait-all-interrupted: SUCCESS (0.102s)
[206.766152] [064/134] (811s left) gem_exec_schedule (u-submit-early-slice)
[206.824461] Starting subtest: u-submit-early-slice
[206.825381] Starting dynamic subtest: rcs0
[206.865616] Dynamic subtest rcs0: SUCCESS (0.041s)
[206.875436] Starting dynamic subtest: bcs0
[206.897832] Dynamic subtest bcs0: SUCCESS (0.032s)
[206.905070] Starting dynamic subtest: vcs0
[206.931874] Dynamic subtest vcs0: SUCCESS (0.033s)
[206.938791] Starting dynamic subtest: vcs1
[206.964614] Dynamic subtest vcs1: SUCCESS (0.032s)
[206.971531] Starting dynamic subtest: vecs0
[206.996496] Dynamic subtest vecs0: SUCCESS (0.031s)
[207.006964] Starting dynamic subtest: ccs0
[207.036143] Dynamic subtest ccs0: SUCCESS (0.038s)
[207.037203] Subtest u-submit-early-slice: SUCCESS (0.212s)
[209.047508] [065/134] (809s left) kms_cursor_crc (cursor-sliding-32x32)
[209.180358] Starting subtest: cursor-sliding-32x32
[209.221613] Subtest cursor-sliding-32x32: SKIP (0.039s)
[209.488680] [066/134] (808s left) gem_exec_parallel (fds)
[209.543020] Starting subtest: fds
[209.544141] Starting dynamic subtest: rcs0
[212.290900] Dynamic subtest rcs0: SUCCESS (2.744s)
[212.300795] Starting dynamic subtest: bcs0
[215.814035] Dynamic subtest bcs0: SUCCESS (3.521s)
[215.823878] Starting dynamic subtest: vcs0
[220.085712] Dynamic subtest vcs0: SUCCESS (4.270s)
[220.098670] Starting dynamic subtest: vcs1
[224.151911] Dynamic subtest vcs1: SUCCESS (4.064s)
[224.159601] Starting dynamic subtest: vecs0
[228.163440] Dynamic subtest vecs0: SUCCESS (4.009s)
[228.172320] Starting dynamic subtest: ccs0
[231.566986] Dynamic subtest ccs0: SUCCESS (3.401s)
[231.568503] Subtest fds: SUCCESS (22.022s)
[231.763382] [067/134] (786s left) kms_sequence (queue-busy)
[231.866189] Starting subtest: queue-busy
[231.867215] Starting dynamic subtest: pipe-A-eDP-1
[233.778494] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.912s)
[233.785901] Starting dynamic subtest: pipe-B-eDP-1
[236.402508] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.623s)
[236.409911] Starting dynamic subtest: pipe-C-eDP-1
[239.034659] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.630s)
[239.042152] Starting dynamic subtest: pipe-D-eDP-1
[241.675683] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.640s)
[241.676562] Subtest queue-busy: SUCCESS (9.810s)
[242.474836] [068/134] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[242.571539] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[242.573291] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[242.689866] [069/134] (775s left) kms_big_fb (y-tiled-addfb-size-overflow)
[242.780624] Starting subtest: y-tiled-addfb-size-overflow
[242.782399] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[242.900905] [070/134] (775s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[243.005036] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[243.006920] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[243.133747] [071/134] (775s left) kms_colorop (plane-xr24-xr24-ctm_3x4_overdrive)
[243.260928] Subtest plane-XR24-XR24-ctm_3x4_overdrive: SKIP (0.000s)
[243.347055] [072/134] (774s left) gem_eio (in-flight-immediate)
[243.580411] Starting subtest: in-flight-immediate
[251.968093] Subtest in-flight-immediate: SUCCESS (8.387s)
[252.152668] [FACT gem_eio (in-flight-immediate)] new: kernel.kmod_is_loaded.vgem: true
[252.154530] [073/134] (766s left) i915_drm_fdinfo (virtual-busy-idle-all)
[252.189164] Starting subtest: virtual-busy-idle-all
[252.191133] Subtest virtual-busy-idle-all: SKIP (0.000s)
[252.279556] [074/134] (766s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[252.394418] Starting subtest: x-tiled-8bpp-rotate-180
[253.529316] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.135s)
[253.659838] [075/134] (764s left) gen3_render_mixed_blits
[253.775012] [076/134] (764s left) kms_invalid_mode (overflow-vrefresh)
[253.865570] Starting subtest: overflow-vrefresh
[253.866508] Starting dynamic subtest: pipe-A-eDP-1
[254.149853] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[254.158859] Starting dynamic subtest: pipe-B-eDP-1
[254.159818] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[254.166851] Starting dynamic subtest: pipe-C-eDP-1
[254.167758] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[254.174813] Starting dynamic subtest: pipe-D-eDP-1
[254.175843] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[254.176780] Subtest overflow-vrefresh: SUCCESS (0.290s)
[255.006069] [077/134] (763s left) gem_softpin (allocator-nopin-reserve)
[255.057295] Starting subtest: allocator-nopin-reserve
[255.155444] Subtest allocator-nopin-reserve: SUCCESS (0.098s)
[255.293410] [078/134] (763s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[255.387292] Starting subtest: plane-downscale-factor-0-5-with-rotation
[255.388125] Starting dynamic subtest: pipe-A
[255.403914] Dynamic subtest pipe-A: SKIP (0.015s)
[255.411800] Starting dynamic subtest: pipe-B
[255.432595] Dynamic subtest pipe-B: SKIP (0.027s)
[255.440215] Starting dynamic subtest: pipe-C
[255.459302] Dynamic subtest pipe-C: SKIP (0.027s)
[255.466663] Starting dynamic subtest: pipe-D
[255.487102] Dynamic subtest pipe-D: SKIP (0.027s)
[255.488844] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.099s)
[255.597039] [079/134] (762s left) gem_exec_params (invalid-fence-in-submit)
[255.642221] Starting subtest: invalid-fence-in-submit
[255.645369] Subtest invalid-fence-in-submit: SUCCESS (0.004s)
[255.811049] [080/134] (762s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[260.760272] Starting subtest: pr-overlay-plane-update-continuous-sf
[260.763282] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.003s)
[261.631730] [081/134] (756s left) kms_flip (2x-plain-flip-interruptible)
[261.729940] Starting subtest: 2x-plain-flip-interruptible
[261.743927] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[261.865644] [082/134] (756s left) kms_async_flips (async-flip-dpms)
[261.954493] Starting subtest: async-flip-dpms
[262.341073] Starting dynamic subtest: pipe-A-eDP-1
[263.293847] Dynamic subtest pipe-A-eDP-1: FAIL (0.791s)
[263.487629] Starting dynamic subtest: pipe-B-eDP-1
[264.318910] Dynamic subtest pipe-B-eDP-1: FAIL (0.749s)
[264.632377] Starting dynamic subtest: pipe-C-eDP-1
[268.412958] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.795s)
[268.786367] Starting dynamic subtest: pipe-D-eDP-1
[271.578522] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.799s)
[271.579486] Subtest async-flip-dpms: FAIL (9.626s)
[272.766622] [083/134] (745s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[272.815687] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[272.923533] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[273.006214] [084/134] (745s left) kms_big_fb (linear-32bpp-rotate-270)
[273.092383] Starting subtest: linear-32bpp-rotate-270
[273.115095] Subtest linear-32bpp-rotate-270: SKIP (0.019s)
[273.260245] [085/134] (745s left) gem_exec_reloc (basic-write-read-active)
[273.313575] Subtest basic-write-read-active: SKIP (0.000s)
[273.395593] [086/134] (745s left) gem_exec_schedule (preempt-queue-contexts-chain)
[273.460607] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[273.760096] [087/134] (745s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[273.842554] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[273.844240] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[273.959391] [088/134] (744s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[274.047064] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[274.048726] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[274.170480] [089/134] (744s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[274.260875] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[274.262640] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[274.378502] [090/134] (744s left) kms_setmode (invalid-clone-single-crtc-stealing)
[274.425893] Starting subtest: invalid-clone-single-crtc-stealing
[274.428492] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s)
[274.544299] [091/134] (744s left) kms_getfb (getfb2-handle-not-fb)
[274.629406] Starting subtest: getfb2-handle-not-fb
[274.630364] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[274.735631] [092/134] (744s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[274.818059] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[274.828734] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[274.909067] [093/134] (744s left) kms_chamelium_frames (hdmi-crc-fast)
[275.309885] Subtest hdmi-crc-fast: SKIP (0.000s)
[276.093394] [094/134] (742s left) kms_psr (fbc-psr2-cursor-blt)
[276.187393] Starting subtest: fbc-psr2-cursor-blt
[276.188313] Starting dynamic subtest: eDP-1
[276.190031] Dynamic subtest eDP-1: SKIP (0.000s)
[276.191747] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[276.266235] [095/134] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[276.364684] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[276.366528] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[276.481993] [096/134] (742s left) kms_draw_crc (draw-method-blt)
[276.561746] Starting subtest: draw-method-blt
[276.562675] Starting dynamic subtest: xrgb8888-untiled
[276.772812] Dynamic subtest xrgb8888-untiled: SUCCESS (0.211s)
[276.780534] Starting dynamic subtest: rgb565-untiled
[276.983378] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[276.990984] Starting dynamic subtest: xrgb2101010-untiled
[277.172250] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.188s)
[277.180298] Starting dynamic subtest: xbgr16161616f-untiled
[277.383401] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[277.391455] Starting dynamic subtest: xrgb8888-xtiled
[277.494693] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.110s)
[277.504201] Starting dynamic subtest: rgb565-xtiled
[277.605719] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[277.614078] Starting dynamic subtest: xrgb2101010-xtiled
[277.716740] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[277.724588] Starting dynamic subtest: xbgr16161616f-xtiled
[277.816930] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.099s)
[277.824843] Starting dynamic subtest: xrgb8888-4tiled
[277.928121] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.110s)
[277.935922] Starting dynamic subtest: rgb565-4tiled
[278.038885] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[278.046415] Starting dynamic subtest: xrgb2101010-4tiled
[278.139097] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.099s)
[278.148875] Starting dynamic subtest: xbgr16161616f-4tiled
[278.250463] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[278.251597] Subtest draw-method-blt: SUCCESS (1.690s)
[278.387102] [097/134] (740s left) kms_psr (fbc-pr-cursor-blt)
[278.479464] Starting subtest: fbc-pr-cursor-blt
[278.481376] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[278.560328] [098/134] (740s left) sw_sync (sync_multi_timeline_wait)
[278.589107] Starting subtest: sync_multi_timeline_wait
[278.590283] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[278.657616] [099/134] (740s left) gem_create (create-ext-placement-sanity-check)
[278.818101] Starting subtest: create-ext-placement-sanity-check
[278.819139] Subtest create-ext-placement-sanity-check: SUCCESS (0.002s)
[278.937969] [100/134] (740s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[278.994615] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[278.995804] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[279.109149] [101/134] (740s left) gem_exec_fence (syncobj-channel)
[279.538810] Starting subtest: syncobj-channel
[280.558593] Subtest syncobj-channel: SUCCESS (1.017s)
[281.034510] [102/134] (738s left) syncobj_basic (bad-pad-fd-to-handle)
[281.077795] Starting subtest: bad-pad-fd-to-handle
[281.078836] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[281.189672] [103/134] (738s left) gem_eio (kms)
[281.459500] Starting subtest: kms
[302.085049] Subtest kms: SUCCESS (20.623s)
[303.280369] [104/134] (716s left) kms_plane_cursor (viewport)
[303.364711] Starting subtest: viewport
[303.367832] Starting dynamic subtest: pipe-A-eDP-1-size-64
[306.114023] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.751s)
[306.182432] Starting dynamic subtest: pipe-A-eDP-1-size-128
[308.961539] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.793s)
[309.023336] Starting dynamic subtest: pipe-A-eDP-1-size-256
[311.761932] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.748s)
[312.109227] Starting dynamic subtest: pipe-B-eDP-1-size-64
[315.562673] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.462s)
[315.624225] Starting dynamic subtest: pipe-B-eDP-1-size-128
[318.419737] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.805s)
[318.481235] Starting dynamic subtest: pipe-B-eDP-1-size-256
[321.252921] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.781s)
[321.608865] Starting dynamic subtest: pipe-C-eDP-1-size-64
[324.660600] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.061s)
[324.699231] Starting dynamic subtest: pipe-C-eDP-1-size-128
[327.082670] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.391s)
[327.121492] Starting dynamic subtest: pipe-C-eDP-1-size-256
[329.471540] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.358s)
[329.796210] Starting dynamic subtest: pipe-D-eDP-1-size-64
[332.957096] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.169s)
[332.995130] Starting dynamic subtest: pipe-D-eDP-1-size-128
[335.368254] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.380s)
[335.411800] Starting dynamic subtest: pipe-D-eDP-1-size-256
[337.803133] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.404s)
[338.124470] Subtest viewport: SUCCESS (34.760s)
[339.007375] [105/134] (680s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[339.065115] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[339.066142] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[339.172504] [106/134] (680s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[339.254974] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[339.256839] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[339.353461] [107/134] (680s left) kms_lease (master-vs-lease)
[339.439289] Starting subtest: master-vs-lease
[339.440140] Subtest master-vs-lease: SUCCESS (0.002s)
[339.574866] [108/134] (679s left) gem_ctx_isolation (preservation)
[339.634056] Starting subtest: preservation
[339.635065] Starting dynamic subtest: rcs0
[339.777661] Dynamic subtest rcs0: SUCCESS (0.143s)
[339.787760] Starting dynamic subtest: bcs0
[339.950823] Dynamic subtest bcs0: SUCCESS (0.172s)
[339.958796] Starting dynamic subtest: vcs0
[340.072728] Dynamic subtest vcs0: SUCCESS (0.121s)
[340.080661] Starting dynamic subtest: vcs1
[342.041204] Dynamic subtest vcs1: SUCCESS (1.967s)
[342.051113] Starting dynamic subtest: vecs0
[344.038036] Dynamic subtest vecs0: SUCCESS (1.996s)
[344.046800] Starting dynamic subtest: ccs0
[346.081217] Dynamic subtest ccs0: SUCCESS (2.042s)
[346.082486] Subtest preservation: SUCCESS (6.447s)
[346.349215] [109/134] (673s left) gem_ringfill (basic-all)
[351.898079] Starting subtest: basic-all
[353.039124] Subtest basic-all: SUCCESS (1.140s)
[353.187803] [110/134] (666s left) gem_exec_schedule (pi-distinct-iova)
[353.351581] Starting subtest: pi-distinct-iova
[353.352821] Starting dynamic subtest: rcs0
[353.483996] Dynamic subtest rcs0: SUCCESS (0.130s)
[353.492540] Starting dynamic subtest: vcs0
[353.565409] Dynamic subtest vcs0: SUCCESS (0.080s)
[353.576644] Starting dynamic subtest: vcs1
[353.655729] Dynamic subtest vcs1: SUCCESS (0.088s)
[353.667846] Starting dynamic subtest: vecs0
[353.738637] Dynamic subtest vecs0: SUCCESS (0.081s)
[353.750657] Starting dynamic subtest: ccs0
[353.865132] Dynamic subtest ccs0: SUCCESS (0.122s)
[353.866253] Subtest pi-distinct-iova: SUCCESS (0.512s)
[355.031474] [111/134] (664s left) syncobj_timeline (wait-all-delayed-signal)
[355.090160] Starting subtest: wait-all-delayed-signal
[355.196162] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[355.287557] [112/134] (664s left) gen9_exec_parse (bb-chained)
[355.334931] Subtest bb-chained: SKIP (0.000s)
[355.407401] [113/134] (664s left) kms_psr (pr-sprite-mmap-gtt)
[355.500420] Starting subtest: pr-sprite-mmap-gtt
[355.502317] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[355.590508] [114/134] (664s left) gem_eio (in-flight-internal-immediate)
[355.821686] Starting subtest: in-flight-internal-immediate
[356.964945] Subtest in-flight-internal-immediate: SUCCESS (1.144s)
[357.109906] [115/134] (662s left) kms_vblank (query-idle-hang)
[357.208334] Starting subtest: query-idle-hang
[357.209258] Starting dynamic subtest: pipe-A-eDP-1
[359.004550] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.796s)
[359.014083] Starting dynamic subtest: pipe-D-eDP-1
[361.453490] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.447s)
[361.454398] Subtest query-idle-hang: SUCCESS (4.248s)
[362.270552] [116/134] (657s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[367.248162] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[367.251598] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[368.106960] [117/134] (651s left) kms_frontbuffer_tracking (fbc-1p-rte)
[368.195734] Starting subtest: fbc-1p-rte
[373.687440] Subtest fbc-1p-rte: SUCCESS (5.493s)
[374.841714] [118/134] (645s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[374.932663] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[374.933550] Starting dynamic subtest: pipe-A-eDP-1
[374.942080] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[374.950689] Starting dynamic subtest: pipe-B-eDP-1
[374.960615] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[374.969863] Starting dynamic subtest: pipe-C-eDP-1
[374.978081] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[374.985324] Starting dynamic subtest: pipe-D-eDP-1
[374.993604] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[374.995265] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.005s)
[377.103699] [119/134] (642s left) prime_mmap (test_userptr)
[377.154500] Starting subtest: test_userptr
[377.155655] Starting dynamic subtest: test_userptr-smem
[377.156600] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[377.157476] Subtest test_userptr: SUCCESS (0.001s)
[377.267030] [120/134] (642s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[377.362460] Starting subtest: 4-tiled-32bpp-rotate-180
[378.729067] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.367s)
[378.874097] [121/134] (641s left) gem_exec_balancer (bonded-chain)
[378.929982] Starting subtest: bonded-chain
[385.906292] Subtest bonded-chain: SUCCESS (6.975s)
[386.232598] [122/134] (633s left) kms_color (ctm-blue-to-red)
[386.318149] Starting subtest: ctm-blue-to-red
[386.318995] Starting dynamic subtest: pipe-A-eDP-1
[386.989739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.671s)
[386.997510] Starting dynamic subtest: pipe-B-eDP-1
[388.379379] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[388.387426] Starting dynamic subtest: pipe-C-eDP-1
[389.729449] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.350s)
[389.737887] Starting dynamic subtest: pipe-D-eDP-1
[391.070116] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.338s)
[391.071163] Subtest ctm-blue-to-red: SUCCESS (4.751s)
[391.926515] [123/134] (628s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[392.015466] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[392.016411] Starting dynamic subtest: pipe-A-eDP-1
[392.435928] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.419s)
[392.446933] Starting dynamic subtest: pipe-B-eDP-1
[392.455305] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[392.465181] Starting dynamic subtest: pipe-C-eDP-1
[392.473060] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[392.481143] Starting dynamic subtest: pipe-D-eDP-1
[392.489626] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[392.490470] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.434s)
[394.683669] [124/134] (625s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[394.782363] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[401.747697] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.966s)
[401.878497] [125/134] (618s left) gem_exec_gttfill (basic)
[401.938840] Starting subtest: basic
[404.965681] Subtest basic: SUCCESS (3.026s)
[405.169472] [126/134] (614s left) perf_pmu (enable-race)
[405.236577] Starting subtest: enable-race
[405.237727] Starting dynamic subtest: rcs0
[417.999646] Dynamic subtest rcs0: SUCCESS (12.762s)
[418.010198] Starting dynamic subtest: bcs0
[430.739793] Dynamic subtest bcs0: SUCCESS (12.739s)
[430.747764] Starting dynamic subtest: vcs0
[445.910884] Dynamic subtest vcs0: SUCCESS (15.167s)
[445.921648] Starting dynamic subtest: vcs1
[461.909512] Dynamic subtest vcs1: SUCCESS (15.997s)
[461.920790] Starting dynamic subtest: vecs0
[477.909493] Dynamic subtest vecs0: SUCCESS (15.997s)
[477.921509] Starting dynamic subtest: ccs0
[490.655782] Dynamic subtest ccs0: SUCCESS (12.745s)
[490.659567] Subtest enable-race: SUCCESS (85.420s)
[490.773218] [127/134] (529s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[490.872131] Starting subtest: psr-rgb565-draw-mmap-gtt
[490.873967] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[490.999009] [128/134] (529s left) kms_addfb_basic (too-high)
[491.097354] Starting subtest: too-high
[491.098217] Subtest too-high: SUCCESS (0.000s)
[491.214758] [129/134] (528s left) kms_pm_rpm (fences)
[493.062528] Starting subtest: fences
[493.065069] Subtest fences: SKIP (0.000s)
[493.464383] [130/134] (526s left) gem_mmap (pf-nonblock)
[493.518788] Subtest pf-nonblock: SKIP (0.000s)
[493.584976] [131/134] (526s left) i915_drm_fdinfo (memory-info-idle)
[493.624443] Starting subtest: memory-info-idle
[493.625479] Starting dynamic subtest: smem0
[493.697047] Dynamic subtest smem0: SUCCESS (0.071s)
[493.702411] Subtest memory-info-idle: SUCCESS (0.072s)
[493.776614] [132/134] (526s left) gem_pxp (hw-rejects-pxp-buffer)
[493.889629] Starting subtest: hw-rejects-pxp-buffer
[493.891907] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[494.026342] [133/134] (526s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[494.112981] Starting subtest: fbc-rgb101010-draw-mmap-wc
[498.737955] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.625s)
[499.857664] [134/134] (520s left) kms_pm_backlight (fade-with-dpms)
[499.952496] Starting subtest: fade-with-dpms
[502.406169] Starting dynamic subtest: eDP-1
[505.089758] Dynamic subtest eDP-1: SUCCESS (2.683s)
[505.091061] Subtest fade-with-dpms: SUCCESS (5.138s)
[505.903161] 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.