[57.138524] Initializing watchdogs
[57.138658]   /dev/watchdog0
[57.154159] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.158598] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.160995] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.164367] [001/132] (960s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[57.319189] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[57.323782] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[57.485905] [002/132] (960s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[57.544037] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[57.606932] [003/132] (960s left) kms_prime (basic-crc-hybrid)
[57.682368] Subtest basic-crc-hybrid: SKIP (0.000s)
[57.807087] [FACT kms_prime (basic-crc-hybrid)] new: kernel.kmod_is_loaded.vgem: true
[57.810168] [004/132] (959s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[57.873483] Starting subtest: single-wait-all-for-submit-signaled
[57.876070] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[58.008794] [005/132] (959s left) gem_ctx_isolation (dirty-create)
[58.077826] Starting subtest: dirty-create
[58.079944] Starting dynamic subtest: rcs0
[58.282878] Dynamic subtest rcs0: SUCCESS (0.205s)
[58.292851] Starting dynamic subtest: bcs0
[58.506229] Dynamic subtest bcs0: SUCCESS (0.223s)
[58.516145] Starting dynamic subtest: vcs0
[58.740575] Dynamic subtest vcs0: SUCCESS (0.234s)
[58.749618] Starting dynamic subtest: vcs1
[60.109712] Dynamic subtest vcs1: SUCCESS (1.368s)
[60.119439] Starting dynamic subtest: vecs0
[62.104590] Dynamic subtest vecs0: SUCCESS (1.995s)
[62.112171] Starting dynamic subtest: ccs0
[64.142825] Dynamic subtest ccs0: SUCCESS (2.038s)
[64.145339] Subtest dirty-create: SUCCESS (6.065s)
[64.429291] [006/132] (953s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[64.522838] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[64.525166] Starting dynamic subtest: pipe-A-default-mode
[65.668453] Dynamic subtest pipe-A-default-mode: SKIP (1.142s)
[65.672917] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.143s)
[65.890863] [007/132] (952s left) kms_cursor_edge_walk (128x128-top-edge)
[65.997160] Starting subtest: 128x128-top-edge
[65.999489] Starting dynamic subtest: pipe-A-eDP-1
[69.508441] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.511s)
[69.516460] Starting dynamic subtest: pipe-D-eDP-1
[72.712419] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.204s)
[72.714875] Subtest 128x128-top-edge: SUCCESS (6.717s)
[73.544239] [008/132] (944s left) gem_exec_flush (basic-wb-prw-default)
[73.608708] Starting subtest: basic-wb-prw-default
[79.065184] Subtest basic-wb-prw-default: SUCCESS (5.457s)
[79.276308] [009/132] (938s left) kms_getfb (getfb2-accept-nv12)
[79.392400] Starting subtest: getfb2-accept-nv12
[79.394659] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[79.539147] [010/132] (938s left) gem_softpin (invalid)
[79.613315] Starting subtest: invalid
[79.624423] Subtest invalid: SUCCESS (0.010s)
[79.789799] [011/132] (938s left) gem_userptr_blits (readonly-unsync)
[79.855999] Subtest readonly-unsync: SKIP (0.000s)
[80.027400] [012/132] (938s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[80.135820] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[80.139979] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[80.245500] [013/132] (937s left) gem_lmem_swapping (smem-oom)
[80.285406] Subtest smem-oom: SKIP (0.000s)
[80.336689] [014/132] (937s left) syncobj_eventfd (binary-wait-before-signal)
[80.386460] Starting subtest: binary-wait-before-signal
[80.417246] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[80.513411] [015/132] (937s left) gem_mmap_offset (partial-unmap)
[80.575026] Starting subtest: partial-unmap
[80.576659] Starting dynamic subtest: smem0
[81.063804] Dynamic subtest smem0: SUCCESS (0.488s)
[81.067119] Subtest partial-unmap: SUCCESS (0.488s)
[81.175684] [016/132] (937s left) kms_color_pipeline (plane-lut3d-green-only)
[81.265397] Starting subtest: plane-lut3d-green-only
[81.277660] Starting dynamic subtest: pipe-A-eDP-1
[81.282470] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.313175] Starting dynamic subtest: pipe-B-eDP-1
[82.317405] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[83.347088] Starting dynamic subtest: pipe-C-eDP-1
[83.351764] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[84.379275] Starting dynamic subtest: pipe-D-eDP-1
[84.383873] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[84.666145] Subtest plane-lut3d-green-only: SKIP (3.401s)
[85.482944] [017/132] (932s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[85.577980] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[85.580379] Starting dynamic subtest: pipe-A-eDP-1
[85.600722] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[85.609608] Starting dynamic subtest: pipe-B-eDP-1
[85.628340] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[85.635186] Starting dynamic subtest: pipe-C-eDP-1
[85.654387] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[85.661049] Starting dynamic subtest: pipe-D-eDP-1
[85.680071] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[85.681925] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[88.049892] [018/132] (930s left) kms_plane_lowres (tiling-y)
[88.150059] Starting subtest: tiling-y
[88.154301] Subtest tiling-y: SKIP (0.000s)
[88.245498] [019/132] (930s left) i915_query (engine-info)
[88.304797] Starting subtest: engine-info
[88.309508] Subtest engine-info: SUCCESS (0.005s)
[88.471132] [020/132] (929s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[93.517507] Starting subtest: pr-cursor-plane-move-continuous-sf
[93.522247] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.003s)
[94.375250] [021/132] (924s left) kms_chamelium_color (ctm-green-to-red)
[94.480686] Subtest ctm-green-to-red: SKIP (0.000s)
[94.524654] [022/132] (923s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[94.615292] Starting subtest: plane-upscale-20x20-with-rotation
[94.617663] Starting dynamic subtest: pipe-A
[94.843727] Dynamic subtest pipe-A: SUCCESS (0.231s)
[94.852715] Starting dynamic subtest: pipe-B
[96.145469] Dynamic subtest pipe-B: SUCCESS (1.301s)
[96.154067] Starting dynamic subtest: pipe-C
[97.474220] Dynamic subtest pipe-C: SUCCESS (1.328s)
[97.483409] Starting dynamic subtest: pipe-D
[98.808022] Dynamic subtest pipe-D: SUCCESS (1.333s)
[98.810354] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.196s)
[100.011538] [023/132] (918s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[100.077582] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[100.133308] [024/132] (918s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[100.234984] Starting subtest: 4-tiled-16bpp-rotate-270
[100.254198] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[100.400370] [025/132] (918s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[100.498024] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[100.502481] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[100.615263] [026/132] (918s left) gem_eio (banned)
[100.928483] Starting subtest: banned
[101.569479] Subtest banned: SUCCESS (0.642s)
[101.774594] [027/132] (916s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[101.882145] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[101.885489] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[102.012228] [028/132] (916s left) gem_flink_race (flink_close)
[102.050656] Starting subtest: flink_close
[107.137201] Subtest flink_close: SUCCESS (5.086s)
[107.246209] [029/132] (911s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[107.356424] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt
[107.360313] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[107.494783] [030/132] (911s left) gem_exec_balancer (parallel-keep-submit-fence)
[107.768931] Starting subtest: parallel-keep-submit-fence
[108.918413] Subtest parallel-keep-submit-fence: SUCCESS (1.151s)
[109.058347] [031/132] (909s left) kms_cursor_crc (cursor-onscreen-512x170)
[109.346600] Starting subtest: cursor-onscreen-512x170
[109.351268] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[109.529892] [032/132] (909s left) gem_ringfill (engines-basic)
[114.903710] Starting subtest: engines-basic
[114.906282] Starting dynamic subtest: rcs0
[114.986663] Dynamic subtest rcs0: SUCCESS (0.084s)
[114.998045] Starting dynamic subtest: bcs0
[115.032905] Dynamic subtest bcs0: SUCCESS (0.045s)
[115.043380] Starting dynamic subtest: vcs0
[115.075910] Dynamic subtest vcs0: SUCCESS (0.042s)
[115.086696] Starting dynamic subtest: vcs1
[115.118051] Dynamic subtest vcs1: SUCCESS (0.041s)
[115.127528] Starting dynamic subtest: vecs0
[115.160961] Dynamic subtest vecs0: SUCCESS (0.042s)
[115.170492] Starting dynamic subtest: ccs0
[115.203828] Dynamic subtest ccs0: SUCCESS (0.042s)
[115.206220] Subtest engines-basic: SUCCESS (0.302s)
[115.307676] [033/132] (903s left) perf (buffer-fill)
[115.451011] Starting subtest: buffer-fill
[115.453167] Starting dynamic subtest: 0-rcs0
[117.730715] Dynamic subtest 0-rcs0: SUCCESS (2.278s)
[117.742828] Starting dynamic subtest: 1-vcs0
[122.170772] Dynamic subtest 1-vcs0: SUCCESS (4.439s)
[122.173081] Subtest buffer-fill: SUCCESS (6.719s)
[122.219058] [034/132] (896s left) gem_linear_blits (basic)
[122.530066] Starting subtest: basic
[122.539657] Subtest basic: SUCCESS (0.010s)
[122.717838] [035/132] (896s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[122.864774] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[123.237086] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[125.973887] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.735s)
[125.984964] Starting dynamic subtest: pipe-A-eDP-1-4
[128.170393] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.197s)
[128.178785] Starting dynamic subtest: pipe-A-eDP-1-4
[130.181766] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.010s)
[130.192969] Starting dynamic subtest: pipe-A-eDP-1-x
[132.359822] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[132.725956] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[135.509973] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.789s)
[135.521182] Starting dynamic subtest: pipe-B-eDP-1-4
[137.695704] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[137.706410] Starting dynamic subtest: pipe-B-eDP-1-4
[139.705874] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.010s)
[139.717200] Starting dynamic subtest: pipe-B-eDP-1-x
[141.896374] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s)
[142.259800] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[145.045670] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.791s)
[145.056828] Starting dynamic subtest: pipe-C-eDP-1-4
[147.221632] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.175s)
[147.232617] Starting dynamic subtest: pipe-C-eDP-1-4
[149.231818] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[149.243058] Starting dynamic subtest: pipe-C-eDP-1-x
[151.411226] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[151.779045] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[154.563686] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.793s)
[154.575243] Starting dynamic subtest: pipe-D-eDP-1-4
[156.750401] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.186s)
[156.761365] Starting dynamic subtest: pipe-D-eDP-1-4
[158.760657] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[158.769385] Starting dynamic subtest: pipe-D-eDP-1-x
[160.939582] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[160.942090] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.073s)
[162.167622] [036/132] (856s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[162.264827] Starting subtest: fbc-dirtyfb-ioctl
[162.266673] Starting dynamic subtest: A-eDP-1
[164.844247] Dynamic subtest A-eDP-1: SUCCESS (2.578s)
[164.846491] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.580s)
[165.669245] [037/132] (853s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[165.775983] Starting subtest: y-tiled-32bpp-rotate-180
[165.780450] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[165.882998] [038/132] (853s left) gem_ctx_param (root-set)
[165.937447] Starting subtest: root-set
[165.939872] Subtest root-set: SUCCESS (0.001s)
[166.075675] [039/132] (853s left) gem_exec_fence (basic-wait-all)
[166.131303] Starting subtest: basic-wait-all
[166.240926] Subtest basic-wait-all: SUCCESS (0.110s)
[168.027317] [040/132] (851s left) gem_lmem_swapping (heavy-random)
[168.075077] Subtest heavy-random: SKIP (0.000s)
[168.123798] [041/132] (851s left) kms_psr (fbc-pr-primary-blt)
[168.227242] Starting subtest: fbc-pr-primary-blt
[168.231635] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[168.325600] [042/132] (850s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[168.437785] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[168.442286] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[168.573080] [043/132] (850s left) kms_content_protection (type1)
[168.719761] Starting subtest: type1
[168.724371] Subtest type1: SKIP (0.000s)
[168.898980] [044/132] (850s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[171.031505] Starting subtest: gem-execbuf-stress-pc8
[171.074790] Subtest gem-execbuf-stress-pc8: SKIP (0.041s)
[171.432954] [045/132] (848s left) kms_plane_alpha_blend (alpha-transparent-fb)
[171.529368] Starting subtest: alpha-transparent-fb
[171.826850] Starting dynamic subtest: pipe-A-eDP-1
[172.449278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[172.458555] Starting dynamic subtest: pipe-D-eDP-1
[173.793256] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[173.795671] Subtest alpha-transparent-fb: SUCCESS (2.266s)
[174.693515] [046/132] (844s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[174.750622] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[174.857037] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.104s)
[174.954270] [047/132] (844s left) gem_mmap_wc (bad-offset)
[175.021096] Subtest bad-offset: SKIP (0.000s)
[175.086183] [048/132] (844s left) gem_mmap_wc (write-gtt-read-wc)
[175.136633] Subtest write-gtt-read-wc: SKIP (0.000s)
[175.223692] [049/132] (844s left) vgem_basic (dmabuf-fence)
[175.271483] Starting subtest: dmabuf-fence
[175.273731] Subtest dmabuf-fence: SUCCESS (0.001s)
[175.352261] [050/132] (844s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[175.464487] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[176.143776] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.681s)
[176.364325] [051/132] (843s left) dmabuf_sync_file (export-wait-after-attach)
[176.402740] Starting subtest: export-wait-after-attach
[176.404953] Subtest export-wait-after-attach: SUCCESS (0.001s)
[176.490815] [052/132] (843s left) gem_exec_reloc (basic-concurrent0)
[176.547330] Subtest basic-concurrent0: SKIP (0.000s)
[176.626694] [053/132] (843s left) perf_pmu (busy-hang)
[176.668964] Starting subtest: busy-hang
[176.671218] Starting dynamic subtest: rcs0
[177.964643] Dynamic subtest rcs0: SUCCESS (1.294s)
[177.975534] Starting dynamic subtest: bcs0
[179.213118] Dynamic subtest bcs0: SUCCESS (1.250s)
[179.224657] Starting dynamic subtest: vcs0
[181.404279] Dynamic subtest vcs0: SUCCESS (2.190s)
[181.415412] Starting dynamic subtest: vcs1
[183.455074] Dynamic subtest vcs1: SUCCESS (2.049s)
[183.464760] Starting dynamic subtest: vecs0
[185.374986] Dynamic subtest vecs0: SUCCESS (1.915s)
[185.386201] Starting dynamic subtest: ccs0
[186.645663] Dynamic subtest ccs0: SUCCESS (1.269s)
[186.647648] Subtest busy-hang: SUCCESS (9.975s)
[186.771574] [054/132] (833s left) kms_psr (pr-suspend)
[186.863793] Starting subtest: pr-suspend
[186.867761] Subtest pr-suspend: SKIP (0.000s)
[186.962267] [055/132] (832s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[187.219570] Starting subtest: cursor-rapid-movement-256x85
[187.269311] Subtest cursor-rapid-movement-256x85: SKIP (0.045s)
[187.485341] [056/132] (832s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[192.538419] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[192.540800] Starting dynamic subtest: pipe-A-eDP-1
[199.177940] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.637s)
[199.187875] Starting dynamic subtest: pipe-B-eDP-1
[205.830019] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.651s)
[205.832307] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (13.292s)
[206.716208] [057/132] (813s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[206.853527] Starting subtest: cursorA-vs-flipA-toggle
[217.981655] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.125s)
[218.842514] [058/132] (801s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[218.933115] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[218.935363] Starting dynamic subtest: pipe-A-eDP-1
[218.958328] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[218.969589] Starting dynamic subtest: pipe-B-eDP-1
[218.991084] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[218.999784] Starting dynamic subtest: pipe-C-eDP-1
[219.020287] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[219.028671] Starting dynamic subtest: pipe-D-eDP-1
[219.050073] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[219.054149] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[221.201367] [059/132] (798s left) gem_ccs (ctrl-surf-copy-new-ctx)
[221.272085] Starting subtest: ctrl-surf-copy-new-ctx
[221.276603] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[221.412858] [060/132] (798s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[221.509771] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[228.438586] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.930s)
[228.615253] [061/132] (791s left) gem_exec_reloc (basic-softpin)
[228.687978] Subtest basic-softpin: SKIP (0.000s)
[228.782173] [062/132] (791s left) kms_vblank (query-idle-hang)
[228.880227] Starting subtest: query-idle-hang
[228.882416] Starting dynamic subtest: pipe-A-eDP-1
[255.698047] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.816s)
[255.713732] Starting dynamic subtest: pipe-D-eDP-1
[280.650713] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.949s)
[280.653062] Subtest query-idle-hang: SUCCESS (51.769s)
[281.480660] [063/132] (738s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[281.872216] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[282.662802] [064/132] (737s left) gem_exec_balancer (hang)
[282.932972] Starting subtest: hang
[284.908331] Subtest hang: SUCCESS (1.978s)
[285.071432] [065/132] (735s left) gem_mmap_gtt (basic-small-bo)
[285.130569] Subtest basic-small-bo: SKIP (0.000s)
[285.192832] [066/132] (735s left) kms_plane_scaling (planes-downscale-factor-0-5)
[285.301325] Starting subtest: planes-downscale-factor-0-5
[285.303429] Starting dynamic subtest: pipe-A
[285.325287] Dynamic subtest pipe-A: SKIP (0.018s)
[285.336405] Starting dynamic subtest: pipe-B
[285.356031] Dynamic subtest pipe-B: SKIP (0.032s)
[285.365646] Starting dynamic subtest: pipe-C
[285.390911] Dynamic subtest pipe-C: SKIP (0.035s)
[285.399774] Starting dynamic subtest: pipe-D
[285.422851] Dynamic subtest pipe-D: SKIP (0.032s)
[285.426877] Subtest planes-downscale-factor-0-5: SKIP (0.119s)
[285.561431] [067/132] (734s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[285.664704] Starting subtest: fbcpsr-rgb565-draw-blt
[292.782451] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.119s)
[292.935503] [068/132] (727s left) kms_selftest (drm_cmdline_parser)
[292.979228] Starting subtest: drm_cmdline_parser
[293.017768] Starting dynamic subtest: drm_test_cmdline_force_d_only
[293.127851] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.109s)
[293.138347] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[293.236742] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.109s)
[293.247371] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[293.343182] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.105s)
[293.353786] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[293.455245] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.112s)
[293.465375] Starting dynamic subtest: drm_test_cmdline_force_e_only
[293.561050] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.104s)
[293.571481] Starting dynamic subtest: drm_test_cmdline_res
[293.670825] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.109s)
[293.679406] Starting dynamic subtest: drm_test_cmdline_res_vesa
[293.786353] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.114s)
[293.795452] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[293.915370] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.129s)
[293.924799] Starting dynamic subtest: drm_test_cmdline_res_rblank
[294.024426] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.106s)
[294.033720] Starting dynamic subtest: drm_test_cmdline_res_bpp
[294.132142] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.107s)
[294.140825] Starting dynamic subtest: drm_test_cmdline_res_refresh
[294.248287] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.115s)
[294.256898] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[294.350386] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.102s)
[294.359028] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[294.474117] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.121s)
[294.483135] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[294.603063] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.128s)
[294.613899] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[294.702348] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.100s)
[294.710950] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[294.822045] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.117s)
[294.832937] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[294.927379] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.105s)
[294.936124] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[295.039436] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.111s)
[295.048464] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[295.153374] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.114s)
[295.162083] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[295.259455] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.107s)
[295.268099] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[295.366166] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.105s)
[295.376513] Starting dynamic subtest: drm_test_cmdline_name
[295.478387] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.111s)
[295.488867] Starting dynamic subtest: drm_test_cmdline_name_bpp
[295.579266] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.101s)
[295.588755] Starting dynamic subtest: drm_test_cmdline_name_option
[295.704947] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.124s)
[295.713825] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[295.820388] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.114s)
[295.829061] Starting dynamic subtest: drm_test_cmdline_rotate_0
[295.930159] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.109s)
[295.940457] Starting dynamic subtest: drm_test_cmdline_rotate_90
[296.058276] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.128s)
[296.066845] Starting dynamic subtest: drm_test_cmdline_rotate_180
[296.195995] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.136s)
[296.206899] Starting dynamic subtest: drm_test_cmdline_rotate_270
[296.321411] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.125s)
[296.330425] Starting dynamic subtest: drm_test_cmdline_hmirror
[296.450253] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.127s)
[296.460514] Starting dynamic subtest: drm_test_cmdline_vmirror
[296.583715] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.133s)
[296.592386] Starting dynamic subtest: drm_test_cmdline_margin_options
[296.730212] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.146s)
[296.740581] Starting dynamic subtest: drm_test_cmdline_multiple_options
[296.871229] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.140s)
[296.879831] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[296.995394] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.124s)
[297.003988] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[297.123774] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.127s)
[297.133749] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[297.274582] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.153s)
[297.283643] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[297.404466] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.127s)
[297.413289] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[297.542883] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.136s)
[297.553177] Starting dynamic subtest: drm_test_cmdline_invalid
[297.680102] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.136s)
[297.690020] Starting dynamic subtest: drm_test_cmdline_tv_options
[297.827476] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.149s)
[297.829862] Subtest drm_cmdline_parser: SUCCESS (4.848s)
[298.032498] [069/132] (722s left) kms_properties (get_properties-sanity-non-atomic)
[298.130719] Starting subtest: get_properties-sanity-non-atomic
[298.132792] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[298.231202] [070/132] (722s left) kms_flip (2x-flip-vs-suspend)
[298.334239] Starting subtest: 2x-flip-vs-suspend
[298.349281] Subtest 2x-flip-vs-suspend: SKIP (0.015s)
[298.449425] [071/132] (722s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[298.549638] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[298.553787] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[298.662318] [072/132] (721s left) perf_pmu (semaphore-wait)
[298.704157] Starting subtest: semaphore-wait
[298.706431] Starting dynamic subtest: rcs0
[299.218285] Dynamic subtest rcs0: SUCCESS (0.513s)
[299.230319] Starting dynamic subtest: bcs0
[299.732452] Dynamic subtest bcs0: SUCCESS (0.511s)
[299.744568] Starting dynamic subtest: vcs0
[300.245671] Dynamic subtest vcs0: SUCCESS (0.510s)
[300.257764] Starting dynamic subtest: vcs1
[300.759305] Dynamic subtest vcs1: SUCCESS (0.511s)
[300.771895] Starting dynamic subtest: vecs0
[301.273265] Dynamic subtest vecs0: SUCCESS (0.511s)
[301.285544] Starting dynamic subtest: ccs0
[301.788897] Dynamic subtest ccs0: SUCCESS (0.512s)
[301.792064] Subtest semaphore-wait: SUCCESS (3.086s)
[302.969215] [073/132] (717s left) gem_exec_schedule (u-lateslice)
[303.050087] Starting subtest: u-lateslice
[303.052320] Starting dynamic subtest: rcs0
[303.071573] Dynamic subtest rcs0: SUCCESS (0.029s)
[303.083129] Starting dynamic subtest: bcs0
[303.096942] Dynamic subtest bcs0: SUCCESS (0.025s)
[303.106336] Starting dynamic subtest: vcs0
[303.120071] Dynamic subtest vcs0: SUCCESS (0.022s)
[303.128968] Starting dynamic subtest: vcs1
[303.143353] Dynamic subtest vcs1: SUCCESS (0.023s)
[303.152419] Starting dynamic subtest: vecs0
[303.166186] Dynamic subtest vecs0: SUCCESS (0.022s)
[303.175551] Starting dynamic subtest: ccs0
[303.191194] Dynamic subtest ccs0: SUCCESS (0.024s)
[303.193682] Subtest u-lateslice: SUCCESS (0.149s)
[305.039359] [074/132] (715s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[305.144742] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[305.147163] Starting dynamic subtest: pipe-A-default-mode
[305.436663] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[305.441770] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.290s)
[306.281834] [075/132] (714s left) gem_spin_batch (spin-all-new)
[306.353340] Starting subtest: spin-all-new
[306.370740] Subtest spin-all-new: SUCCESS (0.016s)
[307.987187] [076/132] (712s left) kms_async_flips (crc-atomic)
[308.095085] Starting subtest: crc-atomic
[308.471392] Starting dynamic subtest: pipe-A-eDP-1
[311.368649] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.895s)
[311.768710] Starting dynamic subtest: pipe-B-eDP-1
[314.700296] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.940s)
[315.076080] Starting dynamic subtest: pipe-C-eDP-1
[318.019268] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.953s)
[318.380136] Starting dynamic subtest: pipe-D-eDP-1
[321.238638] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.868s)
[321.241060] Subtest crc-atomic: SUCCESS (13.144s)
[322.428257] [077/132] (698s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[322.545344] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[322.551896] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[323.660053] [078/132] (697s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[323.758112] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[323.770293] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[323.917316] [079/132] (696s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[324.019248] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[324.023141] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[324.157109] [080/132] (696s left) kms_psr (psr-sprite-blt)
[324.268345] Starting subtest: psr-sprite-blt
[324.270546] Starting dynamic subtest: eDP-1
[326.069264] Dynamic subtest eDP-1: SUCCESS (1.802s)
[326.071538] Subtest psr-sprite-blt: SUCCESS (1.803s)
[326.929033] [081/132] (694s left) gem_mmap_gtt (basic-small-copy-xy)
[326.989113] Subtest basic-small-copy-XY: SKIP (0.000s)
[327.058351] [082/132] (693s left) kms_chamelium_hpd (vga-hpd-fast)
[327.447940] Subtest vga-hpd-fast: SKIP (0.000s)
[328.219730] [083/132] (692s left) gem_exec_async (concurrent-writes)
[328.291300] Starting subtest: concurrent-writes
[328.293216] Starting dynamic subtest: rcs0
[328.303862] Dynamic subtest rcs0: SUCCESS (0.015s)
[328.314844] Starting dynamic subtest: bcs0
[328.317081] Dynamic subtest bcs0: SUCCESS (0.006s)
[328.326201] Starting dynamic subtest: vcs0
[328.328064] Dynamic subtest vcs0: SUCCESS (0.006s)
[328.337223] Starting dynamic subtest: vcs1
[328.339404] Dynamic subtest vcs1: SUCCESS (0.006s)
[328.348384] Starting dynamic subtest: vecs0
[328.350397] Dynamic subtest vecs0: SUCCESS (0.005s)
[328.361266] Starting dynamic subtest: ccs0
[328.363192] Dynamic subtest ccs0: SUCCESS (0.006s)
[328.365067] Subtest concurrent-writes: SUCCESS (0.049s)
[328.614578] [084/132] (692s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[328.686171] Starting subtest: legacy-engines-hostile-preempt
[328.688508] Starting dynamic subtest: render
[328.814088] Dynamic subtest render: SUCCESS (0.127s)
[328.825993] Starting dynamic subtest: bsd1
[330.913781] Dynamic subtest bsd1: SUCCESS (2.096s)
[330.925804] Starting dynamic subtest: bsd2
[332.868054] Dynamic subtest bsd2: SUCCESS (1.951s)
[332.880650] Starting dynamic subtest: blt
[332.997711] Dynamic subtest blt: SUCCESS (0.128s)
[333.012419] Starting dynamic subtest: vebox
[334.898324] Dynamic subtest vebox: SUCCESS (1.896s)
[334.900830] Subtest legacy-engines-hostile-preempt: SUCCESS (6.212s)
[335.004371] [085/132] (686s left) gem_exec_whisper (basic-forked-all)
[335.074300] Starting subtest: basic-forked-all
[349.151827] Subtest basic-forked-all: SUCCESS (14.075s)
[349.364527] [086/132] (671s left) kms_addfb_basic (bo-too-small)
[349.465853] Starting subtest: bo-too-small
[349.468171] Subtest bo-too-small: SUCCESS (0.000s)
[349.605776] [087/132] (671s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[349.715541] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[349.719850] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[349.862085] [088/132] (671s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[349.963099] Starting subtest: x-tiled-32bpp-rotate-0
[351.420068] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.457s)
[351.589809] [089/132] (669s left) sysfs_timeslice_duration (invalid)
[351.641531] Starting subtest: invalid
[351.643629] Starting dynamic subtest: vecs0
[351.645720] Dynamic subtest vecs0: SUCCESS (0.000s)
[351.656296] Starting dynamic subtest: ccs0
[351.657847] Dynamic subtest ccs0: SUCCESS (0.000s)
[351.666696] Starting dynamic subtest: vcs0
[351.668486] Dynamic subtest vcs0: SUCCESS (0.000s)
[351.677186] Starting dynamic subtest: bcs0
[351.678711] Dynamic subtest bcs0: SUCCESS (0.000s)
[351.687169] Starting dynamic subtest: rcs0
[351.688708] Dynamic subtest rcs0: SUCCESS (0.000s)
[351.697333] Starting dynamic subtest: vcs1
[351.698916] Dynamic subtest vcs1: SUCCESS (0.000s)
[351.700516] Subtest invalid: SUCCESS (0.006s)
[351.740562] [090/132] (669s left) syncobj_wait (wait-all-snapshot)
[351.801054] Starting subtest: wait-all-snapshot
[351.881607] Subtest wait-all-snapshot: SUCCESS (0.081s)
[351.982329] [091/132] (669s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[352.083559] Starting subtest: fbc-rgb101010-draw-blt
[356.651982] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.571s)
[357.872445] [092/132] (663s left) gem_ccs (ctrl-surf-copy)
[357.940587] Starting subtest: ctrl-surf-copy
[357.944759] Subtest ctrl-surf-copy: SKIP (0.000s)
[358.074984] [093/132] (663s left) i915_fb_tiling (basic-x-tiling)
[358.146069] Subtest basic-x-tiling: SKIP (0.000s)
[358.218328] [094/132] (663s left) i915_drm_fdinfo (busy-hang)
[358.256677] Starting subtest: busy-hang
[358.259048] Starting dynamic subtest: rcs0
[358.307018] Dynamic subtest rcs0: SKIP (0.049s)
[358.318608] Starting dynamic subtest: bcs0
[358.322537] Dynamic subtest bcs0: SKIP (0.001s)
[358.331779] Starting dynamic subtest: vcs0
[358.336058] Dynamic subtest vcs0: SKIP (0.000s)
[358.345680] Starting dynamic subtest: vcs1
[358.349872] Dynamic subtest vcs1: SKIP (0.000s)
[358.358860] Starting dynamic subtest: vecs0
[358.363101] Dynamic subtest vecs0: SKIP (0.000s)
[358.373062] Starting dynamic subtest: ccs0
[358.377216] Dynamic subtest ccs0: SKIP (0.000s)
[358.381276] Subtest busy-hang: SKIP (0.056s)
[358.417434] [095/132] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[358.529248] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[358.533201] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[358.662535] [096/132] (662s left) gem_render_copy (linear)
[358.727881] Starting subtest: linear
[358.730377] Starting dynamic subtest: smem
[358.769309] Dynamic subtest smem: SUCCESS (0.041s)
[358.771446] Subtest linear: SUCCESS (0.042s)
[359.045234] [097/132] (662s left) prime_mmap (test_aperture_limit)
[359.112741] Starting subtest: test_aperture_limit
[359.114733] Starting dynamic subtest: test_aperture_limit-smem
[359.121673] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[359.125711] Subtest test_aperture_limit: SKIP (0.008s)
[359.253367] [098/132] (662s left) syncobj_wait (multi-wait-submitted-signaled)
[359.311513] Starting subtest: multi-wait-submitted-signaled
[359.313711] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[359.445315] [099/132] (662s left) kms_lease (lease-unleased-crtc)
[359.551365] Starting subtest: lease-unleased-crtc
[359.553761] Starting dynamic subtest: pipe-A-eDP-1
[359.859825] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.307s)
[359.869602] Starting dynamic subtest: pipe-B-eDP-1
[360.600564] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.738s)
[360.610332] Starting dynamic subtest: pipe-C-eDP-1
[360.612723] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[360.621992] Starting dynamic subtest: pipe-D-eDP-1
[360.623982] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[360.625954] Subtest lease-unleased-crtc: SUCCESS (1.065s)
[361.010712] [100/132] (660s left) gem_exec_schedule (pi-common)
[361.203968] Starting subtest: pi-common
[361.206026] Starting dynamic subtest: rcs0
[361.232451] Dynamic subtest rcs0: SUCCESS (0.031s)
[361.241910] Starting dynamic subtest: bcs0
[361.262871] Dynamic subtest bcs0: SUCCESS (0.030s)
[361.272630] Starting dynamic subtest: vcs0
[361.292814] Dynamic subtest vcs0: SUCCESS (0.029s)
[361.305141] Starting dynamic subtest: vcs1
[361.321293] Dynamic subtest vcs1: SUCCESS (0.028s)
[361.334661] Starting dynamic subtest: vecs0
[361.349454] Dynamic subtest vecs0: SUCCESS (0.027s)
[361.361197] Starting dynamic subtest: ccs0
[361.376837] Dynamic subtest ccs0: SUCCESS (0.027s)
[361.379074] Subtest pi-common: SUCCESS (0.177s)
[361.619162] [101/132] (660s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[361.681639] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[361.684059] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[361.804100] [102/132] (659s left) kms_psr (pr-primary-blt)
[361.904865] Starting subtest: pr-primary-blt
[361.909065] Subtest pr-primary-blt: SKIP (0.000s)
[362.003744] [103/132] (659s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[362.105642] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[362.110194] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[362.247131] [104/132] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[362.343377] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[362.347269] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[362.463407] [105/132] (659s left) core_auth (getclient-simple)
[362.503750] Starting subtest: getclient-simple
[362.524819] Subtest getclient-simple: SUCCESS (0.023s)
[362.651588] [106/132] (659s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[362.744521] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[363.580816] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.837s)
[363.797986] [107/132] (658s left) gem_mmap_wc (invalid-flags)
[363.858577] Subtest invalid-flags: SKIP (0.000s)
[363.930612] [108/132] (658s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[363.982108] Starting subtest: multi-wait-all-for-submit-available-submitted
[363.984224] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[364.127885] [109/132] (657s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[364.236138] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[371.276930] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.042s)
[371.436801] [110/132] (650s left) kms_rotation_crc (primary-rotation-270)
[371.533167] Starting subtest: primary-rotation-270
[371.537661] Subtest primary-rotation-270: SKIP (0.001s)
[371.632702] [111/132] (650s left) syncobj_wait (wait-zero-handles)
[371.691248] Starting subtest: wait-zero-handles
[371.693523] Subtest wait-zero-handles: SUCCESS (0.000s)
[371.823401] [112/132] (650s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[371.924629] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[371.928592] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[372.047972] [113/132] (650s left) kms_invalid_mode (overflow-vrefresh)
[372.144055] Starting subtest: overflow-vrefresh
[372.146217] Starting dynamic subtest: pipe-A-eDP-1
[372.428141] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[372.437856] Starting dynamic subtest: pipe-B-eDP-1
[372.440072] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[372.449076] Starting dynamic subtest: pipe-C-eDP-1
[372.451200] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[372.460159] Starting dynamic subtest: pipe-D-eDP-1
[372.462037] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[372.463933] Subtest overflow-vrefresh: SUCCESS (0.291s)
[373.265206] [114/132] (649s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[373.371229] Starting subtest: fbc-rgb565-draw-render
[377.567634] Subtest fbc-rgb565-draw-render: SUCCESS (4.200s)
[378.774792] [115/132] (643s left) gen9_exec_parse (basic-rejected)
[378.833650] Subtest basic-rejected: SKIP (0.000s)
[378.918593] [116/132] (643s left) gem_exec_nop (basic-series)
[379.002746] Starting subtest: basic-series
[394.903633] Subtest basic-series: SUCCESS (15.908s)
[395.172289] [117/132] (627s left) kms_flip (blocking-absolute-wf_vblank)
[395.284173] Starting subtest: blocking-absolute-wf_vblank
[395.286501] Starting dynamic subtest: A-eDP1
[403.987563] Dynamic subtest A-eDP1: SUCCESS (8.702s)
[403.999049] Starting dynamic subtest: B-eDP1
[412.715180] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[412.725157] Starting dynamic subtest: C-eDP1
[421.429706] Dynamic subtest C-eDP1: SUCCESS (8.713s)
[421.439964] Starting dynamic subtest: D-eDP1
[430.151903] Dynamic subtest D-eDP1: SUCCESS (8.721s)
[430.157241] Subtest blocking-absolute-wf_vblank: SUCCESS (34.871s)
[431.386491] [118/132] (591s left) gem_tiled_fence_blits (basic)
[431.448420] Subtest basic: SKIP (0.000s)
[431.525922] [119/132] (590s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[431.588904] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[431.733184] [120/132] (590s left) gem_workarounds (reset)
[431.803711] Starting subtest: reset
[431.998128] Subtest reset: SUCCESS (0.194s)
[432.159644] [121/132] (590s left) gem_exec_fence (nb-await)
[432.330875] Starting subtest: nb-await
[432.333440] Starting dynamic subtest: rcs0
[432.398282] Dynamic subtest rcs0: SUCCESS (0.066s)
[432.410762] Starting dynamic subtest: bcs0
[432.457748] Dynamic subtest bcs0: SUCCESS (0.058s)
[432.469134] Starting dynamic subtest: vcs0
[432.516662] Dynamic subtest vcs0: SUCCESS (0.057s)
[432.528675] Starting dynamic subtest: vcs1
[432.575391] Dynamic subtest vcs1: SUCCESS (0.057s)
[432.586915] Starting dynamic subtest: vecs0
[432.635173] Dynamic subtest vecs0: SUCCESS (0.057s)
[432.648256] Starting dynamic subtest: ccs0
[432.693363] Dynamic subtest ccs0: SUCCESS (0.056s)
[432.695982] Subtest nb-await: SUCCESS (0.363s)
[434.067304] [122/132] (588s left) gem_partial_pwrite_pread (writes-after-reads)
[434.135196] Subtest writes-after-reads: SKIP (0.000s)
[434.223236] [123/132] (588s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[434.331163] Starting subtest: fbc-psr2-cursor-mmap-gtt
[434.333490] Starting dynamic subtest: eDP-1
[434.337970] Dynamic subtest eDP-1: SKIP (0.000s)
[434.342430] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[434.430056] [124/132] (588s left) api_intel_allocator (two-level-inception)
[434.491598] Starting subtest: two-level-inception
[439.888391] Subtest two-level-inception: SUCCESS (5.398s)
[440.044876] [125/132] (582s left) sw_sync (timeline_closed)
[440.080163] Starting subtest: timeline_closed
[440.082417] Subtest timeline_closed: SUCCESS (0.000s)
[440.167855] [126/132] (582s left) gem_pxp (reject-modify-context-protection-off-3)
[440.308244] Starting subtest: reject-modify-context-protection-off-3
[440.310593] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[440.466470] [127/132] (582s left) kms_pm_rpm (basic-rte)
[440.502305] Starting subtest: basic-rte
[443.470456] Subtest basic-rte: SUCCESS (2.968s)
[445.305470] [128/132] (577s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[445.445754] Starting subtest: cursor-vs-flip-legacy
[474.816278] Subtest cursor-vs-flip-legacy: SUCCESS (29.371s)
[475.723766] [129/132] (547s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[475.833319] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[475.835232] Starting dynamic subtest: pipe-A-eDP-1
[477.117058] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.284s)
[477.126335] Starting dynamic subtest: pipe-B-eDP-1
[479.441684] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.325s)
[479.450909] Starting dynamic subtest: pipe-C-eDP-1
[481.774644] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.331s)
[481.784582] Starting dynamic subtest: pipe-D-eDP-1
[484.122431] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.347s)
[484.124727] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.292s)
[485.350414] [130/132] (537s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[485.451744] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[485.454346] Starting dynamic subtest: pipe-A-eDP-1
[485.477895] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[485.489370] Starting dynamic subtest: pipe-B-eDP-1
[485.511252] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[485.520619] Starting dynamic subtest: pipe-C-eDP-1
[485.542561] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[485.551911] Starting dynamic subtest: pipe-D-eDP-1
[485.580495] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[485.584855] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[487.698730] [131/132] (535s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[487.765022] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[487.767336] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[487.891082] [132/132] (535s left) kms_vblank (wait-idle)
[487.979381] Starting subtest: wait-idle
[487.981700] Starting dynamic subtest: pipe-A-eDP-1
[489.744227] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.764s)
[489.758296] Starting dynamic subtest: pipe-D-eDP-1
[492.226067] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.476s)
[492.228441] Subtest wait-idle: SUCCESS (4.247s)
[493.055491] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_prime
results: parsing output: 3/ for test: syncobj_timeline
results: parsing output: 4/ for test: gem_ctx_isolation
results: parsing output: 5/ for test: kms_flip_scaled_crc
results: parsing output: 6/ for test: kms_cursor_edge_walk
results: parsing output: 7/ for test: gem_exec_flush
results: parsing output: 8/ for test: kms_getfb
results: parsing output: 9/ for test: gem_softpin
results: parsing output: 10/ for test: gem_userptr_blits
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_lmem_swapping
results: parsing output: 13/ for test: syncobj_eventfd
results: parsing output: 14/ for test: gem_mmap_offset
results: parsing output: 15/ for test: kms_color_pipeline
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_plane_lowres
results: parsing output: 18/ for test: i915_query
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: kms_chamelium_color
results: parsing output: 21/ for test: kms_plane_scaling
results: parsing output: 22/ for test: gem_fenced_exec_thrash
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_flink_race
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_balancer
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: gem_ringfill
results: parsing output: 32/ for test: perf
results: parsing output: 33/ for test: gem_linear_blits
results: parsing output: 34/ for test: kms_async_flips
results: parsing output: 35/ for test: kms_dirtyfb
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: gem_exec_fence
results: parsing output: 39/ for test: gem_lmem_swapping
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_content_protection
results: parsing output: 43/ for test: i915_pm_rpm
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_mmap_wc
results: parsing output: 47/ for test: gem_mmap_wc
results: parsing output: 48/ for test: vgem_basic
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: dmabuf_sync_file
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: kms_psr2_sf
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: gem_ccs
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: kms_vblank
results: parsing output: 62/ for test: kms_chamelium_hpd
results: parsing output: 63/ for test: gem_exec_balancer
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_selftest
results: parsing output: 68/ for test: kms_properties
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: gem_exec_schedule
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: gem_spin_batch
results: parsing output: 75/ for test: kms_async_flips
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: kms_flip
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: gem_mmap_gtt
results: parsing output: 81/ for test: kms_chamelium_hpd
results: parsing output: 82/ for test: gem_exec_async
results: parsing output: 83/ for test: gem_ctx_persistence
results: parsing output: 84/ for test: gem_exec_whisper
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: sysfs_timeslice_duration
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_ccs
results: parsing output: 92/ for test: i915_fb_tiling
results: parsing output: 93/ for test: i915_drm_fdinfo
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_render_copy
results: parsing output: 96/ for test: prime_mmap
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: kms_lease
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: core_auth
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: gem_mmap_wc
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_invalid_mode
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gen9_exec_parse
results: parsing output: 115/ for test: gem_exec_nop
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: gem_tiled_fence_blits
results: parsing output: 118/ for test: gem_userptr_blits
results: parsing output: 119/ for test: gem_workarounds
results: parsing output: 120/ for test: gem_exec_fence
results: parsing output: 121/ for test: gem_partial_pwrite_pread
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: api_intel_allocator
results: parsing output: 124/ for test: sw_sync
results: parsing output: 125/ for test: gem_pxp
results: parsing output: 126/ for test: kms_pm_rpm
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_vblank
Done.