[68.104177] Initializing watchdogs
[68.104267]   /dev/watchdog0
[68.141855] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[68.160899] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[68.166876] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.169533] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot)
[68.222212] Starting subtest: wait-for-submit-snapshot
[68.304523] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[68.499309] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[69.983246] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[69.984135] Starting dynamic subtest: pipe-A-HDMI-A-3
[70.007302] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.003s)
[70.014534] Starting dynamic subtest: pipe-B-HDMI-A-3
[70.037675] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.003s)
[70.044611] Starting dynamic subtest: pipe-C-HDMI-A-3
[70.067120] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.003s)
[70.074315] Starting dynamic subtest: pipe-D-HDMI-A-3
[70.097230] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.003s)
[70.098820] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.017s)
[70.317345] [003/132] (958s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[71.807156] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[74.236648] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (2.429s)
[74.510633] [004/132] (954s left) kms_big_fb (linear-8bpp-rotate-270)
[76.016182] Starting subtest: linear-8bpp-rotate-270
[76.117854] Subtest linear-8bpp-rotate-270: SKIP (0.101s)
[76.379427] [005/132] (952s left) kms_busy (extended-modeset-hang-newfb)
[77.872887] Starting subtest: extended-modeset-hang-newfb
[77.874837] Starting dynamic subtest: pipe-A
[93.476465] Dynamic subtest pipe-A: SUCCESS (15.600s)
[93.487036] Starting dynamic subtest: pipe-D
[108.989846] Dynamic subtest pipe-D: SUCCESS (15.508s)
[108.990797] Subtest extended-modeset-hang-newfb: SUCCESS (31.115s)
[109.261770] [006/132] (919s left) perf (oa-exponents)
[109.431207] Starting subtest: oa-exponents
[109.431964] Starting dynamic subtest: 0-rcs0
[113.077040] Dynamic subtest 0-rcs0: SUCCESS (3.642s)
[113.077944] Subtest oa-exponents: SUCCESS (3.643s)
[113.145375] [007/132] (916s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[114.647033] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[115.908545] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.258s)
[116.155925] [008/132] (913s left) kms_pipe_crc_basic (suspend-read-crc)
[117.638755] Starting subtest: suspend-read-crc
[117.640523] Starting dynamic subtest: pipe-A-HDMI-A-3
[144.664372] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (11.251s)
[144.675073] Starting dynamic subtest: pipe-B-HDMI-A-3
[171.612079] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (10.687s)
[171.622143] Starting dynamic subtest: pipe-C-HDMI-A-3
[198.595653] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (10.618s)
[198.606302] Starting dynamic subtest: pipe-D-HDMI-A-3
[225.634871] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (10.696s)
[225.635661] Subtest suspend-read-crc: SUCCESS (43.259s)
[234.879230] [009/132] (803s left) perf_pmu (interrupts-sync)
[234.928248] Starting subtest: interrupts-sync
[236.125971] Subtest interrupts-sync: SUCCESS (1.194s)
[236.324718] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true
[236.326643] [010/132] (802s left) gem_exec_reloc (basic-gtt-active)
[236.375583] Subtest basic-gtt-active: SKIP (0.000s)
[236.485261] [011/132] (802s left) kms_flip (modeset-vs-vblank-race)
[237.969318] Starting subtest: modeset-vs-vblank-race
[237.975835] Starting dynamic subtest: A-HDMI-A3
[240.989386] Dynamic subtest A-HDMI-A3: SUCCESS (3.013s)
[241.001012] Starting dynamic subtest: B-HDMI-A3
[243.976843] Dynamic subtest B-HDMI-A3: SUCCESS (2.986s)
[243.988905] Starting dynamic subtest: C-HDMI-A3
[246.950200] Dynamic subtest C-HDMI-A3: SUCCESS (2.972s)
[246.962358] Starting dynamic subtest: D-HDMI-A3
[249.932714] Dynamic subtest D-HDMI-A3: SUCCESS (2.981s)
[249.935095] Subtest modeset-vs-vblank-race: SUCCESS (11.966s)
[250.219165] [012/132] (788s left) gem_sync (basic-each)
[250.283460] Starting subtest: basic-each
[252.422778] Subtest basic-each: SUCCESS (2.139s)
[252.764686] [013/132] (785s left) gem_ctx_persistence (legacy-engines-mixed)
[252.814886] Starting subtest: legacy-engines-mixed
[252.817206] Starting dynamic subtest: render
[252.882973] Dynamic subtest render: SUCCESS (0.067s)
[252.896137] Starting dynamic subtest: bsd1
[252.922016] Dynamic subtest bsd1: SUCCESS (0.037s)
[252.936586] Starting dynamic subtest: bsd2
[252.966066] Dynamic subtest bsd2: SUCCESS (0.042s)
[252.985428] Starting dynamic subtest: blt
[253.020203] Dynamic subtest blt: SUCCESS (0.051s)
[253.048774] Starting dynamic subtest: vebox
[253.083405] Dynamic subtest vebox: SUCCESS (0.059s)
[253.088584] Subtest legacy-engines-mixed: SUCCESS (0.270s)
[253.322022] [014/132] (785s left) kms_plane (plane-panning-bottom-right)
[254.808207] Starting subtest: plane-panning-bottom-right
[254.809419] Starting dynamic subtest: pipe-A
[255.504394] Dynamic subtest pipe-A: SUCCESS (0.695s)
[255.516650] Starting dynamic subtest: pipe-B
[256.037716] Dynamic subtest pipe-B: SUCCESS (0.530s)
[256.038614] Subtest plane-panning-bottom-right: SUCCESS (1.230s)
[256.284505] [015/132] (782s left) prime_vgem (wait)
[256.365992] Starting subtest: wait
[256.366896] Starting dynamic subtest: rcs0
[256.409783] Dynamic subtest rcs0: SUCCESS (0.043s)
[256.422727] Starting dynamic subtest: bcs0
[256.463617] Dynamic subtest bcs0: SUCCESS (0.052s)
[256.481280] Starting dynamic subtest: vcs0
[256.505099] Dynamic subtest vcs0: SUCCESS (0.039s)
[256.525185] Starting dynamic subtest: vcs1
[256.548143] Dynamic subtest vcs1: SUCCESS (0.041s)
[256.570156] Starting dynamic subtest: vecs0
[256.591035] Dynamic subtest vecs0: SUCCESS (0.040s)
[256.594581] Subtest wait: SUCCESS (0.226s)
[256.822556] [016/132] (782s left) i915_query (query-topology-matches-eu-total)
[256.866162] Starting subtest: query-topology-matches-eu-total
[256.867080] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[257.040268] [017/132] (782s left) syncobj_timeline (reset-signaled)
[257.086136] Starting subtest: reset-signaled
[257.086985] Subtest reset-signaled: SUCCESS (0.000s)
[257.293873] [018/132] (781s left) syncobj_timeline (multi-wait-all-available-signaled)
[257.337467] Starting subtest: multi-wait-all-available-signaled
[257.338281] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[257.507951] [019/132] (781s left) kms_cursor_crc (cursor-random-256x256)
[259.210560] Starting subtest: cursor-random-256x256
[259.217495] Starting dynamic subtest: pipe-A-HDMI-A-3
[263.792989] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (4.575s)
[263.829057] Starting dynamic subtest: pipe-D-HDMI-A-3
[268.291093] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (4.472s)
[268.292050] Subtest cursor-random-256x256: SUCCESS (9.080s)
[268.722682] [020/132] (770s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[268.775841] Starting subtest: mixed-tiled-to-y-tiled-ccs
[268.776984] Starting dynamic subtest: smem
[268.881920] Dynamic subtest smem: SUCCESS (0.105s)
[268.892693] Starting dynamic subtest: lmem0
[269.835611] Dynamic subtest lmem0: SUCCESS (0.950s)
[269.836789] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (1.057s)
[270.133671] [021/132] (769s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[271.620298] Starting subtest: primary-y-tiled-reflect-x-180
[282.292208] Subtest primary-y-tiled-reflect-x-180: SUCCESS (10.671s)
[282.651749] [022/132] (756s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[282.704306] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[282.705256] Starting dynamic subtest: smem
[282.801929] Dynamic subtest smem: SUCCESS (0.097s)
[282.814485] Starting dynamic subtest: lmem0
[283.912597] Dynamic subtest lmem0: SUCCESS (1.106s)
[283.913919] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SUCCESS (1.206s)
[284.219129] [023/132] (755s left) gem_exec_schedule (reorder-wide)
[284.393633] Subtest reorder-wide: SKIP (0.000s)
[284.646725] [024/132] (755s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[286.137350] Starting subtest: fbc-indfb-scaledprimary
[289.777760] Subtest fbc-indfb-scaledprimary: SUCCESS (3.640s)
[289.997636] [025/132] (749s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[291.486572] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[291.488132] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[291.758551] [026/132] (748s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[293.243110] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[293.244028] Starting dynamic subtest: pipe-A-HDMI-A-3
[293.247434] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[293.258262] Starting dynamic subtest: pipe-B-HDMI-A-3
[293.261741] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[293.272567] Starting dynamic subtest: pipe-C-HDMI-A-3
[293.275841] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[293.286350] Starting dynamic subtest: pipe-D-HDMI-A-3
[293.289930] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[293.291689] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.011s)
[293.565885] [027/132] (746s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[295.057669] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[295.133809] [028/132] (744s left) kms_cursor_crc (cursor-random-64x21)
[296.766342] Starting subtest: cursor-random-64x21
[296.773532] Starting dynamic subtest: pipe-A-HDMI-A-3
[300.879301] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (4.106s)
[300.914091] Starting dynamic subtest: pipe-D-HDMI-A-3
[305.210762] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (4.306s)
[305.211708] Subtest cursor-random-64x21: SUCCESS (8.445s)
[305.738064] [029/132] (734s left) prime_mmap (test_dup)
[305.782248] Starting subtest: test_dup
[305.783208] Starting dynamic subtest: test_dup-smem
[305.843875] Dynamic subtest test_dup-smem: SUCCESS (0.061s)
[305.856254] Starting dynamic subtest: test_dup-lmem0
[305.907237] Dynamic subtest test_dup-lmem0: SUCCESS (0.062s)
[305.908255] Subtest test_dup: SUCCESS (0.126s)
[306.097807] [030/132] (734s left) gem_exec_schedule (u-submit-late-slice)
[306.161129] Starting subtest: u-submit-late-slice
[306.161966] Starting dynamic subtest: rcs0
[306.219745] Dynamic subtest rcs0: SUCCESS (0.059s)
[306.232462] Starting dynamic subtest: bcs0
[306.261849] Dynamic subtest bcs0: SUCCESS (0.041s)
[306.274993] Starting dynamic subtest: vcs0
[306.303240] Dynamic subtest vcs0: SUCCESS (0.040s)
[306.316246] Starting dynamic subtest: vcs1
[306.343592] Dynamic subtest vcs1: SUCCESS (0.039s)
[306.356537] Starting dynamic subtest: vecs0
[306.384947] Dynamic subtest vecs0: SUCCESS (0.040s)
[306.386033] Subtest u-submit-late-slice: SUCCESS (0.226s)
[306.863574] [031/132] (733s left) syncobj_timeline (etime-multi-wait-all-submitted)
[306.907608] Starting subtest: etime-multi-wait-all-submitted
[307.017248] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s)
[307.217613] [032/132] (733s left) fbdev (write)
[307.277337] Starting subtest: write
[307.997909] Subtest write: SUCCESS (0.717s)
[308.126460] [033/132] (732s left) syncobj_wait (invalid-signal-zero-handles)
[308.172249] Starting subtest: invalid-signal-zero-handles
[308.173076] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[308.376573] [034/132] (732s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[309.866774] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[309.868638] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[310.105697] [035/132] (730s left) gem_exec_balancer (sliced)
[310.163315] Starting subtest: sliced
[310.165518] Subtest sliced: SKIP (0.000s)
[310.638401] [036/132] (730s left) gem_ccs (large-ctrl-surf-copy)
[310.689268] Starting subtest: large-ctrl-surf-copy
[310.690884] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[310.901718] [037/132] (730s left) gem_fenced_exec_thrash (2-spare-fences)
[310.949019] Subtest 2-spare-fences: SKIP (0.000s)
[311.020597] [038/132] (729s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[312.504107] Starting subtest: basic-busy-flip-before-cursor-varying-size
[312.505827] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[312.698421] [039/132] (728s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[314.202938] Starting subtest: 4-tiled-8bpp-rotate-0
[314.204672] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[314.513509] [040/132] (726s left) gem_mmap_wc (copy)
[314.558099] Subtest copy: SKIP (0.000s)
[314.638644] [041/132] (726s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[316.123195] Starting subtest: cursor-vs-flip-toggle
[351.148911] Subtest cursor-vs-flip-toggle: SUCCESS (35.025s)
[351.392689] [042/132] (690s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[352.895268] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[352.897036] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[353.146960] [043/132] (688s left) kms_addfb_basic (unused-handle)
[353.190654] Starting subtest: unused-handle
[353.191613] Subtest unused-handle: SUCCESS (0.000s)
[354.889590] [044/132] (686s left) gem_ctx_shared (q-smoketest-all)
[354.949031] Starting subtest: Q-smoketest-all
[387.251759] Subtest Q-smoketest-all: SUCCESS (32.294s)
[387.632809] [045/132] (654s left) kms_flip (2x-flip-vs-expired-vblank)
[389.118026] Starting subtest: 2x-flip-vs-expired-vblank
[389.136644] Subtest 2x-flip-vs-expired-vblank: SKIP (0.019s)
[389.337143] [046/132] (652s left) kms_force_connector_basic (force-edid)
[389.470465] Starting subtest: force-edid
[389.492762] Subtest force-edid: SUCCESS (0.023s)
[391.246875] [047/132] (650s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[392.752616] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[394.002037] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.248s)
[394.293362] [048/132] (647s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[395.778068] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[395.778965] Starting dynamic subtest: pipe-A
[395.941786] Dynamic subtest pipe-A: SUCCESS (0.164s)
[395.964708] Starting dynamic subtest: pipe-B
[396.152486] Dynamic subtest pipe-B: SUCCESS (0.209s)
[396.165291] Starting dynamic subtest: pipe-C
[396.294064] Dynamic subtest pipe-C: SUCCESS (0.140s)
[396.307272] Starting dynamic subtest: pipe-D
[396.433952] Dynamic subtest pipe-D: SUCCESS (0.139s)
[396.434767] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.658s)
[396.697756] [049/132] (645s left) kms_chamelium_audio (hdmi-audio)
[398.232902] Subtest hdmi-audio: SKIP (0.000s)
[398.352101] [050/132] (644s left) gem_set_tiling_vs_gtt
[398.492854] [051/132] (643s left) gem_lmem_swapping (massive)
[402.936730] Starting subtest: massive
[402.937630] Starting dynamic subtest: lmem0
[411.084583] Dynamic subtest lmem0: SUCCESS (8.144s)
[411.085484] Subtest massive: SUCCESS (8.146s)
[412.659461] [FACT gem_lmem_swapping (massive)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[412.660636] [FACT gem_lmem_swapping (massive)] deleted: kernel.kmod_is_loaded.i915: true
[412.662866] [052/132] (629s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[417.406670] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[417.408446] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[417.671386] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[417.674750] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: kernel.kmod_is_loaded.i915: true
[417.681153] [053/132] (624s left) prime_busy (after)
[417.736275] Starting subtest: after
[417.742653] Starting dynamic subtest: rcs0
[417.782587] Dynamic subtest rcs0: SUCCESS (0.040s)
[417.795537] Starting dynamic subtest: bcs0
[417.870480] Dynamic subtest bcs0: SUCCESS (0.085s)
[417.918318] Starting dynamic subtest: vcs0
[417.919449] Dynamic subtest vcs0: SUCCESS (0.039s)
[417.934956] Starting dynamic subtest: vcs1
[417.954924] Dynamic subtest vcs1: SUCCESS (0.036s)
[417.967759] Starting dynamic subtest: vecs0
[417.994727] Dynamic subtest vecs0: SUCCESS (0.038s)
[418.106847] Subtest after: SUCCESS (0.367s)
[418.381461] [054/132] (624s left) syncobj_wait (invalid-signal-illegal-handle)
[418.426321] Starting subtest: invalid-signal-illegal-handle
[418.427178] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[418.632693] [055/132] (624s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[420.116048] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[420.117202] Starting dynamic subtest: pipe-A-valid-mode
[420.168909] Dynamic subtest pipe-A-valid-mode: SKIP (0.051s)
[420.170577] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.053s)
[420.382912] [056/132] (622s left) gem_gtt_cpu_tlb
[420.515121] [057/132] (622s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[422.003520] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[422.005216] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[422.250293] [058/132] (620s left) gen9_exec_parse (batch-zero-length)
[422.300118] Subtest batch-zero-length: SKIP (0.000s)
[422.390390] [059/132] (620s left) gem_softpin (evict-snoop-interruptible)
[422.443609] Starting subtest: evict-snoop-interruptible
[422.445434] Subtest evict-snoop-interruptible: SKIP (0.000s)
[422.605021] [060/132] (620s left) dmabuf_sync_file (export-basic)
[422.654200] Starting subtest: export-basic
[422.655108] Subtest export-basic: SUCCESS (0.001s)
[422.829632] [061/132] (620s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[424.314103] Starting subtest: fb-8bpc-vs-panel-6bpc
[424.316615] Subtest fb-8bpc-vs-panel-6bpc: SKIP (0.001s)
[424.530265] [062/132] (619s left) syncobj_timeline (host-signal-ordered)
[424.574916] Starting subtest: host-signal-ordered
[424.575829] Subtest host-signal-ordered: SUCCESS (0.000s)
[424.754516] [063/132] (618s left) kms_chamelium_hpd (common-hpd-after-suspend)
[426.293491] Subtest common-hpd-after-suspend: SKIP (0.000s)
[426.412013] [064/132] (617s left) kms_flip (2x-flip-vs-fences)
[427.897947] Starting subtest: 2x-flip-vs-fences
[427.899733] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[428.169458] [065/132] (615s left) perf (mi-rpc)
[428.352734] Starting subtest: mi-rpc
[428.354550] Subtest mi-rpc: SKIP (0.000s)
[428.429175] [066/132] (615s left) sw_sync (sync_random_merge)
[428.473023] Starting subtest: sync_random_merge
[428.559955] Subtest sync_random_merge: SUCCESS (0.088s)
[428.700459] [067/132] (615s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[430.188433] Starting subtest: psr-rgb101010-draw-pwrite
[430.190010] Subtest psr-rgb101010-draw-pwrite: SKIP (0.000s)
[430.423138] [068/132] (613s left) kms_getfb (getfb2-handle-closed)
[431.906163] Starting subtest: getfb2-handle-closed
[431.907032] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[432.177217] [069/132] (612s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[433.668843] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[433.671023] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[433.917929] [070/132] (610s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[433.962953] Starting subtest: invalid-smem-bo-on-discrete
[433.963768] Subtest invalid-smem-bo-on-discrete: SUCCESS (0.000s)
[435.635600] [071/132] (608s left) perf (gen12-invalid-class-instance)
[435.813988] Starting subtest: gen12-invalid-class-instance
[435.837333] Subtest gen12-invalid-class-instance: SUCCESS (0.023s)
[435.912085] [072/132] (608s left) gem_exec_schedule (implicit-read-write)
[435.968713] Starting subtest: implicit-read-write
[435.969604] Starting dynamic subtest: rcs0
[436.171886] Dynamic subtest rcs0: SUCCESS (0.199s)
[436.182512] Starting dynamic subtest: bcs0
[436.308495] Dynamic subtest bcs0: SUCCESS (0.138s)
[436.321140] Starting dynamic subtest: vcs0
[436.449752] Dynamic subtest vcs0: SUCCESS (0.140s)
[436.462219] Starting dynamic subtest: vcs1
[436.589607] Dynamic subtest vcs1: SUCCESS (0.138s)
[436.604151] Starting dynamic subtest: vecs0
[436.737297] Dynamic subtest vecs0: SUCCESS (0.146s)
[436.738120] Subtest implicit-read-write: SUCCESS (0.769s)
[437.218420] [073/132] (607s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[438.705402] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[438.944653] [074/132] (605s left) kms_atomic (plane-cursor-legacy)
[440.430602] Starting subtest: plane-cursor-legacy
[440.431468] Starting dynamic subtest: pipe-A-HDMI-A-3
[440.753012] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.324s)
[440.753826] Subtest plane-cursor-legacy: SUCCESS (0.327s)
[441.016827] [075/132] (603s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[442.501252] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[442.502180] Starting dynamic subtest: pipe-A-HDMI-A-3
[442.509929] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.001s)
[442.521344] Starting dynamic subtest: pipe-B-HDMI-A-3
[442.529121] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.001s)
[442.540292] Starting dynamic subtest: pipe-C-HDMI-A-3
[442.548399] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.001s)
[442.559261] Starting dynamic subtest: pipe-D-HDMI-A-3
[442.567761] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.001s)
[442.568906] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SUCCESS (0.013s)
[442.824498] [076/132] (602s left) gem_exec_balancer (bonded-pair)
[442.880524] Starting subtest: bonded-pair
[442.882167] Subtest bonded-pair: SKIP (0.000s)
[443.398008] [077/132] (601s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[444.887769] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[444.889684] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[445.134523] [078/132] (600s left) gem_ctx_exec (basic-invalid-context)
[445.179789] Starting subtest: basic-invalid-context
[445.185443] Subtest basic-invalid-context: SUCCESS (0.006s)
[445.395059] [079/132] (600s left) kms_content_protection (lic-type-0-hdcp14)
[446.936617] Starting subtest: lic-type-0-hdcp14
[446.944968] Subtest lic-type-0-hdcp14: SKIP (0.006s)
[447.184128] [080/132] (598s left) gem_eio (wait-immediate)
[447.372966] Starting subtest: wait-immediate
[447.814071] Subtest wait-immediate: SUCCESS (0.441s)
[449.501534] [081/132] (596s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[450.994573] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[451.014583] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.019s)
[451.237437] [082/132] (594s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[452.721165] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[452.722094] Starting dynamic subtest: pipe-A-valid-mode
[453.959516] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.237s)
[453.960711] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SUCCESS (1.240s)
[454.215019] [083/132] (591s left) kms_psr (psr2-basic)
[455.699449] Subtest psr2-basic: SKIP (0.000s)
[455.845523] [084/132] (590s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[457.346051] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[457.346934] Starting dynamic subtest: pipe-A-HDMI-A-3
[457.354991] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.001s)
[457.367084] Starting dynamic subtest: pipe-B-HDMI-A-3
[457.375151] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.001s)
[457.386767] Starting dynamic subtest: pipe-C-HDMI-A-3
[457.394821] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.001s)
[457.406209] Starting dynamic subtest: pipe-D-HDMI-A-3
[457.414903] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.001s)
[457.416592] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.014s)
[457.675170] [085/132] (588s left) kms_vblank (query-forked-busy)
[459.157745] Starting subtest: query-forked-busy
[459.160300] Starting dynamic subtest: pipe-A-HDMI-A-3
[461.746179] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.582s)
[461.801100] Starting dynamic subtest: pipe-D-HDMI-A-3
[464.245346] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.472s)
[464.247359] Subtest query-forked-busy: SUCCESS (5.088s)
[464.481477] [086/132] (581s left) kms_pm_dc (dc9-dpms)
[466.975118] Starting subtest: dc9-dpms
[468.121180] Subtest dc9-dpms: SUCCESS (1.147s)
[468.373684] [087/132] (577s left) gem_userptr_blits (set-cache-level)
[468.423966] Starting subtest: set-cache-level
[468.424929] Subtest set-cache-level: SUCCESS (0.000s)
[468.685518] [088/132] (577s left) gem_exec_fence (keep-in-fence)
[468.859304] Starting subtest: keep-in-fence
[468.860224] Starting dynamic subtest: rcs0
[468.958279] Dynamic subtest rcs0: SUCCESS (0.099s)
[468.975491] Starting dynamic subtest: bcs0
[469.014844] Dynamic subtest bcs0: SUCCESS (0.053s)
[469.031814] Starting dynamic subtest: vcs0
[469.069765] Dynamic subtest vcs0: SUCCESS (0.053s)
[469.090697] Starting dynamic subtest: vcs1
[469.128473] Dynamic subtest vcs1: SUCCESS (0.056s)
[469.170038] Starting dynamic subtest: vecs0
[469.205842] Dynamic subtest vecs0: SUCCESS (0.073s)
[469.207021] Subtest keep-in-fence: SUCCESS (0.349s)
[469.917010] [089/132] (576s left) kms_pm_rpm (modeset-pc8-residency-stress)
[473.832985] Starting subtest: modeset-pc8-residency-stress
[473.835223] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[474.786288] [090/132] (571s left) kms_psr (fbc-psr2-sprite-plane-move)
[476.282320] Subtest fbc-psr2-sprite-plane-move: SKIP (0.000s)
[476.366136] [091/132] (570s left) gem_exec_create (forked)
[476.421236] Starting subtest: forked
[476.422069] Starting dynamic subtest: smem
[496.558278] Dynamic subtest smem: SUCCESS (20.136s)
[496.578781] Starting dynamic subtest: lmem0
[516.716703] Dynamic subtest lmem0: SUCCESS (20.156s)
[516.717734] Subtest forked: SUCCESS (40.296s)
[517.046062] [092/132] (529s left) kms_psr (pr-cursor-mmap-cpu)
[518.530991] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[518.615485] [093/132] (528s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[520.104711] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[520.106462] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[520.356651] [094/132] (526s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[521.850478] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[521.943536] [095/132] (525s left) gem_exec_reloc (basic-wc-read)
[522.000671] Subtest basic-wc-read: SKIP (0.000s)
[522.109023] [096/132] (525s left) kms_psr (psr-cursor-plane-move)
[523.592555] Subtest psr-cursor-plane-move: SKIP (0.000s)
[523.666953] [097/132] (523s left) kms_flip (basic-plain-flip)
[525.151556] Starting subtest: basic-plain-flip
[525.157519] Starting dynamic subtest: A-HDMI-A3
[525.884052] Dynamic subtest A-HDMI-A3: SUCCESS (0.726s)
[525.900118] Starting dynamic subtest: B-HDMI-A3
[526.564653] Dynamic subtest B-HDMI-A3: SUCCESS (0.678s)
[526.578971] Starting dynamic subtest: C-HDMI-A3
[527.250418] Dynamic subtest C-HDMI-A3: SUCCESS (0.684s)
[527.264430] Starting dynamic subtest: D-HDMI-A3
[527.935153] Dynamic subtest D-HDMI-A3: SUCCESS (0.683s)
[527.937739] Subtest basic-plain-flip: SUCCESS (2.787s)
[528.239086] [098/132] (519s left) kms_flip (plain-flip-fb-recreate)
[529.724636] Starting subtest: plain-flip-fb-recreate
[529.730757] Starting dynamic subtest: A-HDMI-A3
[537.789523] Dynamic subtest A-HDMI-A3: SUCCESS (8.057s)
[537.834139] Starting dynamic subtest: B-HDMI-A3
[545.802602] Dynamic subtest B-HDMI-A3: SUCCESS (8.004s)
[545.849724] Starting dynamic subtest: C-HDMI-A3
[553.804220] Dynamic subtest C-HDMI-A3: SUCCESS (7.993s)
[553.849015] Starting dynamic subtest: D-HDMI-A3
[561.805495] Dynamic subtest D-HDMI-A3: SUCCESS (7.992s)
[561.815632] Subtest plain-flip-fb-recreate: SUCCESS (32.090s)
[562.155479] [099/132] (485s left) i915_suspend (debugfs-reader)
[562.201907] Starting subtest: debugfs-reader
[589.545927] Subtest debugfs-reader: SUCCESS (11.853s)
[595.758279] [100/132] (457s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[597.249422] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[597.251070] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[597.518383] [101/132] (456s left) gem_exec_nop (basic-sequential)
[597.573938] Starting subtest: basic-sequential
[601.353944] Subtest basic-sequential: SUCCESS (3.779s)
[601.646675] [102/132] (452s left) gem_pxp (create-regular-buffer)
[604.213776] Starting subtest: create-regular-buffer
[604.216096] Subtest create-regular-buffer: SKIP (0.000s)
[604.359358] [103/132] (449s left) syncobj_basic (test-valid-cycle)
[604.403667] Starting subtest: test-valid-cycle
[604.404490] Subtest test-valid-cycle: SUCCESS (0.000s)
[604.554141] [104/132] (449s left) gem_exec_suspend (basic-s3-devices)
[604.610948] Starting subtest: basic-S3-devices
[604.611798] Starting dynamic subtest: smem
[615.038129] Dynamic subtest smem: SUCCESS (10.428s)
[615.052084] Starting dynamic subtest: lmem0
[625.271915] Dynamic subtest lmem0: SUCCESS (10.232s)
[625.272819] Subtest basic-S3-devices: SUCCESS (20.663s)
[631.606469] [105/132] (428s left) template (a)
[631.651447] Starting subtest: A
[631.652327] Subtest A: SUCCESS (0.000s)
[631.811835] [106/132] (428s left) gem_mmap_gtt (bad-object)
[631.855096] Subtest bad-object: SKIP (0.000s)
[631.935466] [107/132] (428s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[633.425894] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[633.427868] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[633.697998] [108/132] (426s left) api_intel_bb (intel-bb-blit-y)
[633.742761] Starting subtest: intel-bb-blit-y
[635.388349] Subtest intel-bb-blit-y: SUCCESS (1.642s)
[635.636008] [109/132] (424s left) kms_selftest (drm_format_helper)
[635.680767] Starting subtest: drm_format_helper
[635.754422] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[635.829923] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.092s)
[635.843444] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[635.933614] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.097s)
[635.977697] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[636.012430] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.076s)
[636.030432] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[636.109284] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.089s)
[636.146584] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[636.197815] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.085s)
[636.225708] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[636.297628] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.094s)
[636.340699] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[636.387681] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.086s)
[636.409769] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[636.483553] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.090s)
[636.522639] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[636.558793] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.071s)
[636.574766] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[636.636082] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.074s)
[636.678975] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[636.726054] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.084s)
[636.752833] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[636.824580] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.096s)
[636.869842] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[636.906662] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.075s)
[636.924496] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[637.009696] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.099s)
[637.054729] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[637.099771] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.082s)
[637.125473] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[637.205086] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.103s)
[637.246438] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[637.297199] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.086s)
[637.300923] Subtest drm_format_helper: SUCCESS (1.618s)
[637.600644] [110/132] (423s left) gem_mmap_offset (oob-read)
[637.645471] Starting subtest: oob-read
[637.646478] Subtest oob-read: SUCCESS (0.000s)
[637.832183] [111/132] (422s left) gem_spin_batch (resubmit-all)
[637.887810] Starting subtest: resubmit-all
[637.888853] Starting dynamic subtest: rcs0
[637.913216] Dynamic subtest rcs0: SUCCESS (0.025s)
[637.928062] Starting dynamic subtest: bcs0
[637.929234] Dynamic subtest bcs0: SUCCESS (0.002s)
[637.941969] Starting dynamic subtest: vcs0
[637.943328] Dynamic subtest vcs0: SUCCESS (0.002s)
[637.955544] Starting dynamic subtest: vcs1
[637.956906] Dynamic subtest vcs1: SUCCESS (0.002s)
[637.969027] Starting dynamic subtest: vecs0
[637.970369] Dynamic subtest vecs0: SUCCESS (0.002s)
[637.971708] Subtest resubmit-all: SUCCESS (0.041s)
[638.242365] [112/132] (422s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[639.736560] Starting subtest: cursorB-vs-flipA-toggle
[639.742968] Subtest cursorB-vs-flipA-toggle: SKIP (0.003s)
[639.962236] [113/132] (420s left) gen9_exec_parse (valid-registers)
[640.011607] Subtest valid-registers: SKIP (0.000s)
[640.085891] [114/132] (420s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[641.570812] Starting subtest: disable-crc-after-crtc
[641.572020] Starting dynamic subtest: pipe-A-HDMI-A-3
[642.257562] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.685s)
[642.271341] Starting dynamic subtest: pipe-B-HDMI-A-3
[642.720203] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.459s)
[642.737264] Starting dynamic subtest: pipe-C-HDMI-A-3
[643.201424] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.479s)
[643.216297] Starting dynamic subtest: pipe-D-HDMI-A-3
[643.686021] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.481s)
[643.687052] Subtest disable-crc-after-crtc: SUCCESS (2.116s)
[643.907168] [115/132] (417s left) gem_exec_fence (syncobj-unused-fence)
[644.328849] Starting subtest: syncobj-unused-fence
[644.349686] Subtest syncobj-unused-fence: SUCCESS (0.021s)
[644.798492] [116/132] (416s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[646.288639] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[646.290497] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[646.532948] [117/132] (414s left) gem_set_tiling_vs_pwrite
[646.656129] [118/132] (414s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[648.168418] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[648.170435] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[648.415727] [119/132] (413s left) vgem_basic (busy-fence)
[648.475623] Starting subtest: busy-fence
[648.476449] Subtest busy-fence: SUCCESS (0.000s)
[648.672258] [120/132] (412s left) i915_pm_rpm (debugfs-read)
[652.289564] Starting subtest: debugfs-read
[653.290200] Subtest debugfs-read: SUCCESS (0.990s)
[654.361324] [121/132] (407s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[655.852204] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[659.108829] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (3.257s)
[659.394537] [122/132] (402s left) perf_pmu (busy-start)
[659.444802] Starting subtest: busy-start
[659.445654] Starting dynamic subtest: rcs0
[662.007428] Dynamic subtest rcs0: SUCCESS (2.563s)
[662.024059] Starting dynamic subtest: bcs0
[664.555440] Dynamic subtest bcs0: SUCCESS (2.546s)
[664.572433] Starting dynamic subtest: vcs0
[667.103448] Dynamic subtest vcs0: SUCCESS (2.546s)
[667.120058] Starting dynamic subtest: vcs1
[669.651423] Dynamic subtest vcs1: SUCCESS (2.546s)
[669.668050] Starting dynamic subtest: vecs0
[672.199405] Dynamic subtest vecs0: SUCCESS (2.546s)
[672.202466] Subtest busy-start: SUCCESS (12.757s)
[672.360109] [123/132] (389s left) kms_addfb_basic (size-max)
[673.846926] Starting subtest: size-max
[673.847773] Subtest size-max: SUCCESS (0.000s)
[674.074249] [124/132] (388s left) kms_psr (fbc-pr-primary-blt)
[675.558818] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[675.637198] [125/132] (386s left) kms_color_pipeline (plane-ctm3x4)
[677.124830] Starting subtest: plane-ctm3x4
[677.158896] Starting dynamic subtest: pipe-A-HDMI-A-3
[677.558896] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.400s)
[677.722541] Starting dynamic subtest: pipe-B-HDMI-A-3
[678.026865] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.315s)
[678.155335] Starting dynamic subtest: pipe-C-HDMI-A-3
[678.476892] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.332s)
[678.607574] Starting dynamic subtest: pipe-D-HDMI-A-3
[678.945262] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.349s)
[679.000554] Subtest plane-ctm3x4: SUCCESS (1.876s)
[679.249991] [126/132] (383s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[680.775375] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[680.890858] [127/132] (381s left) api_intel_allocator (standalone)
[680.934788] Starting subtest: standalone
[680.969727] Subtest standalone: SUCCESS (0.035s)
[681.201459] [128/132] (381s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[682.690744] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[682.692565] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[682.947654] [129/132] (379s left) kms_big_fb (y-tiled-addfb)
[684.451010] Starting subtest: y-tiled-addfb
[684.451995] Subtest y-tiled-addfb: SUCCESS (0.000s)
[684.712180] [130/132] (378s left) kms_prime (basic-crc-vgem)
[686.221010] Subtest basic-crc-vgem: SKIP (0.000s)
[686.454122] [131/132] (376s left) i915_hangman (engine-error-state-capture)
[686.512016] Starting subtest: engine-error-state-capture
[686.512860] Starting dynamic subtest: rcs0
[688.708965] Dynamic subtest rcs0: SUCCESS (2.196s)
[688.725209] Starting dynamic subtest: bcs0
[692.741175] Dynamic subtest bcs0: SUCCESS (4.030s)
[692.758813] Starting dynamic subtest: vcs0
[696.690950] Dynamic subtest vcs0: SUCCESS (3.947s)
[696.706233] Starting dynamic subtest: vcs1
[700.726715] Dynamic subtest vcs1: SUCCESS (4.033s)
[700.750174] Starting dynamic subtest: vecs0
[704.701849] Dynamic subtest vecs0: SUCCESS (3.969s)
[704.702880] Subtest engine-error-state-capture: SUCCESS (18.191s)
[704.913311] [132/132] (358s left) kms_invalid_mode (bad-vsync-end)
[706.396994] Starting subtest: bad-vsync-end
[706.399409] Starting dynamic subtest: pipe-A-HDMI-A-3
[706.447490] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.048s)
[706.461557] Starting dynamic subtest: pipe-B-HDMI-A-3
[706.462451] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.001s)
[706.475620] Starting dynamic subtest: pipe-C-HDMI-A-3
[706.476687] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.001s)
[706.488755] Starting dynamic subtest: pipe-D-HDMI-A-3
[706.489604] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.001s)
[706.490412] Subtest bad-vsync-end: SUCCESS (0.061s)
[706.675009] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_busy
results: parsing output: 5/ for test: perf
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_pipe_crc_basic
results: parsing output: 8/ for test: perf_pmu
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gem_sync
results: parsing output: 12/ for test: gem_ctx_persistence
results: parsing output: 13/ for test: kms_plane
results: parsing output: 14/ for test: prime_vgem
results: parsing output: 15/ for test: i915_query
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_cursor_crc
results: parsing output: 19/ for test: gem_render_copy
results: parsing output: 20/ for test: kms_rotation_crc
results: parsing output: 21/ for test: gem_render_copy
results: parsing output: 22/ for test: gem_exec_schedule
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_psr2_sf
results: parsing output: 27/ for test: kms_cursor_crc
results: parsing output: 28/ for test: prime_mmap
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: fbdev
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_balancer
results: parsing output: 35/ for test: gem_ccs
results: parsing output: 36/ for test: gem_fenced_exec_thrash
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_mmap_wc
results: parsing output: 40/ for test: kms_cursor_legacy
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_addfb_basic
results: parsing output: 43/ for test: gem_ctx_shared
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: kms_force_connector_basic
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: kms_chamelium_audio
results: parsing output: 49/ for test: gem_set_tiling_vs_gtt
results: parsing output: 50/ for test: gem_lmem_swapping
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: prime_busy
results: parsing output: 53/ for test: syncobj_wait
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: gem_gtt_cpu_tlb
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gen9_exec_parse
results: parsing output: 58/ for test: gem_softpin
results: parsing output: 59/ for test: dmabuf_sync_file
results: parsing output: 60/ for test: kms_dither
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_chamelium_hpd
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: perf
results: parsing output: 65/ for test: sw_sync
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_getfb
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_addfb_basic
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: kms_addfb_basic
results: parsing output: 73/ for test: kms_atomic
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: gem_exec_balancer
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_ctx_exec
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: gem_eio
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_vblank
results: parsing output: 85/ for test: kms_pm_dc
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: gem_exec_fence
results: parsing output: 88/ for test: kms_pm_rpm
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: gem_exec_create
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: gem_exec_reloc
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: i915_suspend
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_exec_nop
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: syncobj_basic
results: parsing output: 103/ for test: gem_exec_suspend
results: parsing output: 104/ for test: template
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: kms_selftest
results: parsing output: 109/ for test: gem_mmap_offset
results: parsing output: 110/ for test: gem_spin_batch
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: gen9_exec_parse
results: parsing output: 113/ for test: kms_pipe_crc_basic
results: parsing output: 114/ for test: gem_exec_fence
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: vgem_basic
results: parsing output: 119/ for test: i915_pm_rpm
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: perf_pmu
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: kms_color_pipeline
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: api_intel_allocator
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: kms_prime
results: parsing output: 130/ for test: i915_hangman
results: parsing output: 131/ for test: kms_invalid_mode
Done.