[66.999851] Initializing watchdogs
[66.999929]   /dev/watchdog0
[67.030216] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[67.045181] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[67.050115] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[67.052756] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot)
[67.100463] Starting subtest: wait-for-submit-snapshot
[67.183959] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[67.332044] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[68.816900] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[68.819053] Starting dynamic subtest: pipe-A-HDMI-A-1
[68.843436] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[68.850775] Starting dynamic subtest: pipe-B-HDMI-A-1
[68.870756] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[68.878119] Starting dynamic subtest: pipe-C-HDMI-A-1
[68.898273] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[68.905490] Starting dynamic subtest: pipe-D-HDMI-A-1
[68.925822] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.003s)
[68.927355] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.016s)
[69.111102] [003/132] (958s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[70.601852] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[73.240512] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (2.637s)
[73.519475] [004/132] (954s left) kms_big_fb (linear-8bpp-rotate-270)
[75.027333] Starting subtest: linear-8bpp-rotate-270
[75.143955] Subtest linear-8bpp-rotate-270: SKIP (0.116s)
[75.354035] [005/132] (952s left) kms_busy (extended-modeset-hang-newfb)
[76.847565] Starting subtest: extended-modeset-hang-newfb
[76.849749] Starting dynamic subtest: pipe-A
[92.462972] Dynamic subtest pipe-A: SUCCESS (15.611s)
[92.474112] Starting dynamic subtest: pipe-D
[107.980934] Dynamic subtest pipe-D: SUCCESS (15.513s)
[107.981807] Subtest extended-modeset-hang-newfb: SUCCESS (31.131s)
[108.251294] [006/132] (919s left) perf (oa-exponents)
[108.400294] Starting subtest: oa-exponents
[108.401146] Starting dynamic subtest: 0-rcs0
[112.119628] Dynamic subtest 0-rcs0: SUCCESS (3.716s)
[112.120491] Subtest oa-exponents: SUCCESS (3.716s)
[112.195345] [007/132] (915s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[113.698899] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[114.971789] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.272s)
[115.229747] [008/132] (912s left) kms_pipe_crc_basic (suspend-read-crc)
[116.712904] Starting subtest: suspend-read-crc
[116.715060] Starting dynamic subtest: pipe-A-HDMI-A-1
[138.781539] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (11.175s)
[138.792645] Starting dynamic subtest: pipe-B-HDMI-A-1
[160.746329] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (10.744s)
[160.757333] Starting dynamic subtest: pipe-C-HDMI-A-1
[182.765863] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (10.679s)
[182.777032] Starting dynamic subtest: pipe-D-HDMI-A-1
[204.692656] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (10.645s)
[204.693533] Subtest suspend-read-crc: SUCCESS (43.250s)
[209.918956] [009/132] (823s left) perf_pmu (interrupts-sync)
[209.966764] Starting subtest: interrupts-sync
[211.136990] Subtest interrupts-sync: SUCCESS (1.166s)
[211.306920] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true
[211.308904] [010/132] (821s left) gem_exec_reloc (basic-gtt-active)
[211.357334] Subtest basic-gtt-active: SKIP (0.000s)
[211.451677] [011/132] (821s left) kms_flip (modeset-vs-vblank-race)
[212.936346] Starting subtest: modeset-vs-vblank-race
[212.940815] Starting dynamic subtest: A-HDMI-A1
[216.039458] Dynamic subtest A-HDMI-A1: SUCCESS (3.099s)
[216.052217] Starting dynamic subtest: B-HDMI-A1
[219.087337] Dynamic subtest B-HDMI-A1: SUCCESS (3.047s)
[219.099642] Starting dynamic subtest: C-HDMI-A1
[222.112161] Dynamic subtest C-HDMI-A1: SUCCESS (3.024s)
[222.124686] Starting dynamic subtest: D-HDMI-A1
[225.083926] Dynamic subtest D-HDMI-A1: SUCCESS (2.970s)
[225.087639] Subtest modeset-vs-vblank-race: SUCCESS (12.152s)
[225.380006] [012/132] (807s left) gem_sync (basic-each)
[225.444906] Starting subtest: basic-each
[227.559285] Subtest basic-each: SUCCESS (2.114s)
[227.897028] [013/132] (805s left) gem_ctx_persistence (legacy-engines-mixed)
[227.949034] Starting subtest: legacy-engines-mixed
[227.949959] Starting dynamic subtest: render
[228.031383] Dynamic subtest render: SUCCESS (0.081s)
[228.049365] Starting dynamic subtest: bsd1
[228.093879] Dynamic subtest bsd1: SUCCESS (0.060s)
[228.109496] Starting dynamic subtest: bsd2
[228.162616] Dynamic subtest bsd2: SUCCESS (0.064s)
[228.197364] Starting dynamic subtest: blt
[228.251588] Dynamic subtest blt: SUCCESS (0.084s)
[228.296024] Starting dynamic subtest: vebox
[228.342559] Dynamic subtest vebox: SUCCESS (0.083s)
[228.346258] Subtest legacy-engines-mixed: SUCCESS (0.395s)
[228.609150] [014/132] (804s left) kms_plane (plane-panning-bottom-right)
[230.095919] Starting subtest: plane-panning-bottom-right
[230.097011] Starting dynamic subtest: pipe-A
[230.786902] Dynamic subtest pipe-A: SUCCESS (0.690s)
[230.799651] Starting dynamic subtest: pipe-B
[231.318158] Dynamic subtest pipe-B: SUCCESS (0.528s)
[231.319077] Subtest plane-panning-bottom-right: SUCCESS (1.224s)
[231.581597] [015/132] (802s left) prime_vgem (wait)
[231.655470] Starting subtest: wait
[231.656428] Starting dynamic subtest: rcs0
[231.704052] Dynamic subtest rcs0: SUCCESS (0.048s)
[231.716797] Starting dynamic subtest: bcs0
[231.759507] Dynamic subtest bcs0: SUCCESS (0.054s)
[231.777560] Starting dynamic subtest: vcs0
[231.803677] Dynamic subtest vcs0: SUCCESS (0.042s)
[231.826218] Starting dynamic subtest: vcs1
[231.846348] Dynamic subtest vcs1: SUCCESS (0.040s)
[231.868688] Starting dynamic subtest: vecs0
[231.888133] Dynamic subtest vecs0: SUCCESS (0.039s)
[231.889303] Subtest wait: SUCCESS (0.234s)
[232.158604] [016/132] (801s left) i915_query (query-topology-matches-eu-total)
[232.202021] Starting subtest: query-topology-matches-eu-total
[232.202945] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[232.409050] [017/132] (801s left) syncobj_timeline (reset-signaled)
[232.456461] Starting subtest: reset-signaled
[232.457457] Subtest reset-signaled: SUCCESS (0.000s)
[232.655453] [018/132] (801s left) syncobj_timeline (multi-wait-all-available-signaled)
[232.700763] Starting subtest: multi-wait-all-available-signaled
[232.702137] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[232.857152] [019/132] (801s left) kms_cursor_crc (cursor-random-256x256)
[234.561463] Starting subtest: cursor-random-256x256
[234.568110] Starting dynamic subtest: pipe-A-HDMI-A-1
[239.173412] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.605s)
[239.209463] Starting dynamic subtest: pipe-D-HDMI-A-1
[243.652784] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (4.455s)
[243.653734] Subtest cursor-random-256x256: SUCCESS (9.092s)
[244.019159] [020/132] (790s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[244.073052] Starting subtest: mixed-tiled-to-y-tiled-ccs
[244.074157] Starting dynamic subtest: smem
[244.184808] Dynamic subtest smem: SUCCESS (0.108s)
[244.195887] Starting dynamic subtest: lmem0
[245.140464] Dynamic subtest lmem0: SUCCESS (0.954s)
[245.141723] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (1.065s)
[245.452188] [021/132] (788s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[246.937624] Starting subtest: primary-y-tiled-reflect-x-180
[257.357360] Subtest primary-y-tiled-reflect-x-180: SUCCESS (10.420s)
[257.673612] [022/132] (776s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[257.725990] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[257.726999] Starting dynamic subtest: smem
[257.823469] Dynamic subtest smem: SUCCESS (0.097s)
[257.836656] Starting dynamic subtest: lmem0
[258.940410] Dynamic subtest lmem0: SUCCESS (1.113s)
[258.941524] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SUCCESS (1.212s)
[259.290551] [023/132] (775s left) gem_exec_schedule (reorder-wide)
[259.465402] Subtest reorder-wide: SKIP (0.000s)
[259.726663] [024/132] (774s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[261.217141] Starting subtest: fbc-indfb-scaledprimary
[264.924005] Subtest fbc-indfb-scaledprimary: SUCCESS (3.706s)
[265.197368] [025/132] (769s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[266.688056] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[266.689850] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[266.942615] [026/132] (767s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[268.427534] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[268.428376] Starting dynamic subtest: pipe-A-HDMI-A-1
[268.431660] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[268.442779] Starting dynamic subtest: pipe-B-HDMI-A-1
[268.445847] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[268.456515] Starting dynamic subtest: pipe-C-HDMI-A-1
[268.459565] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[268.470251] Starting dynamic subtest: pipe-D-HDMI-A-1
[268.473381] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[268.475002] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.010s)
[268.744866] [027/132] (766s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[270.236171] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[270.407630] [028/132] (764s left) kms_cursor_crc (cursor-random-64x21)
[272.075741] Starting subtest: cursor-random-64x21
[272.082551] Starting dynamic subtest: pipe-A-HDMI-A-1
[276.759652] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.676s)
[276.795055] Starting dynamic subtest: pipe-D-HDMI-A-1
[281.289787] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (4.506s)
[281.290711] Subtest cursor-random-64x21: SUCCESS (9.215s)
[281.806033] [029/132] (753s left) prime_mmap (test_dup)
[281.851146] Starting subtest: test_dup
[281.852162] Starting dynamic subtest: test_dup-smem
[281.914323] Dynamic subtest test_dup-smem: SUCCESS (0.063s)
[281.927187] Starting dynamic subtest: test_dup-lmem0
[281.978933] Dynamic subtest test_dup-lmem0: SUCCESS (0.063s)
[281.979960] Subtest test_dup: SUCCESS (0.129s)
[282.164057] [030/132] (753s left) gem_exec_schedule (u-submit-late-slice)
[282.230347] Starting subtest: u-submit-late-slice
[282.231214] Starting dynamic subtest: rcs0
[282.289735] Dynamic subtest rcs0: SUCCESS (0.059s)
[282.302728] Starting dynamic subtest: bcs0
[282.330158] Dynamic subtest bcs0: SUCCESS (0.039s)
[282.343092] Starting dynamic subtest: vcs0
[282.371397] Dynamic subtest vcs0: SUCCESS (0.040s)
[282.384844] Starting dynamic subtest: vcs1
[282.410344] Dynamic subtest vcs1: SUCCESS (0.037s)
[282.423649] Starting dynamic subtest: vecs0
[282.451472] Dynamic subtest vecs0: SUCCESS (0.040s)
[282.452293] Subtest u-submit-late-slice: SUCCESS (0.222s)
[282.939061] [031/132] (752s left) syncobj_timeline (etime-multi-wait-all-submitted)
[282.982983] Starting subtest: etime-multi-wait-all-submitted
[283.088101] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s)
[283.273748] [032/132] (752s left) fbdev (write)
[283.332604] Starting subtest: write
[284.056040] Subtest write: SUCCESS (0.722s)
[284.180916] [033/132] (751s left) syncobj_wait (invalid-signal-zero-handles)
[284.226143] Starting subtest: invalid-signal-zero-handles
[284.227078] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[284.402601] [034/132] (751s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[285.892166] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[285.894104] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[286.130666] [035/132] (749s left) gem_exec_balancer (sliced)
[286.187599] Starting subtest: sliced
[286.191900] Subtest sliced: SKIP (0.000s)
[286.657041] [036/132] (749s left) gem_ccs (large-ctrl-surf-copy)
[286.705748] Starting subtest: large-ctrl-surf-copy
[286.707441] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[286.864931] [037/132] (748s left) gem_fenced_exec_thrash (2-spare-fences)
[286.913177] Subtest 2-spare-fences: SKIP (0.000s)
[286.992715] [038/132] (748s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[288.477626] Starting subtest: basic-busy-flip-before-cursor-varying-size
[288.479349] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[288.714564] [039/132] (747s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[290.250395] Starting subtest: 4-tiled-8bpp-rotate-0
[290.252125] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[290.480971] [040/132] (745s left) gem_mmap_wc (copy)
[290.525071] Subtest copy: SKIP (0.000s)
[290.610327] [041/132] (745s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[292.095640] Starting subtest: cursor-vs-flip-toggle
[327.118433] Subtest cursor-vs-flip-toggle: SUCCESS (35.024s)
[327.350409] [042/132] (708s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[328.854092] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[328.855822] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[329.079128] [043/132] (707s left) kms_addfb_basic (unused-handle)
[329.147709] Starting subtest: unused-handle
[329.148511] Subtest unused-handle: SUCCESS (0.000s)
[330.874711] [044/132] (705s left) gem_ctx_shared (q-smoketest-all)
[330.933517] Starting subtest: Q-smoketest-all
[363.236297] Subtest Q-smoketest-all: SUCCESS (32.294s)
[363.622778] [045/132] (673s left) kms_flip (2x-flip-vs-expired-vblank)
[365.108681] Starting subtest: 2x-flip-vs-expired-vblank
[365.129671] Subtest 2x-flip-vs-expired-vblank: SKIP (0.021s)
[365.337689] [046/132] (671s left) kms_force_connector_basic (force-edid)
[365.490848] Starting subtest: force-edid
[365.625679] Subtest force-edid: SUCCESS (0.134s)
[366.638943] [047/132] (670s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[368.146181] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[369.396878] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.251s)
[369.721889] [048/132] (667s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[371.206422] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[371.207200] Starting dynamic subtest: pipe-A
[371.369862] Dynamic subtest pipe-A: SUCCESS (0.163s)
[371.393154] Starting dynamic subtest: pipe-B
[371.546174] Dynamic subtest pipe-B: SUCCESS (0.174s)
[371.559183] Starting dynamic subtest: pipe-C
[371.687290] Dynamic subtest pipe-C: SUCCESS (0.140s)
[371.700798] Starting dynamic subtest: pipe-D
[371.827438] Dynamic subtest pipe-D: SUCCESS (0.139s)
[371.828322] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.622s)
[372.057474] [049/132] (665s left) kms_chamelium_audio (hdmi-audio)
[373.592634] Subtest hdmi-audio: SKIP (0.000s)
[373.711592] [050/132] (663s left) gem_set_tiling_vs_gtt
[373.845694] [051/132] (663s left) gem_lmem_swapping (massive)
[378.268014] Starting subtest: massive
[378.269123] Starting dynamic subtest: lmem0
[389.311866] Dynamic subtest lmem0: SUCCESS (11.040s)
[389.312730] Subtest massive: SUCCESS (11.042s)
[390.987455] [FACT gem_lmem_swapping (massive)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[390.989358] [FACT gem_lmem_swapping (massive)] deleted: kernel.kmod_is_loaded.i915: true
[390.991549] [052/132] (646s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[395.775958] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[395.777505] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[396.034475] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[396.037663] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: kernel.kmod_is_loaded.i915: true
[396.043983] [053/132] (641s left) prime_busy (after)
[396.102816] Starting subtest: after
[396.109638] Starting dynamic subtest: rcs0
[396.149792] Dynamic subtest rcs0: SUCCESS (0.040s)
[396.163578] Starting dynamic subtest: bcs0
[396.207395] Dynamic subtest bcs0: SUCCESS (0.056s)
[396.224724] Starting dynamic subtest: vcs0
[396.250979] Dynamic subtest vcs0: SUCCESS (0.041s)
[396.272657] Starting dynamic subtest: vcs1
[396.292846] Dynamic subtest vcs1: SUCCESS (0.039s)
[396.314315] Starting dynamic subtest: vecs0
[396.333062] Dynamic subtest vecs0: SUCCESS (0.038s)
[396.445344] Subtest after: SUCCESS (0.339s)
[396.701969] [054/132] (640s left) syncobj_wait (invalid-signal-illegal-handle)
[396.747708] Starting subtest: invalid-signal-illegal-handle
[396.748489] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[396.923338] [055/132] (640s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[398.417694] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[398.418669] Starting dynamic subtest: pipe-A-valid-mode
[398.466997] Dynamic subtest pipe-A-valid-mode: SKIP (0.047s)
[398.468812] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.049s)
[398.673309] [056/132] (639s left) gem_gtt_cpu_tlb
[398.795015] [057/132] (639s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[400.284184] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[400.286076] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[400.533261] [058/132] (637s left) gen9_exec_parse (batch-zero-length)
[400.582768] Subtest batch-zero-length: SKIP (0.000s)
[400.660246] [059/132] (637s left) gem_softpin (evict-snoop-interruptible)
[400.712940] Starting subtest: evict-snoop-interruptible
[400.714641] Subtest evict-snoop-interruptible: SKIP (0.000s)
[400.912772] [060/132] (637s left) dmabuf_sync_file (export-basic)
[400.967224] Starting subtest: export-basic
[400.968130] Subtest export-basic: SUCCESS (0.001s)
[401.153119] [061/132] (637s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[402.637523] Starting subtest: fb-8bpc-vs-panel-6bpc
[402.640079] Subtest fb-8bpc-vs-panel-6bpc: SKIP (0.001s)
[402.848983] [062/132] (635s left) syncobj_timeline (host-signal-ordered)
[402.893983] Starting subtest: host-signal-ordered
[402.894778] Subtest host-signal-ordered: SUCCESS (0.001s)
[403.102144] [063/132] (635s left) kms_chamelium_hpd (common-hpd-after-suspend)
[404.641259] Subtest common-hpd-after-suspend: SKIP (0.000s)
[404.757762] [064/132] (633s left) kms_flip (2x-flip-vs-fences)
[406.241908] Starting subtest: 2x-flip-vs-fences
[406.243528] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[406.457516] [065/132] (632s left) perf (mi-rpc)
[406.620541] Starting subtest: mi-rpc
[406.622299] Subtest mi-rpc: SKIP (0.000s)
[406.696611] [066/132] (632s left) sw_sync (sync_random_merge)
[406.738676] Starting subtest: sync_random_merge
[406.826725] Subtest sync_random_merge: SUCCESS (0.088s)
[406.994768] [067/132] (631s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[408.491475] Starting subtest: psr-rgb101010-draw-pwrite
[408.493052] Subtest psr-rgb101010-draw-pwrite: SKIP (0.000s)
[408.746959] [068/132] (630s left) kms_getfb (getfb2-handle-closed)
[410.232571] Starting subtest: getfb2-handle-closed
[410.233447] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[410.509501] [069/132] (628s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[411.998787] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[412.000623] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[412.250324] [070/132] (627s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[412.294545] Starting subtest: invalid-smem-bo-on-discrete
[412.295366] Subtest invalid-smem-bo-on-discrete: SUCCESS (0.000s)
[413.989672] [071/132] (625s left) perf (gen12-invalid-class-instance)
[414.140154] Starting subtest: gen12-invalid-class-instance
[414.163831] Subtest gen12-invalid-class-instance: SUCCESS (0.023s)
[414.295067] [072/132] (625s left) gem_exec_schedule (implicit-read-write)
[414.354571] Starting subtest: implicit-read-write
[414.355414] Starting dynamic subtest: rcs0
[414.556609] Dynamic subtest rcs0: SUCCESS (0.200s)
[414.568120] Starting dynamic subtest: bcs0
[414.689920] Dynamic subtest bcs0: SUCCESS (0.135s)
[414.703104] Starting dynamic subtest: vcs0
[414.832504] Dynamic subtest vcs0: SUCCESS (0.141s)
[414.845505] Starting dynamic subtest: vcs1
[414.971576] Dynamic subtest vcs1: SUCCESS (0.138s)
[414.984600] Starting dynamic subtest: vecs0
[415.120912] Dynamic subtest vecs0: SUCCESS (0.148s)
[415.121781] Subtest implicit-read-write: SUCCESS (0.768s)
[415.598461] [073/132] (624s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[417.084208] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[417.332752] [074/132] (622s left) kms_atomic (plane-cursor-legacy)
[418.817152] Starting subtest: plane-cursor-legacy
[418.819705] Starting dynamic subtest: pipe-A-HDMI-A-1
[419.148787] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.330s)
[419.149633] Subtest plane-cursor-legacy: SUCCESS (0.332s)
[419.398056] [075/132] (620s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[420.883577] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[420.884552] Starting dynamic subtest: pipe-A-HDMI-A-1
[420.892534] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.001s)
[420.904097] Starting dynamic subtest: pipe-B-HDMI-A-1
[420.911949] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[420.923114] Starting dynamic subtest: pipe-C-HDMI-A-1
[420.931137] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[420.942174] Starting dynamic subtest: pipe-D-HDMI-A-1
[420.951738] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.001s)
[420.952491] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SUCCESS (0.013s)
[421.178995] [076/132] (618s left) gem_exec_balancer (bonded-pair)
[421.235712] Starting subtest: bonded-pair
[421.237456] Subtest bonded-pair: SKIP (0.000s)
[421.700429] [077/132] (618s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[423.188804] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[423.190631] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[423.434321] [078/132] (616s left) gem_ctx_exec (basic-invalid-context)
[423.513739] Starting subtest: basic-invalid-context
[423.518748] Subtest basic-invalid-context: SUCCESS (0.006s)
[423.761319] [079/132] (616s left) kms_content_protection (lic-type-0-hdcp14)
[425.318078] Starting subtest: lic-type-0-hdcp14
[425.327217] Subtest lic-type-0-hdcp14: SKIP (0.006s)
[425.580348] [080/132] (614s left) gem_eio (wait-immediate)
[425.769520] Starting subtest: wait-immediate
[426.205726] Subtest wait-immediate: SUCCESS (0.436s)
[427.894144] [081/132] (612s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[429.383856] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[429.406513] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.022s)
[429.601008] [082/132] (611s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[431.085548] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[431.086510] Starting dynamic subtest: pipe-A-valid-mode
[432.378996] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.292s)
[432.379868] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SUCCESS (1.294s)
[432.623825] [083/132] (608s left) kms_psr (psr2-basic)
[434.108186] Subtest psr2-basic: SKIP (0.000s)
[434.321792] [084/132] (606s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[435.806102] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[435.808528] Starting dynamic subtest: pipe-A-HDMI-A-1
[435.817096] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[435.829530] Starting dynamic subtest: pipe-B-HDMI-A-1
[435.838379] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[435.850637] Starting dynamic subtest: pipe-C-HDMI-A-1
[435.859801] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[435.871748] Starting dynamic subtest: pipe-D-HDMI-A-1
[435.881105] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[435.882771] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.014s)
[436.134174] [085/132] (604s left) kms_vblank (query-forked-busy)
[437.618487] Starting subtest: query-forked-busy
[437.619633] Starting dynamic subtest: pipe-A-HDMI-A-1
[440.203667] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.583s)
[440.257513] Starting dynamic subtest: pipe-D-HDMI-A-1
[442.687550] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.453s)
[442.689581] Subtest query-forked-busy: SUCCESS (5.069s)
[442.925584] [086/132] (598s left) kms_pm_dc (dc9-dpms)
[445.419855] Starting subtest: dc9-dpms
[446.546756] Subtest dc9-dpms: SUCCESS (1.126s)
[446.802687] [087/132] (594s left) gem_userptr_blits (set-cache-level)
[446.853366] Starting subtest: set-cache-level
[446.854376] Subtest set-cache-level: SUCCESS (0.000s)
[447.126462] [088/132] (594s left) gem_exec_fence (keep-in-fence)
[447.299171] Starting subtest: keep-in-fence
[447.300127] Starting dynamic subtest: rcs0
[447.387552] Dynamic subtest rcs0: SUCCESS (0.087s)
[447.408938] Starting dynamic subtest: bcs0
[447.445798] Dynamic subtest bcs0: SUCCESS (0.056s)
[447.463444] Starting dynamic subtest: vcs0
[447.502238] Dynamic subtest vcs0: SUCCESS (0.053s)
[447.522040] Starting dynamic subtest: vcs1
[447.557468] Dynamic subtest vcs1: SUCCESS (0.053s)
[447.589421] Starting dynamic subtest: vecs0
[447.625449] Dynamic subtest vecs0: SUCCESS (0.065s)
[447.629447] Subtest keep-in-fence: SUCCESS (0.328s)
[448.326814] [089/132] (593s left) kms_pm_rpm (modeset-pc8-residency-stress)
[452.750789] Starting subtest: modeset-pc8-residency-stress
[452.753041] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[453.737077] [090/132] (587s left) kms_psr (fbc-psr2-sprite-plane-move)
[455.220576] Subtest fbc-psr2-sprite-plane-move: SKIP (0.000s)
[455.295023] [091/132] (586s left) gem_exec_create (forked)
[455.350630] Starting subtest: forked
[455.351500] Starting dynamic subtest: smem
[475.495816] Dynamic subtest smem: SUCCESS (20.145s)
[475.518510] Starting dynamic subtest: lmem0
[495.650463] Dynamic subtest lmem0: SUCCESS (20.153s)
[495.651397] Subtest forked: SUCCESS (40.301s)
[495.980337] [092/132] (545s left) kms_psr (pr-cursor-mmap-cpu)
[497.464378] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[497.662510] [093/132] (544s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[499.151078] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[499.152763] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[499.400290] [094/132] (542s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[500.892157] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[500.967565] [095/132] (541s left) gem_exec_reloc (basic-wc-read)
[501.106217] Subtest basic-wc-read: SKIP (0.000s)
[501.229440] [096/132] (541s left) kms_psr (psr-cursor-plane-move)
[502.713295] Subtest psr-cursor-plane-move: SKIP (0.000s)
[502.795237] [097/132] (539s left) kms_flip (basic-plain-flip)
[504.280276] Starting subtest: basic-plain-flip
[504.284486] Starting dynamic subtest: A-HDMI-A1
[505.016269] Dynamic subtest A-HDMI-A1: SUCCESS (0.731s)
[505.060785] Starting dynamic subtest: B-HDMI-A1
[505.725987] Dynamic subtest B-HDMI-A1: SUCCESS (0.702s)
[505.741085] Starting dynamic subtest: C-HDMI-A1
[506.412021] Dynamic subtest C-HDMI-A1: SUCCESS (0.684s)
[506.427054] Starting dynamic subtest: D-HDMI-A1
[507.096327] Dynamic subtest D-HDMI-A1: SUCCESS (0.683s)
[507.100238] Subtest basic-plain-flip: SUCCESS (2.821s)
[507.396697] [098/132] (535s left) kms_flip (plain-flip-fb-recreate)
[508.880102] Starting subtest: plain-flip-fb-recreate
[508.885666] Starting dynamic subtest: A-HDMI-A1
[516.900661] Dynamic subtest A-HDMI-A1: SUCCESS (8.014s)
[516.948437] Starting dynamic subtest: B-HDMI-A1
[524.896693] Dynamic subtest B-HDMI-A1: SUCCESS (7.987s)
[524.940543] Starting dynamic subtest: C-HDMI-A1
[532.898538] Dynamic subtest C-HDMI-A1: SUCCESS (7.993s)
[532.944474] Starting dynamic subtest: D-HDMI-A1
[540.900661] Dynamic subtest D-HDMI-A1: SUCCESS (7.993s)
[540.920195] Subtest plain-flip-fb-recreate: SUCCESS (32.039s)
[541.214197] [099/132] (501s left) i915_suspend (debugfs-reader)
[541.258692] Starting subtest: debugfs-reader
[563.629122] Subtest debugfs-reader: SUCCESS (11.878s)
[567.871631] [100/132] (478s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[569.364030] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[569.365822] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[569.628325] [101/132] (477s left) gem_exec_nop (basic-sequential)
[569.685186] Starting subtest: basic-sequential
[573.416830] Subtest basic-sequential: SUCCESS (3.730s)
[573.762568] [102/132] (473s left) gem_pxp (create-regular-buffer)
[576.332878] Starting subtest: create-regular-buffer
[576.335243] Subtest create-regular-buffer: SKIP (0.000s)
[576.473423] [103/132] (470s left) syncobj_basic (test-valid-cycle)
[576.518036] Starting subtest: test-valid-cycle
[576.518928] Subtest test-valid-cycle: SUCCESS (0.000s)
[576.718658] [104/132] (470s left) gem_exec_suspend (basic-s3-devices)
[576.776536] Starting subtest: basic-S3-devices
[576.777540] Starting dynamic subtest: smem
[587.170594] Dynamic subtest smem: SUCCESS (10.394s)
[587.185201] Starting dynamic subtest: lmem0
[597.350656] Dynamic subtest lmem0: SUCCESS (10.179s)
[597.351711] Subtest basic-S3-devices: SUCCESS (20.575s)
[604.733701] [105/132] (449s left) template (a)
[604.780163] Starting subtest: A
[604.781021] Subtest A: SUCCESS (0.000s)
[604.971939] [106/132] (449s left) gem_mmap_gtt (bad-object)
[605.019085] Subtest bad-object: SKIP (0.000s)
[605.095968] [107/132] (449s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[606.586747] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[606.588783] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[606.844829] [108/132] (447s left) api_intel_bb (intel-bb-blit-y)
[606.889155] Starting subtest: intel-bb-blit-y
[608.565041] Subtest intel-bb-blit-y: SUCCESS (1.672s)
[608.754111] [109/132] (446s left) kms_selftest (drm_format_helper)
[608.800312] Starting subtest: drm_format_helper
[608.873097] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[608.977181] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.113s)
[609.024241] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[609.056964] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.075s)
[609.076813] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[609.165000] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.104s)
[609.213532] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[609.257679] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.086s)
[609.281820] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[609.345713] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.084s)
[609.390406] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[609.425189] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.073s)
[609.440805] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[609.522213] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.093s)
[609.569255] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[609.615063] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.087s)
[609.640986] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[609.706291] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.089s)
[609.755558] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[609.790256] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.076s)
[609.805864] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[609.894282] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.097s)
[609.938897] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[609.979740] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.083s)
[610.003811] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[610.074196] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.087s)
[610.115275] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[610.163068] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.087s)
[610.188899] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[610.266058] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.100s)
[610.313907] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[610.356510] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.083s)
[610.375693] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[610.469437] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.109s)
[610.471845] Subtest drm_format_helper: SUCCESS (1.670s)
[610.757055] [110/132] (444s left) gem_mmap_offset (oob-read)
[610.805024] Starting subtest: oob-read
[610.806271] Subtest oob-read: SUCCESS (0.000s)
[611.017658] [111/132] (443s left) gem_spin_batch (resubmit-all)
[611.073303] Starting subtest: resubmit-all
[611.074278] Starting dynamic subtest: rcs0
[611.096889] Dynamic subtest rcs0: SUCCESS (0.023s)
[611.113250] Starting dynamic subtest: bcs0
[611.114407] Dynamic subtest bcs0: SUCCESS (0.002s)
[611.128189] Starting dynamic subtest: vcs0
[611.129409] Dynamic subtest vcs0: SUCCESS (0.002s)
[611.142531] Starting dynamic subtest: vcs1
[611.143723] Dynamic subtest vcs1: SUCCESS (0.002s)
[611.156945] Starting dynamic subtest: vecs0
[611.158227] Dynamic subtest vecs0: SUCCESS (0.002s)
[611.159481] Subtest resubmit-all: SUCCESS (0.039s)
[611.501660] [112/132] (443s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[612.987129] Starting subtest: cursorB-vs-flipA-toggle
[612.989974] Subtest cursorB-vs-flipA-toggle: SKIP (0.003s)
[613.211787] [113/132] (442s left) gen9_exec_parse (valid-registers)
[613.261780] Subtest valid-registers: SKIP (0.000s)
[613.347363] [114/132] (441s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[614.831971] Starting subtest: disable-crc-after-crtc
[614.833496] Starting dynamic subtest: pipe-A-HDMI-A-1
[615.478080] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.643s)
[615.493250] Starting dynamic subtest: pipe-B-HDMI-A-1
[615.939088] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.457s)
[615.957804] Starting dynamic subtest: pipe-C-HDMI-A-1
[616.408526] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.466s)
[616.428606] Starting dynamic subtest: pipe-D-HDMI-A-1
[616.868052] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.455s)
[616.869147] Subtest disable-crc-after-crtc: SUCCESS (2.036s)
[617.114633] [115/132] (438s left) gem_exec_fence (syncobj-unused-fence)
[617.482676] Starting subtest: syncobj-unused-fence
[617.502953] Subtest syncobj-unused-fence: SUCCESS (0.020s)
[618.039572] [116/132] (437s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[619.527806] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[619.529432] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[619.767217] [117/132] (435s left) gem_set_tiling_vs_pwrite
[619.896292] [118/132] (435s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[621.385178] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[621.386912] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[621.593821] [119/132] (434s left) vgem_basic (busy-fence)
[621.649369] Starting subtest: busy-fence
[621.650379] Subtest busy-fence: SUCCESS (0.000s)
[621.856297] [120/132] (434s left) i915_pm_rpm (debugfs-read)
[626.280378] Starting subtest: debugfs-read
[627.215658] Subtest debugfs-read: SUCCESS (0.933s)
[628.263669] [121/132] (427s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[629.754207] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[633.070864] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (3.316s)
[633.354767] [122/132] (422s left) perf_pmu (busy-start)
[633.402309] Starting subtest: busy-start
[633.403097] Starting dynamic subtest: rcs0
[635.969691] Dynamic subtest rcs0: SUCCESS (2.566s)
[635.987275] Starting dynamic subtest: bcs0
[638.517676] Dynamic subtest bcs0: SUCCESS (2.546s)
[638.535354] Starting dynamic subtest: vcs0
[641.065613] Dynamic subtest vcs0: SUCCESS (2.546s)
[641.083098] Starting dynamic subtest: vcs1
[643.613597] Dynamic subtest vcs1: SUCCESS (2.546s)
[643.630858] Starting dynamic subtest: vecs0
[646.162636] Dynamic subtest vecs0: SUCCESS (2.547s)
[646.165706] Subtest busy-start: SUCCESS (12.761s)
[646.389543] [123/132] (409s left) kms_addfb_basic (size-max)
[647.876078] Starting subtest: size-max
[647.877011] Subtest size-max: SUCCESS (0.000s)
[648.123830] [124/132] (408s left) kms_psr (fbc-pr-primary-blt)
[649.608376] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[649.684709] [125/132] (406s left) kms_color_pipeline (plane-ctm3x4)
[651.172676] Starting subtest: plane-ctm3x4
[651.205196] Starting dynamic subtest: pipe-A-HDMI-A-1
[651.605084] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.400s)
[651.770104] Starting dynamic subtest: pipe-B-HDMI-A-1
[652.089977] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.332s)
[652.234846] Starting dynamic subtest: pipe-C-HDMI-A-1
[652.555276] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.332s)
[652.676711] Starting dynamic subtest: pipe-D-HDMI-A-1
[652.997262] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.332s)
[653.067779] Subtest plane-ctm3x4: SUCCESS (1.894s)
[653.305682] [126/132] (403s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[654.837605] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[654.961106] [127/132] (401s left) api_intel_allocator (standalone)
[655.005307] Starting subtest: standalone
[655.042690] Subtest standalone: SUCCESS (0.038s)
[655.306529] [128/132] (401s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[656.805905] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[656.807831] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[657.034348] [129/132] (399s left) kms_big_fb (y-tiled-addfb)
[658.538582] Starting subtest: y-tiled-addfb
[658.539490] Subtest y-tiled-addfb: SUCCESS (0.000s)
[658.789644] [130/132] (398s left) kms_prime (basic-crc-vgem)
[660.297414] Subtest basic-crc-vgem: SKIP (0.000s)
[660.556178] [131/132] (396s left) i915_hangman (engine-error-state-capture)
[660.614963] Starting subtest: engine-error-state-capture
[660.615848] Starting dynamic subtest: rcs0
[662.736247] Dynamic subtest rcs0: SUCCESS (2.121s)
[662.753708] Starting dynamic subtest: bcs0
[666.694173] Dynamic subtest bcs0: SUCCESS (3.956s)
[666.716446] Starting dynamic subtest: vcs0
[670.725984] Dynamic subtest vcs0: SUCCESS (4.030s)
[670.742113] Starting dynamic subtest: vcs1
[674.717372] Dynamic subtest vcs1: SUCCESS (3.989s)
[674.759004] Starting dynamic subtest: vecs0
[678.710978] Dynamic subtest vecs0: SUCCESS (3.991s)
[678.711862] Subtest engine-error-state-capture: SUCCESS (18.097s)
[678.899202] [132/132] (378s left) kms_invalid_mode (bad-vsync-end)
[680.384228] Starting subtest: bad-vsync-end
[680.386675] Starting dynamic subtest: pipe-A-HDMI-A-1
[680.432788] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.046s)
[680.451709] Starting dynamic subtest: pipe-B-HDMI-A-1
[680.452955] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[680.467716] Starting dynamic subtest: pipe-C-HDMI-A-1
[680.468680] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[680.482155] Starting dynamic subtest: pipe-D-HDMI-A-1
[680.483016] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.005s)
[680.484225] Subtest bad-vsync-end: SUCCESS (0.063s)
[680.663357] 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.