[68.429656] Initializing watchdogs
[68.429737]   /dev/watchdog0
[68.459598] [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.474094] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[68.479378] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.481846] [001/132] (960s left) syncobj_timeline (wait-for-submit-snapshot)
[68.528809] Starting subtest: wait-for-submit-snapshot
[68.612960] Subtest wait-for-submit-snapshot: SUCCESS (0.083s)
[68.828880] [002/132] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[70.307141] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[70.308100] Starting dynamic subtest: pipe-A-HDMI-A-4
[70.331404] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.003s)
[70.338892] Starting dynamic subtest: pipe-B-HDMI-A-4
[70.364595] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.003s)
[70.371555] Starting dynamic subtest: pipe-C-HDMI-A-4
[70.390807] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.003s)
[70.397826] Starting dynamic subtest: pipe-D-HDMI-A-4
[70.417470] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.003s)
[70.418941] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.017s)
[70.591147] [003/132] (958s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[72.081947] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[74.490501] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (2.411s)
[74.727111] [004/132] (954s left) kms_big_fb (linear-8bpp-rotate-270)
[76.243556] Starting subtest: linear-8bpp-rotate-270
[76.346413] Subtest linear-8bpp-rotate-270: SKIP (0.102s)
[76.573784] [005/132] (952s left) kms_busy (extended-modeset-hang-newfb)
[78.064221] Starting subtest: extended-modeset-hang-newfb
[78.065033] Starting dynamic subtest: pipe-A
[93.628267] Dynamic subtest pipe-A: SUCCESS (15.569s)
[93.639541] Starting dynamic subtest: pipe-D
[109.126171] Dynamic subtest pipe-D: SUCCESS (15.496s)
[109.127041] Subtest extended-modeset-hang-newfb: SUCCESS (31.072s)
[109.350048] [006/132] (920s left) perf (oa-exponents)
[109.547283] Starting subtest: oa-exponents
[109.548161] Starting dynamic subtest: 0-rcs0
[113.206490] Dynamic subtest 0-rcs0: SUCCESS (3.657s)
[113.207363] Subtest oa-exponents: SUCCESS (3.657s)
[113.275519] [007/132] (916s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[114.776903] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[116.039611] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.259s)
[116.264561] [008/132] (913s left) kms_pipe_crc_basic (suspend-read-crc)
[117.747663] Starting subtest: suspend-read-crc
[117.748620] Starting dynamic subtest: pipe-A-HDMI-A-4
[139.732971] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (11.163s)
[139.743129] Starting dynamic subtest: pipe-B-HDMI-A-4
[161.697356] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (10.620s)
[161.708403] Starting dynamic subtest: pipe-C-HDMI-A-4
[183.703208] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (10.681s)
[183.714354] Starting dynamic subtest: pipe-D-HDMI-A-4
[205.750183] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (10.722s)
[205.751111] Subtest suspend-read-crc: SUCCESS (43.193s)
[211.989741] [009/132] (823s left) perf_pmu (interrupts-sync)
[212.039199] Starting subtest: interrupts-sync
[213.235613] Subtest interrupts-sync: SUCCESS (1.192s)
[213.416908] [FACT perf_pmu (interrupts-sync)] new: kernel.kmod_is_loaded.vgem: true
[213.419363] [010/132] (822s left) gem_exec_reloc (basic-gtt-active)
[213.467304] Subtest basic-gtt-active: SKIP (0.000s)
[213.560432] [011/132] (822s left) kms_flip (modeset-vs-vblank-race)
[215.044093] Starting subtest: modeset-vs-vblank-race
[215.051400] Starting dynamic subtest: A-HDMI-A4
[218.071932] Dynamic subtest A-HDMI-A4: SUCCESS (3.021s)
[218.086568] Starting dynamic subtest: B-HDMI-A4
[221.088841] Dynamic subtest B-HDMI-A4: SUCCESS (3.016s)
[221.101159] Starting dynamic subtest: C-HDMI-A4
[224.086497] Dynamic subtest C-HDMI-A4: SUCCESS (2.997s)
[224.098994] Starting dynamic subtest: D-HDMI-A4
[227.099932] Dynamic subtest D-HDMI-A4: SUCCESS (3.012s)
[227.100914] Subtest modeset-vs-vblank-race: SUCCESS (12.058s)
[227.410114] [012/132] (808s left) gem_sync (basic-each)
[227.477235] Starting subtest: basic-each
[229.628284] Subtest basic-each: SUCCESS (2.153s)
[229.962734] [013/132] (805s left) gem_ctx_persistence (legacy-engines-mixed)
[230.012944] Starting subtest: legacy-engines-mixed
[230.013801] Starting dynamic subtest: render
[230.071836] Dynamic subtest render: SUCCESS (0.059s)
[230.084132] Starting dynamic subtest: bsd1
[230.119756] Dynamic subtest bsd1: SUCCESS (0.046s)
[230.137235] Starting dynamic subtest: bsd2
[230.170037] Dynamic subtest bsd2: SUCCESS (0.048s)
[230.195677] Starting dynamic subtest: blt
[230.232089] Dynamic subtest blt: SUCCESS (0.058s)
[230.264290] Starting dynamic subtest: vebox
[230.303969] Dynamic subtest vebox: SUCCESS (0.068s)
[230.305862] Subtest legacy-engines-mixed: SUCCESS (0.293s)
[230.541482] [014/132] (805s left) kms_plane (plane-panning-bottom-right)
[232.025379] Starting subtest: plane-panning-bottom-right
[232.026237] Starting dynamic subtest: pipe-A
[232.715809] Dynamic subtest pipe-A: SUCCESS (0.689s)
[232.728619] Starting dynamic subtest: pipe-B
[233.226348] Dynamic subtest pipe-B: SUCCESS (0.508s)
[233.227193] Subtest plane-panning-bottom-right: SUCCESS (1.203s)
[233.478064] [015/132] (802s left) prime_vgem (wait)
[233.550404] Starting subtest: wait
[233.551249] Starting dynamic subtest: rcs0
[233.600963] Dynamic subtest rcs0: SUCCESS (0.050s)
[233.613692] Starting dynamic subtest: bcs0
[233.656160] Dynamic subtest bcs0: SUCCESS (0.054s)
[233.673883] Starting dynamic subtest: vcs0
[233.696800] Dynamic subtest vcs0: SUCCESS (0.038s)
[233.716403] Starting dynamic subtest: vcs1
[233.737728] Dynamic subtest vcs1: SUCCESS (0.039s)
[233.757300] Starting dynamic subtest: vecs0
[233.779125] Dynamic subtest vecs0: SUCCESS (0.039s)
[233.782669] Subtest wait: SUCCESS (0.230s)
[234.039254] [016/132] (802s left) i915_query (query-topology-matches-eu-total)
[234.083522] Starting subtest: query-topology-matches-eu-total
[234.084512] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[234.246272] [017/132] (802s left) syncobj_timeline (reset-signaled)
[234.290252] Starting subtest: reset-signaled
[234.291218] Subtest reset-signaled: SUCCESS (0.000s)
[234.472192] [018/132] (801s left) syncobj_timeline (multi-wait-all-available-signaled)
[234.516690] Starting subtest: multi-wait-all-available-signaled
[234.517792] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[234.704102] [019/132] (801s left) kms_cursor_crc (cursor-random-256x256)
[236.405164] Starting subtest: cursor-random-256x256
[236.412351] Starting dynamic subtest: pipe-A-HDMI-A-4
[240.867756] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.455s)
[240.900325] Starting dynamic subtest: pipe-D-HDMI-A-4
[245.360973] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.470s)
[245.361882] Subtest cursor-random-256x256: SUCCESS (8.955s)
[245.769618] [020/132] (790s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[245.822284] Starting subtest: mixed-tiled-to-y-tiled-ccs
[245.823234] Starting dynamic subtest: smem
[245.931449] Dynamic subtest smem: SUCCESS (0.107s)
[245.942588] Starting dynamic subtest: lmem0
[246.882197] Dynamic subtest lmem0: SUCCESS (0.949s)
[246.883277] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (1.059s)
[247.214342] [021/132] (789s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[248.697498] Starting subtest: primary-y-tiled-reflect-x-180
[259.101811] Subtest primary-y-tiled-reflect-x-180: SUCCESS (10.404s)
[259.412641] [022/132] (777s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[259.464000] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[259.464897] Starting dynamic subtest: smem
[259.569586] Dynamic subtest smem: SUCCESS (0.102s)
[259.580586] Starting dynamic subtest: lmem0
[260.683238] Dynamic subtest lmem0: SUCCESS (1.112s)
[260.684317] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SUCCESS (1.217s)
[261.020813] [023/132] (775s left) gem_exec_schedule (reorder-wide)
[261.236678] Subtest reorder-wide: SKIP (0.000s)
[261.508196] [024/132] (775s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[262.996797] Starting subtest: fbc-indfb-scaledprimary
[266.636966] Subtest fbc-indfb-scaledprimary: SUCCESS (3.640s)
[266.906721] [025/132] (770s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[268.394331] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[268.396012] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[268.656524] [026/132] (768s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[270.139745] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[270.140813] Starting dynamic subtest: pipe-A-HDMI-A-4
[270.145000] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[270.156699] Starting dynamic subtest: pipe-B-HDMI-A-4
[270.160831] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[270.172261] Starting dynamic subtest: pipe-C-HDMI-A-4
[270.176173] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[270.187275] Starting dynamic subtest: pipe-D-HDMI-A-4
[270.193948] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[270.195813] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.011s)
[270.425578] [027/132] (766s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[271.914576] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[271.993897] [028/132] (765s left) kms_cursor_crc (cursor-random-64x21)
[273.622571] Starting subtest: cursor-random-64x21
[273.629372] Starting dynamic subtest: pipe-A-HDMI-A-4
[278.135646] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.507s)
[278.169406] Starting dynamic subtest: pipe-D-HDMI-A-4
[282.579518] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.420s)
[282.580558] Subtest cursor-random-64x21: SUCCESS (8.957s)
[283.086496] [029/132] (754s left) prime_mmap (test_dup)
[283.130913] Starting subtest: test_dup
[283.131811] Starting dynamic subtest: test_dup-smem
[283.192871] Dynamic subtest test_dup-smem: SUCCESS (0.062s)
[283.205638] Starting dynamic subtest: test_dup-lmem0
[283.255629] Dynamic subtest test_dup-lmem0: SUCCESS (0.061s)
[283.256515] Subtest test_dup: SUCCESS (0.126s)
[283.416569] [030/132] (754s left) gem_exec_schedule (u-submit-late-slice)
[283.475533] Starting subtest: u-submit-late-slice
[283.476503] Starting dynamic subtest: rcs0
[283.531700] Dynamic subtest rcs0: SUCCESS (0.055s)
[283.544946] Starting dynamic subtest: bcs0
[283.572714] Dynamic subtest bcs0: SUCCESS (0.040s)
[283.585912] Starting dynamic subtest: vcs0
[283.612969] Dynamic subtest vcs0: SUCCESS (0.039s)
[283.626387] Starting dynamic subtest: vcs1
[283.655716] Dynamic subtest vcs1: SUCCESS (0.041s)
[283.668895] Starting dynamic subtest: vecs0
[283.698179] Dynamic subtest vecs0: SUCCESS (0.041s)
[283.699100] Subtest u-submit-late-slice: SUCCESS (0.223s)
[284.231423] [031/132] (753s left) syncobj_timeline (etime-multi-wait-all-submitted)
[284.275543] Starting subtest: etime-multi-wait-all-submitted
[284.380759] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s)
[284.581246] [032/132] (753s left) fbdev (write)
[284.642312] Starting subtest: write
[285.367445] Subtest write: SUCCESS (0.722s)
[285.491754] [033/132] (752s left) syncobj_wait (invalid-signal-zero-handles)
[285.537080] Starting subtest: invalid-signal-zero-handles
[285.537950] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[285.750180] [034/132] (752s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[287.236947] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[287.238794] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[287.493787] [035/132] (750s left) gem_exec_balancer (sliced)
[287.551095] Starting subtest: sliced
[287.552882] Subtest sliced: SKIP (0.000s)
[288.011760] [036/132] (750s left) gem_ccs (large-ctrl-surf-copy)
[288.061565] Starting subtest: large-ctrl-surf-copy
[288.063578] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[288.243922] [037/132] (749s left) gem_fenced_exec_thrash (2-spare-fences)
[288.298619] Subtest 2-spare-fences: SKIP (0.000s)
[288.391906] [038/132] (749s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[289.875332] Starting subtest: basic-busy-flip-before-cursor-varying-size
[289.877113] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[290.096684] [039/132] (748s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[291.623185] Starting subtest: 4-tiled-8bpp-rotate-0
[291.625024] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[291.887577] [040/132] (746s left) gem_mmap_wc (copy)
[291.931141] Subtest copy: SKIP (0.000s)
[292.002195] [041/132] (746s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[293.484129] Starting subtest: cursor-vs-flip-toggle
[328.545309] Subtest cursor-vs-flip-toggle: SUCCESS (35.062s)
[328.797162] [042/132] (709s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[330.301975] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[330.303740] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[330.548782] [043/132] (708s left) kms_addfb_basic (unused-handle)
[330.593155] Starting subtest: unused-handle
[330.593978] Subtest unused-handle: SUCCESS (0.000s)
[332.276743] [044/132] (706s left) gem_ctx_shared (q-smoketest-all)
[332.333444] Starting subtest: Q-smoketest-all
[364.642046] Subtest Q-smoketest-all: SUCCESS (32.300s)
[365.059248] [045/132] (674s left) kms_flip (2x-flip-vs-expired-vblank)
[366.543067] Starting subtest: 2x-flip-vs-expired-vblank
[366.560221] Subtest 2x-flip-vs-expired-vblank: SKIP (0.018s)
[366.770769] [046/132] (672s left) kms_force_connector_basic (force-edid)
[366.925428] Starting subtest: force-edid
[366.947208] Subtest force-edid: SUCCESS (0.021s)
[368.684235] [047/132] (670s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[370.186135] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[371.413841] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.228s)
[371.716238] [048/132] (667s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[373.198166] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[373.199100] Starting dynamic subtest: pipe-A
[373.353436] Dynamic subtest pipe-A: SUCCESS (0.156s)
[373.371824] Starting dynamic subtest: pipe-B
[373.514197] Dynamic subtest pipe-B: SUCCESS (0.159s)
[373.527422] Starting dynamic subtest: pipe-C
[373.654976] Dynamic subtest pipe-C: SUCCESS (0.139s)
[373.668354] Starting dynamic subtest: pipe-D
[373.794936] Dynamic subtest pipe-D: SUCCESS (0.139s)
[373.795782] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.599s)
[374.047284] [049/132] (665s left) kms_chamelium_audio (hdmi-audio)
[375.577378] Subtest hdmi-audio: SKIP (0.000s)
[375.697076] [050/132] (664s left) gem_set_tiling_vs_gtt
[375.829296] [051/132] (663s left) gem_lmem_swapping (massive)
[380.173009] Starting subtest: massive
[380.173952] Starting dynamic subtest: lmem0
[388.383935] Dynamic subtest lmem0: SUCCESS (8.209s)
[388.384863] Subtest massive: SUCCESS (8.211s)
[390.153910] [FACT gem_lmem_swapping (massive)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[390.155054] [FACT gem_lmem_swapping (massive)] deleted: kernel.kmod_is_loaded.i915: true
[390.157249] [052/132] (649s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[394.879794] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[394.881655] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[395.146354] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[395.149415] [FACT kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)] new: kernel.kmod_is_loaded.i915: true
[395.158907] [053/132] (644s left) prime_busy (after)
[395.228437] Starting subtest: after
[395.234919] Starting dynamic subtest: rcs0
[395.272527] Dynamic subtest rcs0: SUCCESS (0.037s)
[395.286165] Starting dynamic subtest: bcs0
[395.329544] Dynamic subtest bcs0: SUCCESS (0.055s)
[395.349137] Starting dynamic subtest: vcs0
[395.372049] Dynamic subtest vcs0: SUCCESS (0.040s)
[395.393586] Starting dynamic subtest: vcs1
[395.414936] Dynamic subtest vcs1: SUCCESS (0.040s)
[395.429511] Starting dynamic subtest: vecs0
[395.454675] Dynamic subtest vecs0: SUCCESS (0.038s)
[395.578046] Subtest after: SUCCESS (0.345s)
[395.826586] [054/132] (644s left) syncobj_wait (invalid-signal-illegal-handle)
[395.871899] Starting subtest: invalid-signal-illegal-handle
[395.872745] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[396.059468] [055/132] (644s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[397.542199] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[397.543143] Starting dynamic subtest: pipe-A-valid-mode
[397.592008] Dynamic subtest pipe-A-valid-mode: SKIP (0.047s)
[397.593701] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.050s)
[397.788469] [056/132] (642s left) gem_gtt_cpu_tlb
[397.914607] [057/132] (642s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[399.402149] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[399.403811] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[399.676942] [058/132] (640s left) gen9_exec_parse (batch-zero-length)
[399.726695] Subtest batch-zero-length: SKIP (0.000s)
[399.817590] [059/132] (640s left) gem_softpin (evict-snoop-interruptible)
[399.868154] Starting subtest: evict-snoop-interruptible
[399.869814] Subtest evict-snoop-interruptible: SKIP (0.000s)
[400.060899] [060/132] (640s left) dmabuf_sync_file (export-basic)
[400.117867] Starting subtest: export-basic
[400.120360] Subtest export-basic: SUCCESS (0.001s)
[400.266536] [061/132] (640s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[401.750304] Starting subtest: fb-8bpc-vs-panel-6bpc
[401.752847] Subtest fb-8bpc-vs-panel-6bpc: SKIP (0.001s)
[401.964373] [062/132] (638s left) syncobj_timeline (host-signal-ordered)
[402.009096] Starting subtest: host-signal-ordered
[402.009968] Subtest host-signal-ordered: SUCCESS (0.001s)
[402.180896] [063/132] (638s left) kms_chamelium_hpd (common-hpd-after-suspend)
[403.710168] Subtest common-hpd-after-suspend: SKIP (0.000s)
[403.819177] [064/132] (637s left) kms_flip (2x-flip-vs-fences)
[405.301094] Starting subtest: 2x-flip-vs-fences
[405.302702] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[405.560645] [065/132] (635s left) perf (mi-rpc)
[405.733501] Starting subtest: mi-rpc
[405.735150] Subtest mi-rpc: SKIP (0.000s)
[405.804139] [066/132] (635s left) sw_sync (sync_random_merge)
[405.847495] Starting subtest: sync_random_merge
[405.935934] Subtest sync_random_merge: SUCCESS (0.088s)
[406.076241] [067/132] (635s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[407.564767] Starting subtest: psr-rgb101010-draw-pwrite
[407.566607] Subtest psr-rgb101010-draw-pwrite: SKIP (0.000s)
[407.840257] [068/132] (633s left) kms_getfb (getfb2-handle-closed)
[409.323536] Starting subtest: getfb2-handle-closed
[409.324530] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[409.563685] [069/132] (632s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[411.051544] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[411.053232] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[411.309492] [070/132] (630s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[411.354783] Starting subtest: invalid-smem-bo-on-discrete
[411.355635] Subtest invalid-smem-bo-on-discrete: SUCCESS (0.000s)
[413.037295] [071/132] (628s left) perf (gen12-invalid-class-instance)
[413.199920] Starting subtest: gen12-invalid-class-instance
[413.223210] Subtest gen12-invalid-class-instance: SUCCESS (0.023s)
[413.303496] [072/132] (628s left) gem_exec_schedule (implicit-read-write)
[413.361459] Starting subtest: implicit-read-write
[413.362262] Starting dynamic subtest: rcs0
[413.563776] Dynamic subtest rcs0: SUCCESS (0.198s)
[413.575026] Starting dynamic subtest: bcs0
[413.696570] Dynamic subtest bcs0: SUCCESS (0.135s)
[413.708963] Starting dynamic subtest: vcs0
[413.833568] Dynamic subtest vcs0: SUCCESS (0.136s)
[413.846311] Starting dynamic subtest: vcs1
[413.974689] Dynamic subtest vcs1: SUCCESS (0.140s)
[413.987170] Starting dynamic subtest: vecs0
[414.126016] Dynamic subtest vecs0: SUCCESS (0.150s)
[414.126892] Subtest implicit-read-write: SUCCESS (0.765s)
[414.628291] [073/132] (627s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[416.111155] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[416.362721] [074/132] (625s left) kms_atomic (plane-cursor-legacy)
[417.863571] Starting subtest: plane-cursor-legacy
[417.864629] Starting dynamic subtest: pipe-A-HDMI-A-4
[418.194344] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.330s)
[418.195279] Subtest plane-cursor-legacy: SUCCESS (0.333s)
[418.463625] [075/132] (623s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[419.945816] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[419.948037] Starting dynamic subtest: pipe-A-HDMI-A-4
[419.954900] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.001s)
[419.966344] Starting dynamic subtest: pipe-B-HDMI-A-4
[419.974788] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.001s)
[419.986193] Starting dynamic subtest: pipe-C-HDMI-A-4
[419.994769] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.001s)
[420.005643] Starting dynamic subtest: pipe-D-HDMI-A-4
[420.014578] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.001s)
[420.015632] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SUCCESS (0.013s)
[420.246546] [076/132] (622s left) gem_exec_balancer (bonded-pair)
[420.302682] Starting subtest: bonded-pair
[420.305103] Subtest bonded-pair: SKIP (0.000s)
[420.757143] [077/132] (621s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[422.253982] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[422.255558] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[422.515596] [078/132] (620s left) gem_ctx_exec (basic-invalid-context)
[422.561912] Starting subtest: basic-invalid-context
[422.565615] Subtest basic-invalid-context: SUCCESS (0.005s)
[422.806608] [079/132] (619s left) kms_content_protection (lic-type-0-hdcp14)
[424.344966] Starting subtest: lic-type-0-hdcp14
[424.353071] Subtest lic-type-0-hdcp14: SKIP (0.005s)
[424.603537] [080/132] (618s left) gem_eio (wait-immediate)
[424.791072] Starting subtest: wait-immediate
[425.225092] Subtest wait-immediate: SUCCESS (0.434s)
[426.875613] [081/132] (616s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[428.363028] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[428.381636] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.018s)
[428.598818] [082/132] (614s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[430.081722] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[430.082820] Starting dynamic subtest: pipe-A-valid-mode
[431.322561] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.240s)
[431.323527] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SUCCESS (1.243s)
[431.592706] [083/132] (611s left) kms_psr (psr2-basic)
[433.079280] Subtest psr2-basic: SKIP (0.000s)
[433.161729] [084/132] (610s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[434.643104] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[434.644020] Starting dynamic subtest: pipe-A-HDMI-A-4
[434.652524] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[434.664703] Starting dynamic subtest: pipe-B-HDMI-A-4
[434.672847] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[434.684950] Starting dynamic subtest: pipe-C-HDMI-A-4
[434.693689] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[434.705196] Starting dynamic subtest: pipe-D-HDMI-A-4
[434.714077] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[434.715766] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.014s)
[434.941105] [085/132] (608s left) kms_vblank (query-forked-busy)
[436.422909] Starting subtest: query-forked-busy
[436.423949] Starting dynamic subtest: pipe-A-HDMI-A-4
[439.006091] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.580s)
[439.065240] Starting dynamic subtest: pipe-D-HDMI-A-4
[441.486373] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.448s)
[441.488432] Subtest query-forked-busy: SUCCESS (5.063s)
[441.728005] [086/132] (601s left) kms_pm_dc (dc9-dpms)
[444.218989] Starting subtest: dc9-dpms
[444.543671] Subtest dc9-dpms: SUCCESS (0.324s)
[445.460885] [087/132] (598s left) gem_userptr_blits (set-cache-level)
[445.512621] Starting subtest: set-cache-level
[445.513541] Subtest set-cache-level: SUCCESS (0.000s)
[445.767965] [088/132] (597s left) gem_exec_fence (keep-in-fence)
[445.938985] Starting subtest: keep-in-fence
[445.939869] Starting dynamic subtest: rcs0
[446.026038] Dynamic subtest rcs0: SUCCESS (0.086s)
[446.049379] Starting dynamic subtest: bcs0
[446.085184] Dynamic subtest bcs0: SUCCESS (0.057s)
[446.124959] Starting dynamic subtest: vcs0
[446.161799] Dynamic subtest vcs0: SUCCESS (0.073s)
[446.179086] Starting dynamic subtest: vcs1
[446.217562] Dynamic subtest vcs1: SUCCESS (0.054s)
[446.237721] Starting dynamic subtest: vecs0
[446.272870] Dynamic subtest vecs0: SUCCESS (0.053s)
[446.277112] Subtest keep-in-fence: SUCCESS (0.335s)
[447.018234] [089/132] (596s left) kms_pm_rpm (modeset-pc8-residency-stress)
[450.837792] Starting subtest: modeset-pc8-residency-stress
[450.840489] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[451.822933] [090/132] (592s left) kms_psr (fbc-psr2-sprite-plane-move)
[453.316737] Subtest fbc-psr2-sprite-plane-move: SKIP (0.000s)
[453.388181] [091/132] (590s left) gem_exec_create (forked)
[453.443332] Starting subtest: forked
[453.444145] Starting dynamic subtest: smem
[473.586153] Dynamic subtest smem: SUCCESS (20.141s)
[473.604807] Starting dynamic subtest: lmem0
[493.736928] Dynamic subtest lmem0: SUCCESS (20.148s)
[493.738015] Subtest forked: SUCCESS (40.293s)
[494.069481] [092/132] (549s left) kms_psr (pr-cursor-mmap-cpu)
[495.552651] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[495.641200] [093/132] (548s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[497.129072] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[497.130645] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[497.399017] [094/132] (546s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[498.888707] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[498.956626] [095/132] (545s left) gem_exec_reloc (basic-wc-read)
[499.006138] Subtest basic-wc-read: SKIP (0.000s)
[499.107506] [096/132] (545s left) kms_psr (psr-cursor-plane-move)
[500.590387] Subtest psr-cursor-plane-move: SKIP (0.000s)
[500.672388] [097/132] (543s left) kms_flip (basic-plain-flip)
[502.155976] Starting subtest: basic-plain-flip
[502.163221] Starting dynamic subtest: A-HDMI-A4
[502.889292] Dynamic subtest A-HDMI-A4: SUCCESS (0.726s)
[502.904364] Starting dynamic subtest: B-HDMI-A4
[503.565860] Dynamic subtest B-HDMI-A4: SUCCESS (0.674s)
[503.580364] Starting dynamic subtest: C-HDMI-A4
[504.225991] Dynamic subtest C-HDMI-A4: SUCCESS (0.658s)
[504.241048] Starting dynamic subtest: D-HDMI-A4
[504.903822] Dynamic subtest D-HDMI-A4: SUCCESS (0.676s)
[504.904690] Subtest basic-plain-flip: SUCCESS (2.750s)
[505.203484] [098/132] (539s left) kms_flip (plain-flip-fb-recreate)
[506.688184] Starting subtest: plain-flip-fb-recreate
[506.694745] Starting dynamic subtest: A-HDMI-A4
[514.712973] Dynamic subtest A-HDMI-A4: SUCCESS (8.017s)
[514.753882] Starting dynamic subtest: B-HDMI-A4
[522.726648] Dynamic subtest B-HDMI-A4: SUCCESS (8.005s)
[522.768672] Starting dynamic subtest: C-HDMI-A4
[530.737874] Dynamic subtest C-HDMI-A4: SUCCESS (8.002s)
[530.773786] Starting dynamic subtest: D-HDMI-A4
[538.714468] Dynamic subtest D-HDMI-A4: SUCCESS (7.968s)
[538.723133] Subtest plain-flip-fb-recreate: SUCCESS (32.031s)
[539.023151] [099/132] (505s left) i915_suspend (debugfs-reader)
[539.067593] Starting subtest: debugfs-reader
[561.757382] Subtest debugfs-reader: SUCCESS (11.850s)
[565.983665] [100/132] (482s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[567.473885] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[567.475735] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[567.706393] [101/132] (481s left) gem_exec_nop (basic-sequential)
[567.762439] Starting subtest: basic-sequential
[571.496983] Subtest basic-sequential: SUCCESS (3.734s)
[571.791730] [102/132] (477s left) gem_pxp (create-regular-buffer)
[574.361347] Starting subtest: create-regular-buffer
[574.363720] Subtest create-regular-buffer: SKIP (0.000s)
[574.523883] [103/132] (474s left) syncobj_basic (test-valid-cycle)
[574.567364] Starting subtest: test-valid-cycle
[574.568297] Subtest test-valid-cycle: SUCCESS (0.000s)
[574.775825] [104/132] (474s left) gem_exec_suspend (basic-s3-devices)
[574.831307] Starting subtest: basic-S3-devices
[574.832146] Starting dynamic subtest: smem
[585.288721] Dynamic subtest smem: SUCCESS (10.458s)
[585.303116] Starting dynamic subtest: lmem0
[595.490839] Dynamic subtest lmem0: SUCCESS (10.201s)
[595.491838] Subtest basic-S3-devices: SUCCESS (20.662s)
[600.820760] [105/132] (453s left) template (a)
[600.871408] Starting subtest: A
[600.872254] Subtest A: SUCCESS (0.000s)
[601.055907] [106/132] (453s left) gem_mmap_gtt (bad-object)
[601.105790] Subtest bad-object: SKIP (0.000s)
[601.261532] [107/132] (453s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[602.766976] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[602.768733] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[603.027985] [108/132] (451s left) api_intel_bb (intel-bb-blit-y)
[603.086169] Starting subtest: intel-bb-blit-y
[604.756844] Subtest intel-bb-blit-y: SUCCESS (1.667s)
[604.945226] [109/132] (449s left) kms_selftest (drm_format_helper)
[604.990265] Starting subtest: drm_format_helper
[605.063989] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[605.129363] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.087s)
[605.173182] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[605.210389] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.077s)
[605.230500] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[605.311449] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.093s)
[605.357390] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[605.392515] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.079s)
[605.414823] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[605.482176] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.083s)
[605.524271] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[605.556086] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.071s)
[605.572084] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[605.652915] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.095s)
[605.698195] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[605.741719] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.082s)
[605.767452] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[605.818980] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.074s)
[605.860448] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[605.909061] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.084s)
[605.937469] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[606.001420] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.086s)
[606.039739] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[606.087761] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.084s)
[606.112651] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[606.187439] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.093s)
[606.229320] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[606.271808] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.081s)
[606.295468] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[606.359142] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.084s)
[606.403198] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[606.440211] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.074s)
[606.462080] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[606.557499] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.113s)
[606.565154] Subtest drm_format_helper: SUCCESS (1.569s)
[606.855453] [110/132] (448s left) gem_mmap_offset (oob-read)
[606.901343] Starting subtest: oob-read
[606.902248] Subtest oob-read: SUCCESS (0.000s)
[607.077585] [111/132] (447s left) gem_spin_batch (resubmit-all)
[607.131845] Starting subtest: resubmit-all
[607.132819] Starting dynamic subtest: rcs0
[607.155447] Dynamic subtest rcs0: SUCCESS (0.023s)
[607.170761] Starting dynamic subtest: bcs0
[607.171842] Dynamic subtest bcs0: SUCCESS (0.002s)
[607.184544] Starting dynamic subtest: vcs0
[607.185723] Dynamic subtest vcs0: SUCCESS (0.003s)
[607.198120] Starting dynamic subtest: vcs1
[607.199348] Dynamic subtest vcs1: SUCCESS (0.002s)
[607.211620] Starting dynamic subtest: vecs0
[607.212818] Dynamic subtest vecs0: SUCCESS (0.002s)
[607.214040] Subtest resubmit-all: SUCCESS (0.039s)
[607.557062] [112/132] (447s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[609.039118] Starting subtest: cursorB-vs-flipA-toggle
[609.042842] Subtest cursorB-vs-flipA-toggle: SKIP (0.003s)
[609.246591] [113/132] (446s left) gen9_exec_parse (valid-registers)
[609.296395] Subtest valid-registers: SKIP (0.000s)
[609.383238] [114/132] (445s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[610.868586] Starting subtest: disable-crc-after-crtc
[610.869486] Starting dynamic subtest: pipe-A-HDMI-A-4
[611.465728] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.596s)
[611.480287] Starting dynamic subtest: pipe-B-HDMI-A-4
[611.932273] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.464s)
[611.948236] Starting dynamic subtest: pipe-C-HDMI-A-4
[612.406309] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.470s)
[612.423982] Starting dynamic subtest: pipe-D-HDMI-A-4
[612.869379] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.460s)
[612.870425] Subtest disable-crc-after-crtc: SUCCESS (2.004s)
[613.117279] [115/132] (442s left) gem_exec_fence (syncobj-unused-fence)
[613.483855] Starting subtest: syncobj-unused-fence
[613.503292] Subtest syncobj-unused-fence: SUCCESS (0.020s)
[614.041728] [116/132] (441s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[615.529778] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[615.531528] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[615.794487] [117/132] (439s left) gem_set_tiling_vs_pwrite
[615.916287] [118/132] (439s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[617.403761] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[617.405399] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[617.677302] [119/132] (438s left) vgem_basic (busy-fence)
[617.736159] Starting subtest: busy-fence
[617.736996] Subtest busy-fence: SUCCESS (0.000s)
[617.877762] [120/132] (438s left) i915_pm_rpm (debugfs-read)
[621.681058] Starting subtest: debugfs-read
[622.661920] Subtest debugfs-read: SUCCESS (0.972s)
[623.763894] [121/132] (432s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[625.253363] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[628.568664] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (3.314s)
[628.841539] [122/132] (427s left) perf_pmu (busy-start)
[628.890423] Starting subtest: busy-start
[628.891299] Starting dynamic subtest: rcs0
[631.457790] Dynamic subtest rcs0: SUCCESS (2.567s)
[631.475153] Starting dynamic subtest: bcs0
[634.005760] Dynamic subtest bcs0: SUCCESS (2.546s)
[634.023027] Starting dynamic subtest: vcs0
[636.553871] Dynamic subtest vcs0: SUCCESS (2.546s)
[636.571129] Starting dynamic subtest: vcs1
[639.096877] Dynamic subtest vcs1: SUCCESS (2.541s)
[639.114128] Starting dynamic subtest: vecs0
[641.641849] Dynamic subtest vecs0: SUCCESS (2.543s)
[641.642947] Subtest busy-start: SUCCESS (12.753s)
[641.863188] [123/132] (414s left) kms_addfb_basic (size-max)
[643.367215] Starting subtest: size-max
[643.368103] Subtest size-max: SUCCESS (0.000s)
[643.645572] [124/132] (412s left) kms_psr (fbc-pr-primary-blt)
[645.146922] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[645.231869] [125/132] (411s left) kms_color_pipeline (plane-ctm3x4)
[646.719653] Starting subtest: plane-ctm3x4
[646.751418] Starting dynamic subtest: pipe-A-HDMI-A-4
[647.151265] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.400s)
[647.310492] Starting dynamic subtest: pipe-B-HDMI-A-4
[647.631371] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.332s)
[647.738473] Starting dynamic subtest: pipe-C-HDMI-A-4
[648.059115] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.332s)
[648.200654] Starting dynamic subtest: pipe-D-HDMI-A-4
[648.519832] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.331s)
[648.560053] Subtest plane-ctm3x4: SUCCESS (1.841s)
[648.808528] [126/132] (407s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[650.335957] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[650.448301] [127/132] (406s left) api_intel_allocator (standalone)
[650.492521] Starting subtest: standalone
[650.530528] Subtest standalone: SUCCESS (0.038s)
[650.771413] [128/132] (406s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[652.259891] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[652.261522] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[652.524807] [129/132] (404s left) kms_big_fb (y-tiled-addfb)
[654.048121] Starting subtest: y-tiled-addfb
[654.049088] Subtest y-tiled-addfb: SUCCESS (0.001s)
[654.300666] [130/132] (402s left) kms_prime (basic-crc-vgem)
[655.806438] Subtest basic-crc-vgem: SKIP (0.000s)
[656.141946] [131/132] (401s left) i915_hangman (engine-error-state-capture)
[656.199351] Starting subtest: engine-error-state-capture
[656.200158] Starting dynamic subtest: rcs0
[658.113188] Dynamic subtest rcs0: SUCCESS (1.913s)
[658.129090] Starting dynamic subtest: bcs0
[662.151360] Dynamic subtest bcs0: SUCCESS (4.036s)
[662.182332] Starting dynamic subtest: vcs0
[666.110394] Dynamic subtest vcs0: SUCCESS (3.957s)
[666.127901] Starting dynamic subtest: vcs1
[670.138250] Dynamic subtest vcs1: SUCCESS (4.026s)
[670.157529] Starting dynamic subtest: vecs0
[674.158193] Dynamic subtest vecs0: SUCCESS (4.018s)
[674.159092] Subtest engine-error-state-capture: SUCCESS (17.960s)
[674.388509] [132/132] (383s left) kms_invalid_mode (bad-vsync-end)
[675.871222] Starting subtest: bad-vsync-end
[675.874218] Starting dynamic subtest: pipe-A-HDMI-A-4
[675.919658] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.046s)
[675.934251] Starting dynamic subtest: pipe-B-HDMI-A-4
[675.935423] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.001s)
[675.948685] Starting dynamic subtest: pipe-C-HDMI-A-4
[675.949793] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.001s)
[675.961973] Starting dynamic subtest: pipe-D-HDMI-A-4
[675.962955] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.001s)
[675.963860] Subtest bad-vsync-end: SUCCESS (0.059s)
[676.150160] 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.