[58.721039] Initializing watchdogs
[58.721084]   /dev/watchdog0
[58.734790] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.739184] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.741833] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.745914] [001/132] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[58.882175] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[66.210728] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.324s)
[66.351761] [002/132] (952s left) gen7_exec_parse (bitmasks)
[66.415741] Subtest bitmasks: SKIP (0.000s)
[66.487136] [003/132] (952s left) gem_userptr_blits (relocations)
[66.547832] Starting subtest: relocations
[66.552268] Subtest relocations: SKIP (0.001s)
[66.666819] [004/132] (952s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[66.800857] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[66.805079] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[66.870711] [005/132] (952s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[66.924296] Starting subtest: x-tiled-to-vebox-yf-tiled
[66.928563] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[67.348253] [006/132] (952s left) gem_exec_reloc (basic-cpu-wc-active)
[67.493999] Subtest basic-cpu-wc-active: SKIP (0.000s)
[67.647176] [007/132] (951s left) kms_addfb_basic (bo-too-small)
[67.815220] Starting subtest: bo-too-small
[67.817454] Subtest bo-too-small: SUCCESS (0.000s)
[67.977933] [008/132] (951s left) gem_mmap_offset (partial-unmap)
[68.133006] Starting subtest: partial-unmap
[68.135160] Starting dynamic subtest: smem0
[68.643021] Dynamic subtest smem0: SUCCESS (0.509s)
[68.645693] Subtest partial-unmap: SUCCESS (0.510s)
[68.750472] [009/132] (950s left) kms_cursor_crc (cursor-onscreen-512x170)
[69.042057] Starting subtest: cursor-onscreen-512x170
[69.046577] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[69.168644] [010/132] (950s left) i915_pm_rpm (reg-read-ioctl)
[71.129869] Starting subtest: reg-read-ioctl
[73.519868] Subtest reg-read-ioctl: SUCCESS (2.392s)
[73.899343] [011/132] (945s left) kms_psr2_su (page_flip-nv12)
[73.956022] Subtest page_flip-NV12: SKIP (0.000s)
[74.014133] [012/132] (945s left) kms_content_protection (dp-mst-type-0)
[74.166161] Starting subtest: dp-mst-type-0
[74.170694] Subtest dp-mst-type-0: SKIP (0.000s)
[74.269546] [013/132] (945s left) kms_busy (basic-hang)
[74.368344] Starting subtest: basic-hang
[74.370752] Starting dynamic subtest: flip-pipe-A
[74.950875] Dynamic subtest flip-pipe-A: SUCCESS (0.581s)
[74.959914] Starting dynamic subtest: modeset-pipe-A
[78.397853] Dynamic subtest modeset-pipe-A: SUCCESS (3.445s)
[78.407051] Starting dynamic subtest: flip-pipe-D
[79.592212] Dynamic subtest flip-pipe-D: SUCCESS (1.191s)
[79.600563] Starting dynamic subtest: modeset-pipe-D
[83.012650] Dynamic subtest modeset-pipe-D: SUCCESS (3.421s)
[83.015077] Subtest basic-hang: SUCCESS (8.643s)
[83.814280] [014/132] (935s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[83.878669] Starting subtest: multi-wait-for-submit-submitted-signaled
[83.880903] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[83.996746] [015/132] (935s left) kms_cursor_crc (cursor-tearing-position-change)
[84.114590] Starting subtest: cursor-tearing-position-change
[84.117013] Starting dynamic subtest: pipe-A-eDP-1
[84.665943] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s)
[84.674571] Starting dynamic subtest: pipe-D-eDP-1
[85.934728] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.270s)
[85.937054] Subtest cursor-tearing-position-change: SUCCESS (1.822s)
[86.772211] [016/132] (933s left) kms_flip (2x-nonexisting-fb-interruptible)
[86.877223] Starting subtest: 2x-nonexisting-fb-interruptible
[86.888236] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.010s)
[86.997380] [017/132] (932s left) i915_module_load (resize-bar)
[87.031056] Starting subtest: resize-bar
[87.037692] Subtest resize-bar: SKIP (0.005s)
[87.077720] [018/132] (932s left) kms_psr (fbc-psr-primary-mmap-cpu)
[87.161114] Starting subtest: fbc-psr-primary-mmap-cpu
[87.163460] Starting dynamic subtest: eDP-1
[87.167947] Dynamic subtest eDP-1: SKIP (0.000s)
[87.172494] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[87.227814] [019/132] (932s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[87.328312] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[87.332790] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[87.425157] [020/132] (932s left) syncobj_wait (reset-signaled)
[87.482841] Starting subtest: reset-signaled
[87.485276] Subtest reset-signaled: SUCCESS (0.000s)
[87.577899] [021/132] (932s left) kms_cursor_crc (cursor-random-32x32)
[87.729301] Starting subtest: cursor-random-32x32
[87.775186] Subtest cursor-random-32x32: SKIP (0.042s)
[88.078867] [022/132] (932s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[88.176810] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[88.180234] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[88.270349] [023/132] (931s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[88.365315] Starting subtest: plane-lut1d-pre-ctm3x4
[88.488169] Starting dynamic subtest: pipe-A-plane-0
[88.643784] Dynamic subtest pipe-A-plane-0: SUCCESS (0.156s)
[88.651854] Starting dynamic subtest: pipe-A-plane-1
[88.779207] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[88.787396] Starting dynamic subtest: pipe-A-plane-2
[88.924823] Dynamic subtest pipe-A-plane-2: SUCCESS (0.145s)
[90.081342] Starting dynamic subtest: pipe-B-plane-0
[90.253680] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s)
[90.261745] Starting dynamic subtest: pipe-B-plane-1
[90.408840] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[90.417394] Starting dynamic subtest: pipe-B-plane-2
[90.541863] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[91.718194] Starting dynamic subtest: pipe-C-plane-0
[91.856389] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[91.863696] Starting dynamic subtest: pipe-C-plane-1
[92.000250] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[92.009874] Starting dynamic subtest: pipe-C-plane-2
[92.133913] Dynamic subtest pipe-C-plane-2: SUCCESS (0.133s)
[93.353991] Starting dynamic subtest: pipe-D-plane-0
[93.480771] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[93.488833] Starting dynamic subtest: pipe-D-plane-1
[93.614481] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[93.622815] Starting dynamic subtest: pipe-D-plane-2
[93.758961] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[94.043646] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.680s)
[94.922182] [024/132] (925s left) gem_userptr_blits (stress-mm)
[94.998766] Starting subtest: stress-mm
[100.419169] Subtest stress-mm: SUCCESS (5.369s)
[100.485387] [025/132] (919s left) syncobj_timeline (invalid-transfer-illegal-handle)
[100.531183] Starting subtest: invalid-transfer-illegal-handle
[100.533426] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[100.633664] [026/132] (919s left) kms_big_fb (linear-8bpp-rotate-0)
[100.734648] Starting subtest: linear-8bpp-rotate-0
[102.015417] Subtest linear-8bpp-rotate-0: SUCCESS (1.281s)
[102.146200] [027/132] (918s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[102.195342] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[102.199144] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[102.389945] [028/132] (917s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[102.492181] Starting subtest: yf-tiled-32bpp-rotate-180
[102.496319] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[102.579562] [029/132] (917s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[102.678668] Starting subtest: pr-overlay-plane-move-continuous-sf
[102.682903] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[102.744203] [030/132] (917s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[102.838809] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[102.843376] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[103.944148] [031/132] (916s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[104.023959] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[104.026449] Starting dynamic subtest: pipe-A-eDP-1
[104.050526] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[104.060229] Starting dynamic subtest: pipe-B-eDP-1
[104.081586] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[104.088684] Starting dynamic subtest: pipe-C-eDP-1
[104.110198] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[104.117161] Starting dynamic subtest: pipe-D-eDP-1
[104.138907] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[104.143045] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[106.298845] [032/132] (914s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[106.399857] Starting subtest: yf-tiled-8bpp-rotate-90
[106.404315] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[106.508088] [033/132] (914s left) kms_big_fb (4-tiled-addfb-size-overflow)
[106.615353] Starting subtest: 4-tiled-addfb-size-overflow
[106.617592] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[106.712091] [034/132] (913s left) gem_exec_parallel (basic)
[106.779206] Starting subtest: basic
[106.781588] Starting dynamic subtest: rcs0
[108.192223] Dynamic subtest rcs0: SUCCESS (1.415s)
[108.202498] Starting dynamic subtest: bcs0
[109.646844] Dynamic subtest bcs0: SUCCESS (1.454s)
[109.654950] Starting dynamic subtest: vcs0
[112.920597] Dynamic subtest vcs0: SUCCESS (3.270s)
[112.938102] Starting dynamic subtest: vcs1
[114.903107] Dynamic subtest vcs1: SUCCESS (1.980s)
[114.919702] Starting dynamic subtest: vecs0
[116.886855] Dynamic subtest vecs0: SUCCESS (1.981s)
[116.895522] Starting dynamic subtest: ccs0
[118.384323] Dynamic subtest ccs0: SUCCESS (1.498s)
[118.386729] Subtest basic: SUCCESS (11.608s)
[118.568806] [035/132] (902s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[118.705989] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[118.710337] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[118.845821] [036/132] (901s left) gem_exec_whisper (basic-fds-priority-all)
[118.905904] Starting subtest: basic-fds-priority-all
[141.278816] Subtest basic-fds-priority-all: SUCCESS (22.370s)
[141.459739] [037/132] (879s left) perf_pmu (all-busy-check-all)
[141.495720] Starting subtest: all-busy-check-all
[142.934695] Subtest all-busy-check-all: SUCCESS (1.437s)
[143.046525] [038/132] (877s left) kms_selftest (drm_format)
[143.081840] Starting subtest: drm_format
[143.164257] Starting dynamic subtest: drm_test_format_block_width_invalid
[143.306991] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.144s)
[143.320896] Starting dynamic subtest: drm_test_format_block_width_one_plane
[143.402069] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.092s)
[143.416307] Starting dynamic subtest: drm_test_format_block_width_two_plane
[143.497778] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.097s)
[143.506088] Starting dynamic subtest: drm_test_format_block_width_three_plane
[143.595402] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.094s)
[143.605290] Starting dynamic subtest: drm_test_format_block_width_tiled
[143.688671] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.093s)
[143.698572] Starting dynamic subtest: drm_test_format_block_height_invalid
[143.808626] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.117s)
[143.818641] Starting dynamic subtest: drm_test_format_block_height_one_plane
[143.897784] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.090s)
[143.907888] Starting dynamic subtest: drm_test_format_block_height_two_plane
[144.006183] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.109s)
[144.013949] Starting dynamic subtest: drm_test_format_block_height_three_plane
[144.110697] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.103s)
[144.118301] Starting dynamic subtest: drm_test_format_block_height_tiled
[144.198155] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.087s)
[144.205505] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[144.284063] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.086s)
[144.291358] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[144.374070] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.089s)
[144.382132] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[144.472635] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.096s)
[144.480050] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[144.581450] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.108s)
[144.588922] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[144.670375] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.088s)
[144.677757] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[144.756284] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.087s)
[144.763612] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[144.847077] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.090s)
[144.854465] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[144.934147] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.086s)
[144.935992] Subtest drm_format: SUCCESS (1.853s)
[145.127151] [039/132] (875s left) gem_mmap_gtt (basic-short)
[145.188684] Subtest basic-short: SKIP (0.000s)
[145.247515] [040/132] (875s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[145.345230] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[145.349543] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[145.430505] [041/132] (875s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[145.545216] Starting subtest: 4-tiled-16bpp-rotate-0
[146.880972] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.336s)
[147.007112] [042/132] (873s left) gem_exec_big (single)
[147.062066] Starting subtest: single
[149.464688] Subtest single: SUCCESS (2.403s)
[149.679704] [043/132] (871s left) kms_properties (colorop-properties-legacy)
[149.818137] Starting subtest: colorop-properties-legacy
[149.820049] Starting dynamic subtest: pipe-A-eDP-1
[150.199161] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.381s)
[150.207820] Starting dynamic subtest: pipe-B-eDP-1
[151.261736] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.060s)
[151.271185] Starting dynamic subtest: pipe-C-eDP-1
[152.289401] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.029s)
[152.299168] Starting dynamic subtest: pipe-D-eDP-1
[153.336157] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.043s)
[153.338081] Subtest colorop-properties-legacy: SUCCESS (3.517s)
[154.134695] [044/132] (866s left) core_setmaster (master-drop-set-shared-fd)
[154.231423] Starting subtest: master-drop-set-shared-fd
[154.253319] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[154.287606] [045/132] (866s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180-hflip)
[154.398123] Starting subtest: linear-max-hw-stride-32bpp-rotate-180-hflip
[154.402561] Subtest linear-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[154.511704] [046/132] (866s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[154.574346] Starting subtest: multi-wait-available-unsubmitted-submitted
[154.577416] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[154.672384] [047/132] (866s left) kms_prime (basic-crc-vgem)
[154.797979] Starting subtest: basic-crc-vgem
[154.800075] Starting dynamic subtest: second-to-first
[155.784146] Dynamic subtest second-to-first: SUCCESS (0.988s)
[155.785917] Subtest basic-crc-vgem: SUCCESS (0.990s)
[155.891221] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.vgem: true
[155.893997] [048/132] (865s left) kms_feature_discovery (display)
[155.992045] Starting subtest: display
[155.993853] Subtest display: SUCCESS (0.000s)
[156.074687] [049/132] (865s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[156.124239] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[156.188823] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.024s)
[156.223468] [050/132] (864s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[156.325859] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[156.329140] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[156.420989] [051/132] (864s left) api_intel_bb (bb-with-allocator)
[156.470622] Starting subtest: bb-with-allocator
[156.473415] Subtest bb-with-allocator: SUCCESS (0.005s)
[156.597879] [052/132] (864s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[156.681485] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[156.685169] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[156.754469] [053/132] (864s left) kms_flip (2x-flip-vs-panning-interruptible)
[156.854027] Starting subtest: 2x-flip-vs-panning-interruptible
[156.865263] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s)
[156.975048] [054/132] (864s left) gem_bad_reloc (negative-reloc-bltcopy)
[157.038646] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[157.125910] [055/132] (864s left) kms_chamelium_color (ctm-max)
[157.220346] Subtest ctm-max: SKIP (0.000s)
[157.263108] [056/132] (864s left) kms_psr (fbc-psr2-primary-render)
[157.359391] Starting subtest: fbc-psr2-primary-render
[157.361306] Starting dynamic subtest: eDP-1
[157.364855] Dynamic subtest eDP-1: SKIP (0.000s)
[157.368583] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[157.431741] [057/132] (864s left) gem_exec_balancer (bonded-true-hang)
[157.687285] Starting subtest: bonded-true-hang
[157.692263] Subtest bonded-true-hang: SKIP (0.000s)
[157.786554] [058/132] (863s left) syncobj_timeline (host-signal-ordered)
[157.845768] Starting subtest: host-signal-ordered
[157.848063] Subtest host-signal-ordered: SUCCESS (0.000s)
[157.943620] [059/132] (863s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[158.027679] Starting subtest: fbc-rgb565-draw-pwrite
[162.655704] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.630s)
[163.844044] [060/132] (857s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[163.941635] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[163.946140] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[164.029364] [061/132] (857s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[164.124094] Starting subtest: plane-upscale-20x20-with-pixel-format
[164.126323] Starting dynamic subtest: pipe-A
[165.171759] Dynamic subtest pipe-A: SUCCESS (1.050s)
[165.179919] Starting dynamic subtest: pipe-B
[167.244770] Dynamic subtest pipe-B: SUCCESS (2.072s)
[167.253387] Starting dynamic subtest: pipe-C
[168.455262] Dynamic subtest pipe-C: SUCCESS (1.209s)
[168.464970] Starting dynamic subtest: pipe-D
[169.598571] Dynamic subtest pipe-D: SUCCESS (1.141s)
[169.600858] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.476s)
[170.793643] [062/132] (850s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[170.895084] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[170.897403] Starting dynamic subtest: pipe-A-eDP-1
[170.920765] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[170.932068] Starting dynamic subtest: pipe-B-eDP-1
[170.953854] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[170.962098] Starting dynamic subtest: pipe-C-eDP-1
[170.983499] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[170.991478] Starting dynamic subtest: pipe-D-eDP-1
[171.013192] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[171.017471] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[173.162417] [063/132] (848s left) perf (non-zero-reason)
[173.313257] Starting subtest: non-zero-reason
[173.315154] Starting dynamic subtest: 0-rcs0
[178.711918] Dynamic subtest 0-rcs0: SUCCESS (5.398s)
[178.723866] Starting dynamic subtest: 1-vcs0
[182.620203] Dynamic subtest 1-vcs0: SUCCESS (3.907s)
[182.622940] Subtest non-zero-reason: SUCCESS (9.307s)
[182.660753] [064/132] (839s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[182.767091] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[182.771085] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[182.869833] [065/132] (838s left) gem_vm_create (invalid-destroy)
[182.938041] Starting subtest: invalid-destroy
[182.940295] Subtest invalid-destroy: SUCCESS (0.003s)
[183.041835] [066/132] (838s left) gem_ctx_persistence (engines-mixed-process)
[183.101038] Starting subtest: engines-mixed-process
[183.103628] Starting dynamic subtest: rcs0
[184.923523] Dynamic subtest rcs0: SUCCESS (1.822s)
[184.935138] Starting dynamic subtest: bcs0
[186.908166] Dynamic subtest bcs0: SUCCESS (1.983s)
[186.918180] Starting dynamic subtest: vcs0
[188.897916] Dynamic subtest vcs0: SUCCESS (1.988s)
[188.909846] Starting dynamic subtest: vcs1
[190.915677] Dynamic subtest vcs1: SUCCESS (2.016s)
[190.926331] Starting dynamic subtest: vecs0
[192.940248] Dynamic subtest vecs0: SUCCESS (2.023s)
[192.951342] Starting dynamic subtest: ccs0
[194.886315] Dynamic subtest ccs0: SUCCESS (1.942s)
[194.888461] Subtest engines-mixed-process: SUCCESS (11.784s)
[195.004716] [067/132] (826s left) i915_query (query-topology-unsupported)
[195.058830] Starting subtest: query-topology-unsupported
[195.064844] Subtest query-topology-unsupported: SKIP (0.000s)
[195.165813] [068/132] (826s left) kms_invalid_mode (bad-hsync-end)
[195.284958] Starting subtest: bad-hsync-end
[195.287268] Starting dynamic subtest: pipe-A-eDP-1
[195.571710] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[195.580802] Starting dynamic subtest: pipe-B-eDP-1
[195.582778] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[195.590802] Starting dynamic subtest: pipe-C-eDP-1
[195.593186] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[195.601694] Starting dynamic subtest: pipe-D-eDP-1
[195.603898] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[195.606045] Subtest bad-hsync-end: SUCCESS (0.292s)
[196.378081] [069/132] (825s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[196.443293] Starting subtest: basic-batch-kernel-default-cmd
[196.448165] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[196.624979] [070/132] (825s left) kms_flip (2x-flip-vs-suspend)
[196.731255] Starting subtest: 2x-flip-vs-suspend
[196.742870] Subtest 2x-flip-vs-suspend: SKIP (0.010s)
[196.821660] [071/132] (825s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[196.932411] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[206.232729] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.302s)
[206.346911] [072/132] (815s left) gem_exec_schedule (timeslicing)
[206.426792] Starting subtest: timeslicing
[206.428826] Starting dynamic subtest: rcs0
[206.438041] Dynamic subtest rcs0: SUCCESS (0.022s)
[206.449520] Starting dynamic subtest: bcs0
[206.458864] Dynamic subtest bcs0: SUCCESS (0.020s)
[206.467525] Starting dynamic subtest: vcs0
[206.484900] Dynamic subtest vcs0: SUCCESS (0.023s)
[206.498071] Starting dynamic subtest: vcs1
[206.505195] Dynamic subtest vcs1: SUCCESS (0.020s)
[206.513778] Starting dynamic subtest: vecs0
[206.525988] Dynamic subtest vecs0: SUCCESS (0.020s)
[206.534551] Starting dynamic subtest: ccs0
[206.548552] Dynamic subtest ccs0: SUCCESS (0.022s)
[206.550945] Subtest timeslicing: SUCCESS (0.133s)
[207.935306] [073/132] (814s left) vgem_basic (unload)
[207.975904] Starting subtest: unload
[237.069912] Subtest unload: SUCCESS (29.044s)
[237.132765] [074/132] (785s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[237.244671] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[244.208976] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.967s)
[244.354880] [075/132] (777s left) gem_exec_gttfill (all-engines)
[244.426639] Starting subtest: all-engines
[267.252811] Subtest all-engines: SUCCESS (22.824s)
[267.443503] [076/132] (754s left) gem_partial_pwrite_pread (write-uncached)
[267.550151] Subtest write-uncached: SKIP (0.000s)
[267.629900] [077/132] (754s left) syncobj_wait (wait-all-delayed-signal)
[267.697731] Starting subtest: wait-all-delayed-signal
[267.802583] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[267.882615] [078/132] (754s left) kms_psr (psr-basic)
[267.985216] Starting subtest: psr-basic
[267.987537] Starting dynamic subtest: eDP-1
[269.648947] Dynamic subtest eDP-1: SUCCESS (1.661s)
[269.650984] Subtest psr-basic: SUCCESS (1.663s)
[270.523326] [079/132] (751s left) syncobj_wait (invalid-reset-zero-handles)
[270.583160] Starting subtest: invalid-reset-zero-handles
[270.585425] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[270.691656] [080/132] (751s left) api_intel_bb (destroy-bb)
[270.754602] Starting subtest: destroy-bb
[270.764084] Subtest destroy-bb: SUCCESS (0.008s)
[270.877070] [081/132] (751s left) kms_pm_backlight (fade-with-dpms)
[270.967050] Starting subtest: fade-with-dpms
[273.351905] Starting dynamic subtest: eDP-1
[275.851476] Dynamic subtest eDP-1: SUCCESS (2.498s)
[275.853736] Subtest fade-with-dpms: SUCCESS (4.884s)
[276.639822] [082/132] (745s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[276.731102] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[284.219523] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.490s)
[284.376879] [083/132] (738s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[284.473771] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[288.893929] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.423s)
[290.074706] [084/132] (732s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[290.173952] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[290.178174] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[290.287330] [085/132] (732s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[290.381688] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[290.481449] [086/132] (732s left) gem_close_race (basic-process)
[290.543811] Starting subtest: basic-process
[290.591816] Subtest basic-process: SUCCESS (0.048s)
[290.778645] [087/132] (731s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[290.879193] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[290.883185] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[290.949404] [088/132] (731s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[291.053713] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[298.089333] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.039s)
[298.226105] [089/132] (724s left) gem_exec_reloc (basic-wc-cpu-active)
[298.296580] Subtest basic-wc-cpu-active: SKIP (0.000s)
[298.380252] [090/132] (724s left) syncobj_timeline (wait-any-interrupted)
[298.433893] Starting subtest: wait-any-interrupted
[298.536851] Subtest wait-any-interrupted: SUCCESS (0.102s)
[298.612202] [091/132] (724s left) kms_psr (psr-no-drrs)
[298.725570] Starting subtest: psr-no-drrs
[298.728083] Starting dynamic subtest: eDP-1
[300.346495] Dynamic subtest eDP-1: SUCCESS (1.618s)
[300.348389] Subtest psr-no-drrs: SUCCESS (1.620s)
[301.224977] [092/132] (721s left) gem_pread (self)
[301.291738] Subtest self: SKIP (0.000s)
[301.358098] [093/132] (721s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[301.446307] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[301.458842] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[301.538620] [094/132] (721s left) kms_chamelium_color (ctm-red-to-blue)
[301.621203] Subtest ctm-red-to-blue: SKIP (0.000s)
[301.665243] [095/132] (721s left) sysfs_preempt_timeout (invalid)
[301.717216] Starting subtest: invalid
[301.719480] Starting dynamic subtest: vecs0
[301.721617] Dynamic subtest vecs0: SUCCESS (0.000s)
[301.731214] Starting dynamic subtest: ccs0
[301.733291] Dynamic subtest ccs0: SUCCESS (0.000s)
[301.741902] Starting dynamic subtest: vcs0
[301.743718] Dynamic subtest vcs0: SUCCESS (0.000s)
[301.752272] Starting dynamic subtest: bcs0
[301.754405] Dynamic subtest bcs0: SUCCESS (0.000s)
[301.762942] Starting dynamic subtest: rcs0
[301.765132] Dynamic subtest rcs0: SUCCESS (0.000s)
[301.773609] Starting dynamic subtest: vcs1
[301.775801] Dynamic subtest vcs1: SUCCESS (0.000s)
[301.778069] Subtest invalid: SUCCESS (0.006s)
[301.814287] [096/132] (721s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[301.922532] Starting subtest: fbcpsr-rgb565-draw-render
[308.968271] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.048s)
[309.101093] [097/132] (714s left) gem_ctx_bad_destroy (invalid-pad)
[309.154803] Starting subtest: invalid-pad
[309.157076] Subtest invalid-pad: SUCCESS (0.000s)
[309.227817] [098/132] (713s left) gem_ctx_persistence (legacy-engines-hostile)
[309.281819] Starting subtest: legacy-engines-hostile
[309.284098] Starting dynamic subtest: render
[309.429682] Dynamic subtest render: SUCCESS (0.147s)
[309.444282] Starting dynamic subtest: bsd1
[309.523031] Dynamic subtest bsd1: SUCCESS (0.090s)
[309.535309] Starting dynamic subtest: bsd2
[309.623673] Dynamic subtest bsd2: SUCCESS (0.098s)
[309.637958] Starting dynamic subtest: blt
[309.720650] Dynamic subtest blt: SUCCESS (0.094s)
[309.734842] Starting dynamic subtest: vebox
[309.820624] Dynamic subtest vebox: SUCCESS (0.097s)
[309.823066] Subtest legacy-engines-hostile: SUCCESS (0.540s)
[309.903773] [099/132] (713s left) i915_drm_fdinfo (isolation)
[309.948205] Starting subtest: isolation
[309.949713] Starting dynamic subtest: rcs0
[309.968247] Dynamic subtest rcs0: SKIP (0.018s)
[309.977498] Starting dynamic subtest: bcs0
[309.980744] Dynamic subtest bcs0: SKIP (0.000s)
[309.989291] Starting dynamic subtest: vcs0
[309.992821] Dynamic subtest vcs0: SKIP (0.000s)
[310.001236] Starting dynamic subtest: vcs1
[310.004477] Dynamic subtest vcs1: SKIP (0.000s)
[310.013495] Starting dynamic subtest: vecs0
[310.016796] Dynamic subtest vecs0: SKIP (0.000s)
[310.024901] Starting dynamic subtest: ccs0
[310.028366] Dynamic subtest ccs0: SKIP (0.000s)
[310.031748] Subtest isolation: SKIP (0.024s)
[310.066449] [100/132] (713s left) kms_flip (flip-vs-panning-vs-hang)
[310.148782] Starting subtest: flip-vs-panning-vs-hang
[310.150808] Starting dynamic subtest: A-eDP1
[362.366296] Dynamic subtest A-eDP1: SUCCESS (52.215s)
[362.378181] Starting dynamic subtest: D-eDP1
[412.415778] Dynamic subtest D-eDP1: SUCCESS (50.045s)
[412.418074] Subtest flip-vs-panning-vs-hang: SUCCESS (102.265s)
[413.663922] [101/132] (609s left) gem_exec_params (cliprects-invalid)
[413.719736] Starting subtest: cliprects-invalid
[413.722054] Subtest cliprects-invalid: SUCCESS (0.000s)
[413.845175] [102/132] (609s left) gem_mmap_gtt (basic-read-write-distinct)
[413.894292] Subtest basic-read-write-distinct: SKIP (0.000s)
[413.954044] [103/132] (609s left) kms_lease (possible-crtcs-filtering)
[414.051143] Starting subtest: possible-crtcs-filtering
[414.055540] Subtest possible-crtcs-filtering: SUCCESS (0.006s)
[414.185531] [104/132] (609s left) kms_flip (2x-plain-flip)
[414.277157] Starting subtest: 2x-plain-flip
[414.286060] Subtest 2x-plain-flip: SKIP (0.010s)
[414.362103] [105/132] (609s left) gem_lmem_swapping (verify-ccs)
[414.406165] Subtest verify-ccs: SKIP (0.000s)
[414.452058] [106/132] (609s left) i915_suspend (basic-s2idle-without-i915)
[414.489303] Starting subtest: basic-s2idle-without-i915
[433.385072] Subtest basic-s2idle-without-i915: SUCCESS (4.218s)
[433.498212] [107/132] (590s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[433.607047] Starting subtest: primary-x-tiled-reflect-x-0
[440.639501] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.002s)
[441.807831] [108/132] (581s left) gem_softpin (full)
[441.869353] Starting subtest: full
[441.878678] Subtest full: SUCCESS (0.009s)
[442.004568] [109/132] (581s left) gem_ctx_shared (q-smoketest)
[442.071010] Starting subtest: Q-smoketest
[442.073440] Starting dynamic subtest: rcs0
[447.689257] Dynamic subtest rcs0: SUCCESS (5.506s)
[447.699149] Starting dynamic subtest: bcs0
[453.191037] Dynamic subtest bcs0: SUCCESS (5.491s)
[453.202280] Starting dynamic subtest: vcs0
[458.669161] Dynamic subtest vcs0: SUCCESS (5.478s)
[458.680592] Starting dynamic subtest: vcs1
[464.158933] Dynamic subtest vcs1: SUCCESS (5.483s)
[464.174935] Starting dynamic subtest: vecs0
[469.632941] Dynamic subtest vecs0: SUCCESS (5.475s)
[469.643695] Starting dynamic subtest: ccs0
[475.127811] Dynamic subtest ccs0: SUCCESS (5.498s)
[475.129877] Subtest Q-smoketest: SUCCESS (32.939s)
[475.219709] [110/132] (548s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[475.608951] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[476.419001] [111/132] (547s left) gem_ctx_engines (invalid-engines)
[476.476433] Starting subtest: invalid-engines
[476.484500] Subtest invalid-engines: SUCCESS (0.008s)
[476.714687] [112/132] (547s left) kms_plane_alpha_blend (alpha-opaque-fb)
[476.810711] Starting subtest: alpha-opaque-fb
[477.098603] Starting dynamic subtest: pipe-A-eDP-1
[477.798581] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.699s)
[477.808667] Starting dynamic subtest: pipe-D-eDP-1
[479.166992] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.366s)
[479.169190] Subtest alpha-opaque-fb: SUCCESS (2.357s)
[479.996477] [113/132] (543s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[480.094686] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[480.096853] Starting dynamic subtest: pipe-A-eDP-1
[480.588608] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[480.599360] Starting dynamic subtest: pipe-B-eDP-1
[482.010262] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.423s)
[482.020112] Starting dynamic subtest: pipe-C-eDP-1
[483.403063] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.391s)
[483.412781] Starting dynamic subtest: pipe-D-eDP-1
[484.741417] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[484.743432] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.620s)
[485.920897] [114/132] (537s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[486.037677] Starting subtest: y-tiled-8bpp-rotate-270
[486.042109] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[486.147660] [115/132] (537s left) gem_exec_fence (submit-chain)
[486.322367] Starting subtest: submit-chain
[486.341218] Subtest submit-chain: SUCCESS (0.019s)
[487.680816] [116/132] (536s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[487.774188] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[487.778465] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[487.858981] [117/132] (536s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[487.950155] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[487.954436] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[488.040619] [118/132] (535s left) gem_exec_suspend (basic-s4-devices)
[488.108654] Starting subtest: basic-S4-devices
[488.110845] Starting dynamic subtest: smem
[499.560510] Dynamic subtest smem: SUCCESS (11.449s)
[499.564706] Subtest basic-S4-devices: SUCCESS (11.452s)
[505.766892] [119/132] (524s left) kms_vrr (flip-basic-fastset)
[505.870609] Starting subtest: flip-basic-fastset
[505.876944] Subtest flip-basic-fastset: SKIP (0.000s)
[505.950536] [120/132] (524s left) gem_exec_params (sol-reset-not-gen7)
[505.997289] Starting subtest: sol-reset-not-gen7
[506.002338] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[506.119972] [121/132] (524s left) gem_exec_schedule (pi-userfault)
[506.297623] Starting subtest: pi-userfault
[506.299607] Starting dynamic subtest: rcs0
[506.305197] Dynamic subtest rcs0: SUCCESS (0.009s)
[506.320677] Starting dynamic subtest: bcs0
[506.322857] Dynamic subtest bcs0: SUCCESS (0.011s)
[506.332956] Starting dynamic subtest: vcs0
[506.335210] Dynamic subtest vcs0: SUCCESS (0.012s)
[506.344654] Starting dynamic subtest: vcs1
[506.346560] Dynamic subtest vcs1: SUCCESS (0.009s)
[506.355867] Starting dynamic subtest: vecs0
[506.358062] Dynamic subtest vecs0: SUCCESS (0.008s)
[506.367142] Starting dynamic subtest: ccs0
[506.369065] Dynamic subtest ccs0: SUCCESS (0.008s)
[506.370951] Subtest pi-userfault: SUCCESS (0.064s)
[506.557928] [122/132] (523s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[506.671477] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[514.186049] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.515s)
[514.321928] [123/132] (515s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[514.424480] Starting subtest: fbc-rgb565-draw-mmap-cpu
[518.411823] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (3.990s)
[519.595927] [124/132] (510s left) perf (polling)
[519.725939] Starting subtest: polling
[519.728266] Starting dynamic subtest: 0-rcs0
[529.743299] Dynamic subtest 0-rcs0: SUCCESS (10.012s)
[529.754916] Starting dynamic subtest: 1-vcs1
[539.769881] Dynamic subtest 1-vcs1: SUCCESS (10.022s)
[539.773053] Subtest polling: SUCCESS (20.037s)
[539.819527] [125/132] (490s left) fbdev (eof)
[539.872773] Starting subtest: eof
[539.875499] Subtest eof: SUCCESS (0.000s)
[539.960681] [126/132] (490s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[540.068953] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[543.807482] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.741s)
[544.985897] [127/132] (485s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[545.048563] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[545.050938] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[545.143319] [128/132] (485s left) perf_pmu (most-busy-idle-check-all)
[545.180970] Starting subtest: most-busy-idle-check-all
[545.182959] Starting dynamic subtest: rcs0
[546.599142] Dynamic subtest rcs0: SUCCESS (1.418s)
[546.611592] Starting dynamic subtest: bcs0
[548.584085] Dynamic subtest bcs0: SUCCESS (1.981s)
[548.596085] Starting dynamic subtest: vcs0
[550.567227] Dynamic subtest vcs0: SUCCESS (1.980s)
[550.579071] Starting dynamic subtest: vcs1
[552.550864] Dynamic subtest vcs1: SUCCESS (1.980s)
[552.563462] Starting dynamic subtest: vecs0
[554.599775] Dynamic subtest vecs0: SUCCESS (2.045s)
[554.611568] Starting dynamic subtest: ccs0
[556.584058] Dynamic subtest ccs0: SUCCESS (1.981s)
[556.586913] Subtest most-busy-idle-check-all: SUCCESS (11.402s)
[556.695156] [129/132] (473s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[556.750472] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[556.859006] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.108s)
[556.934962] [130/132] (473s left) syncobj_wait (single-wait-all-for-submit-signaled)
[557.011480] Starting subtest: single-wait-all-for-submit-signaled
[557.013989] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[557.128309] [131/132] (473s left) kms_psr (pr-dpms)
[557.236635] Starting subtest: pr-dpms
[557.240981] Subtest pr-dpms: SKIP (0.000s)
[557.306062] [132/132] (473s left) gem_exec_whisper (basic-queues-priority-all)
[557.365829] Starting subtest: basic-queues-priority-all
[578.809841] Subtest basic-queues-priority-all: SUCCESS (21.433s)
[578.988967] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gen7_exec_parse
results: parsing output: 2/ for test: gem_userptr_blits
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: gem_exec_reloc
results: parsing output: 6/ for test: kms_addfb_basic
results: parsing output: 7/ for test: gem_mmap_offset
results: parsing output: 8/ for test: kms_cursor_crc
results: parsing output: 9/ for test: i915_pm_rpm
results: parsing output: 10/ for test: kms_psr2_su
results: parsing output: 11/ for test: kms_content_protection
results: parsing output: 12/ for test: kms_busy
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: kms_cursor_crc
results: parsing output: 15/ for test: kms_flip
results: parsing output: 16/ for test: i915_module_load
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: syncobj_wait
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_color_pipeline
results: parsing output: 23/ for test: gem_userptr_blits
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: gem_render_copy
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_psr2_sf
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_exec_parallel
results: parsing output: 34/ for test: kms_cursor_legacy
results: parsing output: 35/ for test: gem_exec_whisper
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_selftest
results: parsing output: 38/ for test: gem_mmap_gtt
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: gem_exec_big
results: parsing output: 42/ for test: kms_properties
results: parsing output: 43/ for test: core_setmaster
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: kms_prime
results: parsing output: 47/ for test: kms_feature_discovery
results: parsing output: 48/ for test: sriov_basic
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: api_intel_bb
results: parsing output: 51/ for test: kms_plane
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: gem_bad_reloc
results: parsing output: 54/ for test: kms_chamelium_color
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: gem_exec_balancer
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_plane_scaling
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: perf
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_vm_create
results: parsing output: 65/ for test: gem_ctx_persistence
results: parsing output: 66/ for test: i915_query
results: parsing output: 67/ for test: kms_invalid_mode
results: parsing output: 68/ for test: gem_exec_flush
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: vgem_basic
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_exec_gttfill
results: parsing output: 75/ for test: gem_partial_pwrite_pread
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: api_intel_bb
results: parsing output: 80/ for test: kms_pm_backlight
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_plane
results: parsing output: 84/ for test: kms_addfb_basic
results: parsing output: 85/ for test: gem_close_race
results: parsing output: 86/ for test: kms_plane
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: syncobj_timeline
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: gem_pread
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: kms_chamelium_color
results: parsing output: 94/ for test: sysfs_preempt_timeout
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_ctx_bad_destroy
results: parsing output: 97/ for test: gem_ctx_persistence
results: parsing output: 98/ for test: i915_drm_fdinfo
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: gem_exec_params
results: parsing output: 101/ for test: gem_mmap_gtt
results: parsing output: 102/ for test: kms_lease
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_lmem_swapping
results: parsing output: 105/ for test: i915_suspend
results: parsing output: 106/ for test: kms_rotation_crc
results: parsing output: 107/ for test: gem_softpin
results: parsing output: 108/ for test: gem_ctx_shared
results: parsing output: 109/ for test: kms_chamelium_hpd
results: parsing output: 110/ for test: gem_ctx_engines
results: parsing output: 111/ for test: kms_plane_alpha_blend
results: parsing output: 112/ for test: kms_ccs
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: gem_exec_fence
results: parsing output: 115/ for test: kms_flip_scaled_crc
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_exec_suspend
results: parsing output: 118/ for test: kms_vrr
results: parsing output: 119/ for test: gem_exec_params
results: parsing output: 120/ for test: gem_exec_schedule
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: fbdev
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: perf_pmu
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: gem_exec_whisper
Done.