[32.042530] Initializing watchdogs
[32.042603]   /dev/watchdog0
[32.049798] [001/130] (960s left) vgem_basic (mmap)
[32.121565] Starting subtest: mmap
[32.123910] Subtest mmap: SUCCESS (0.005s)
[32.154505] [002/130] (960s left) kms_psr (psr-cursor-render)
[32.240795] Starting subtest: psr-cursor-render
[32.243295] Starting dynamic subtest: eDP-1
[34.031815] Dynamic subtest eDP-1: SUCCESS (1.790s)
[34.034635] Subtest psr-cursor-render: SUCCESS (1.790s)
[34.956243] [003/130] (957s left) kms_big_fb (linear-64bpp-rotate-180)
[35.054158] Starting subtest: linear-64bpp-rotate-180
[36.500774] Subtest linear-64bpp-rotate-180: SUCCESS (1.448s)
[36.634329] [004/130] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[36.730999] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[36.735158] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[36.796093] [005/130] (955s left) kms_universal_plane (disable-primary-vs-flip)
[36.884470] Starting subtest: disable-primary-vs-flip
[36.886551] Starting dynamic subtest: pipe-A-eDP-1
[38.476876] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.591s)
[38.484422] Starting dynamic subtest: pipe-B-eDP-1
[40.766491] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.289s)
[40.774465] Starting dynamic subtest: pipe-C-eDP-1
[43.086660] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.318s)
[43.094338] Starting dynamic subtest: pipe-D-eDP-1
[45.385662] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.300s)
[45.388359] Subtest disable-primary-vs-flip: SUCCESS (8.501s)
[46.283154] [006/130] (946s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[46.369200] Starting subtest: fbc-pr-sprite-mmap-gtt
[46.373906] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[46.421539] [007/130] (946s left) syncobj_wait (single-wait-all-for-submit-signaled)
[46.473500] Starting subtest: single-wait-all-for-submit-signaled
[46.475830] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[46.538102] [008/130] (946s left) gem_exec_schedule (pi-distinct-iova)
[46.706561] Starting subtest: pi-distinct-iova
[46.708618] Starting dynamic subtest: rcs0
[46.771189] Dynamic subtest rcs0: SUCCESS (0.067s)
[46.780224] Starting dynamic subtest: vcs0
[46.810050] Dynamic subtest vcs0: SUCCESS (0.039s)
[46.817158] Starting dynamic subtest: vcs1
[46.849559] Dynamic subtest vcs1: SUCCESS (0.039s)
[46.856459] Starting dynamic subtest: vecs0
[46.889243] Dynamic subtest vecs0: SUCCESS (0.039s)
[46.895994] Starting dynamic subtest: ccs0
[46.936639] Dynamic subtest ccs0: SUCCESS (0.048s)
[46.939046] Subtest pi-distinct-iova: SUCCESS (0.233s)
[48.449308] [009/130] (944s left) kms_fbcon_fbt (psr-suspend)
[48.498735] Starting subtest: psr-suspend
[96.299041] Subtest psr-suspend: SUCCESS (17.515s)
[96.361331] [010/130] (926s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[96.444854] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[96.447261] Starting dynamic subtest: pipe-A-default-mode
[97.649674] Dynamic subtest pipe-A-default-mode: SKIP (1.198s)
[97.654648] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.199s)
[97.780327] [011/130] (925s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[97.820320] Starting subtest: etime-multi-wait-for-submit-submitted
[97.927243] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.106s)
[97.980793] [012/130] (925s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[98.077878] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[98.080361] Starting dynamic subtest: pipe-A-eDP-1
[98.103708] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[98.111748] Starting dynamic subtest: pipe-B-eDP-1
[98.134236] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[98.140704] Starting dynamic subtest: pipe-C-eDP-1
[98.163494] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[98.170039] Starting dynamic subtest: pipe-D-eDP-1
[98.192734] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[98.196919] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.002s)
[100.429488] [013/130] (922s left) gem_render_copy (y-tiled)
[100.473120] Starting subtest: y-tiled
[100.477548] Subtest y-tiled: SKIP (0.000s)
[100.629994] [014/130] (922s left) kms_joiner (basic-force-ultra-joiner)
[100.716087] Starting subtest: basic-force-ultra-joiner
[100.720427] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[100.757713] [015/130] (922s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[100.845505] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[105.125062] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.282s)
[105.209951] [016/130] (917s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[105.292882] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[105.295507] Starting dynamic subtest: pipe-A-default-mode
[108.096031] Dynamic subtest pipe-A-default-mode: SUCCESS (2.800s)
[108.098556] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.801s)
[109.006185] [017/130] (914s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[109.089941] Starting subtest: addfb25-y-tiled-small-legacy
[109.094561] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[109.150011] [018/130] (913s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[109.226804] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[109.229110] Starting dynamic subtest: pipe-A-eDP-1
[109.286251] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[109.291984] Starting dynamic subtest: pipe-B-eDP-1
[109.350191] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[109.357802] Starting dynamic subtest: pipe-C-eDP-1
[109.416205] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[109.423561] Starting dynamic subtest: pipe-D-eDP-1
[109.480859] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[109.485050] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[111.566007] [019/130] (911s left) gem_lmem_swapping (heavy-multi)
[111.601524] Subtest heavy-multi: SKIP (0.000s)
[111.618711] [020/130] (911s left) gem_media_vme
[111.696351] [021/130] (911s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[112.183047] Starting subtest: syncobj-timeline-multiple-ext-nodes
[112.185930] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.002s)
[113.705483] [022/130] (909s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[113.801633] Starting subtest: fbc-rgb101010-draw-mmap-wc
[117.353829] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (3.556s)
[117.449897] [023/130] (905s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[117.546843] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[117.549058] Starting dynamic subtest: pipe-A
[117.887894] Dynamic subtest pipe-A: SUCCESS (0.341s)
[117.895113] Starting dynamic subtest: pipe-B
[119.339597] Dynamic subtest pipe-B: SUCCESS (1.452s)
[119.346174] Starting dynamic subtest: pipe-C
[120.811089] Dynamic subtest pipe-C: SUCCESS (1.471s)
[120.819295] Starting dynamic subtest: pipe-D
[122.284219] Dynamic subtest pipe-D: SUCCESS (1.472s)
[122.286600] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.737s)
[123.531592] [024/130] (899s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[123.631460] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[123.636517] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[123.707173] [025/130] (899s left) gem_exec_store (dword)
[123.769385] Starting subtest: dword
[123.771646] Starting dynamic subtest: rcs0
[123.827196] Dynamic subtest rcs0: SUCCESS (0.061s)
[123.838474] Starting dynamic subtest: bcs0
[123.868379] Dynamic subtest bcs0: SUCCESS (0.040s)
[123.876506] Starting dynamic subtest: vcs0
[123.909027] Dynamic subtest vcs0: SUCCESS (0.040s)
[123.918212] Starting dynamic subtest: vcs1
[123.951385] Dynamic subtest vcs1: SUCCESS (0.042s)
[123.960501] Starting dynamic subtest: vecs0
[123.993517] Dynamic subtest vecs0: SUCCESS (0.042s)
[124.002211] Starting dynamic subtest: ccs0
[124.034112] Dynamic subtest ccs0: SUCCESS (0.041s)
[124.037245] Subtest dword: SUCCESS (0.268s)
[124.193672] [026/130] (899s left) gen9_exec_parse (bb-start-param)
[124.249681] Subtest bb-start-param: SKIP (0.000s)
[124.310394] [027/130] (898s left) kms_psr (fbc-psr-primary-mmap-cpu)
[124.404273] Starting subtest: fbc-psr-primary-mmap-cpu
[124.406931] Starting dynamic subtest: eDP-1
[124.411868] Dynamic subtest eDP-1: SKIP (0.000s)
[124.417970] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[124.451788] [028/130] (898s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[129.564201] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[129.569469] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.002s)
[130.447803] [029/130] (892s left) perf (per-context-mode-unprivileged)
[130.579193] Starting subtest: per-context-mode-unprivileged
[130.583397] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[130.603514] [030/130] (892s left) kms_properties (get_properties-sanity-non-atomic)
[130.688784] Starting subtest: get_properties-sanity-non-atomic
[130.691070] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[130.742626] [031/130] (892s left) api_intel_allocator (standalone)
[130.795213] Starting subtest: standalone
[130.812510] Subtest standalone: SUCCESS (0.019s)
[130.887131] [032/130] (892s left) gem_render_copy (x-tiled)
[130.928792] Starting subtest: x-tiled
[130.931259] Starting dynamic subtest: smem
[130.967896] Dynamic subtest smem: SUCCESS (0.040s)
[130.970608] Subtest x-tiled: SUCCESS (0.040s)
[131.125521] [033/130] (892s left) gem_exec_balancer (bonded-false-hang)
[131.332889] Starting subtest: bonded-false-hang
[131.338588] Subtest bonded-false-hang: SKIP (0.000s)
[131.405677] [034/130] (891s left) drm_read (invalid-buffer)
[131.548154] Starting subtest: invalid-buffer
[131.550630] Subtest invalid-buffer: SUCCESS (0.000s)
[131.641072] [035/130] (891s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[131.731872] Starting subtest: y-tiled-16bpp-rotate-0
[131.735868] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[131.792573] [036/130] (891s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[131.901562] Starting subtest: cursorB-vs-flipA-atomic-transitions
[131.906203] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[131.991009] [037/130] (891s left) gem_mmap_gtt (cpuset-big-copy-xy)
[132.032522] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[132.076326] [038/130] (891s left) gem_mmap_gtt (big-bo-tiledx)
[132.125208] Subtest big-bo-tiledX: SKIP (0.000s)
[132.152595] [039/130] (891s left) kms_plane_alpha_blend (constant-alpha-min)
[132.238787] Starting subtest: constant-alpha-min
[132.510339] Starting dynamic subtest: pipe-A-eDP-1
[133.206122] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.696s)
[133.215897] Starting dynamic subtest: pipe-D-eDP-1
[134.741279] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.533s)
[134.743846] Subtest constant-alpha-min: SUCCESS (2.501s)
[135.633868] [040/130] (887s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[135.741510] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[140.570202] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (4.829s)
[140.667842] [041/130] (882s left) kms_rmfb (close-fd)
[140.763867] Starting subtest: close-fd
[140.766254] Starting dynamic subtest: pipe-A-eDP-1
[140.875618] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.109s)
[140.884171] Starting dynamic subtest: pipe-B-eDP-1
[142.151992] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.274s)
[142.160792] Starting dynamic subtest: pipe-C-eDP-1
[143.421350] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.268s)
[143.433764] Starting dynamic subtest: pipe-D-eDP-1
[144.634969] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.215s)
[144.637260] Subtest close-fd: SUCCESS (3.870s)
[145.873405] [042/130] (877s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[145.980260] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[145.984651] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[146.056195] [043/130] (877s left) gem_create (create-ext-set-pat)
[146.211545] Starting subtest: create-ext-set-pat
[146.214249] Subtest create-ext-set-pat: SUCCESS (0.001s)
[146.301973] [044/130] (877s left) kms_chamelium_frames (hdmi-aspect-ratio)
[146.666864] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[147.516337] [045/130] (875s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[147.604509] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[147.608683] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[147.693037] [046/130] (875s left) drm_fdinfo (busy-idle)
[147.726501] Starting subtest: busy-idle
[147.728980] Starting dynamic subtest: rcs0
[147.745043] Dynamic subtest rcs0: SKIP (0.019s)
[147.752780] Starting dynamic subtest: bcs0
[147.757413] Dynamic subtest bcs0: SKIP (0.011s)
[147.763200] Starting dynamic subtest: vcs0
[147.767579] Dynamic subtest vcs0: SKIP (0.000s)
[147.773549] Starting dynamic subtest: vcs1
[147.777701] Dynamic subtest vcs1: SKIP (0.000s)
[147.783711] Starting dynamic subtest: vecs0
[147.788175] Dynamic subtest vecs0: SKIP (0.000s)
[147.793979] Starting dynamic subtest: ccs0
[147.798459] Dynamic subtest ccs0: SKIP (0.000s)
[147.802942] Subtest busy-idle: SKIP (0.033s)
[147.815778] [047/130] (875s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[147.912093] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[147.916328] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[147.967069] [048/130] (875s left) perf (invalid-oa-format-id)
[148.036792] Starting subtest: invalid-oa-format-id
[148.045050] Subtest invalid-oa-format-id: SUCCESS (0.009s)
[148.067867] [049/130] (875s left) kms_addfb_basic (no-handle)
[148.117781] Starting subtest: no-handle
[148.120326] Subtest no-handle: SUCCESS (0.000s)
[148.221606] [050/130] (875s left) sw_sync (sync_busy_fork)
[148.252106] Starting subtest: sync_busy_fork
[149.260277] Subtest sync_busy_fork: SUCCESS (1.010s)
[149.301866] [051/130] (874s left) gem_exec_balancer (nohangcheck)
[149.519063] Starting subtest: nohangcheck
[149.991903] Subtest nohangcheck: SUCCESS (0.473s)
[150.098687] [052/130] (873s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[150.186867] Starting subtest: basic-max-pipe-crc-check
[150.191419] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[150.210915] [053/130] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[150.301587] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[150.305850] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[150.371314] [054/130] (873s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[150.422746] Starting subtest: mixed-tiled-to-y-tiled-ccs
[150.427175] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[150.578962] [055/130] (873s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[150.670768] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[150.685815] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.013s)
[150.735205] [056/130] (872s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[150.785137] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[150.789595] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[150.942848] [057/130] (872s left) syncobj_timeline (device-signal-unordered)
[150.994642] Starting subtest: device-signal-unordered
[150.997039] Subtest device-signal-unordered: SUCCESS (0.000s)
[151.057737] [058/130] (872s left) kms_flip (blocking-absolute-wf_vblank)
[151.149900] Starting subtest: blocking-absolute-wf_vblank
[151.152180] Starting dynamic subtest: A-eDP1
[159.949066] Dynamic subtest A-eDP1: SUCCESS (8.797s)
[159.955957] Starting dynamic subtest: B-eDP1
[168.782557] Dynamic subtest B-eDP1: SUCCESS (8.833s)
[168.791198] Starting dynamic subtest: C-eDP1
[177.603768] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[177.613428] Starting dynamic subtest: D-eDP1
[186.372089] Dynamic subtest D-eDP1: SUCCESS (8.766s)
[186.377483] Subtest blocking-absolute-wf_vblank: SUCCESS (35.228s)
[187.597437] [059/130] (836s left) kms_plane_scaling (planes-upscale-20x20)
[187.683858] Starting subtest: planes-upscale-20x20
[187.686227] Starting dynamic subtest: pipe-A
[187.748091] Dynamic subtest pipe-A: SUCCESS (0.064s)
[187.755949] Starting dynamic subtest: pipe-B
[188.961984] Dynamic subtest pipe-B: SUCCESS (1.212s)
[188.971505] Starting dynamic subtest: pipe-C
[190.153582] Dynamic subtest pipe-C: SUCCESS (1.191s)
[190.161916] Starting dynamic subtest: pipe-D
[191.305557] Dynamic subtest pipe-D: SUCCESS (1.152s)
[191.307910] Subtest planes-upscale-20x20: SUCCESS (3.622s)
[192.524888] [060/130] (831s left) gem_workarounds (suspend-resume-context)
[192.581706] Starting subtest: suspend-resume-context
[208.295160] Subtest suspend-resume-context: SUCCESS (1.043s)
[209.392967] [061/130] (829s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[209.499385] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[214.454378] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.955s)
[214.543388] [062/130] (824s left) kms_addfb_basic (bad-pitch-256)
[214.595020] Starting subtest: bad-pitch-256
[214.597046] Subtest bad-pitch-256: SUCCESS (0.000s)
[214.716154] [063/130] (824s left) gem_ppgtt (shrink-vs-evict-pinned)
[214.765584] Starting subtest: shrink-vs-evict-pinned
[220.303676] Subtest shrink-vs-evict-pinned: SUCCESS (5.536s)
[220.372096] [064/130] (819s left) gem_exec_balancer (persistence)
[220.575313] Starting subtest: persistence
[221.054554] Subtest persistence: SUCCESS (0.479s)
[221.160095] [065/130] (818s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[221.246322] Starting subtest: fbcpsr-shrfb-scaledprimary
[227.025028] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.781s)
[227.135949] [066/130] (812s left) drm_fdinfo (virtual-busy-hang-all)
[227.171157] Starting subtest: virtual-busy-hang-all
[227.175447] Subtest virtual-busy-hang-all: SKIP (0.000s)
[227.211654] [067/130] (812s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[232.359249] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[232.364009] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[233.233556] [068/130] (806s left) syncobj_wait (multi-wait-all-signaled)
[233.286224] Starting subtest: multi-wait-all-signaled
[233.288727] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[233.337379] [069/130] (806s left) kms_flip (2x-flip-vs-expired-vblank)
[233.429482] Starting subtest: 2x-flip-vs-expired-vblank
[233.444454] Subtest 2x-flip-vs-expired-vblank: SKIP (0.014s)
[233.495296] [070/130] (806s left) gem_exec_schedule (semaphore-power)
[233.670909] Subtest semaphore-power: SKIP (0.000s)
[233.821716] [071/130] (805s left) kms_cursor_crc (cursor-alpha-transparent)
[233.909492] Starting subtest: cursor-alpha-transparent
[233.911875] Starting dynamic subtest: pipe-A-eDP-1
[234.488054] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[234.496522] Starting dynamic subtest: pipe-D-eDP-1
[235.803917] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.314s)
[235.805918] Subtest cursor-alpha-transparent: SUCCESS (1.893s)
[236.667888] [072/130] (802s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[241.827826] Starting subtest: psr2-plane-move-sf-dmg-area
[241.830725] Starting dynamic subtest: pipe-A-eDP-1
[247.218698] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.392s)
[247.226265] Starting dynamic subtest: pipe-B-eDP-1
[252.585255] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.366s)
[252.589392] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.761s)
[253.455783] [073/130] (786s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-gtt)
[253.555072] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-gtt
[253.559545] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[253.626225] [074/130] (785s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[253.713601] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[253.717667] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[253.788924] [075/130] (785s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[253.881108] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[253.885314] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[253.944463] [076/130] (785s left) kms_vrr (negative-basic)
[254.026370] Starting subtest: negative-basic
[254.033219] Starting dynamic subtest: pipe-A-eDP-1
[259.446193] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.410s)
[259.734935] Subtest negative-basic: SUCCESS (5.705s)
[260.632137] [077/130] (778s left) perf_pmu (busy-idle-check-all)
[260.669990] Starting subtest: busy-idle-check-all
[260.672340] Starting dynamic subtest: rcs0
[261.331369] Dynamic subtest rcs0: SUCCESS (0.663s)
[261.341766] Starting dynamic subtest: bcs0
[261.982553] Dynamic subtest bcs0: SUCCESS (0.650s)
[261.992315] Starting dynamic subtest: vcs0
[263.344191] Dynamic subtest vcs0: SUCCESS (1.358s)
[263.353933] Starting dynamic subtest: vcs1
[265.328483] Dynamic subtest vcs1: SUCCESS (1.983s)
[265.337081] Starting dynamic subtest: vecs0
[267.312164] Dynamic subtest vecs0: SUCCESS (1.982s)
[267.319991] Starting dynamic subtest: ccs0
[268.021219] Dynamic subtest ccs0: FAIL (0.636s)
[268.023582] Subtest busy-idle-check-all: FAIL (7.280s)
[268.041379] [078/130] (771s left) gem_mmap_wc (write)
[268.095125] Subtest write: SKIP (0.000s)
[268.135486] [079/130] (771s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[268.230147] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[268.234597] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[268.292768] [080/130] (771s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[268.384129] Starting subtest: 4-tiled-8bpp-rotate-180
[269.360702] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (0.979s)
[269.449955] [081/130] (770s left) kms_pm_rpm (basic-pci-d3-state)
[271.463925] Starting subtest: basic-pci-d3-state
[272.912909] Subtest basic-pci-d3-state: SUCCESS (1.448s)
[272.975742] [082/130] (766s left) kms_chamelium_color (degamma)
[273.068658] Subtest degamma: SKIP (0.000s)
[273.092091] [083/130] (766s left) kms_psr (pr-sprite-render)
[273.182484] Starting subtest: pr-sprite-render
[273.187025] Subtest pr-sprite-render: SKIP (0.000s)
[273.229834] [084/130] (766s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[273.324334] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[273.326709] Starting dynamic subtest: pipe-A-eDP-1
[273.352307] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[273.360978] Starting dynamic subtest: pipe-B-eDP-1
[273.385521] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[273.393057] Starting dynamic subtest: pipe-C-eDP-1
[273.417588] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[273.425161] Starting dynamic subtest: pipe-D-eDP-1
[273.450049] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[273.454220] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[275.667086] [085/130] (764s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[275.722827] Starting subtest: saturated-hostile-nopreempt
[275.725580] Starting dynamic subtest: rcs0
[275.806402] Dynamic subtest rcs0: SKIP (0.080s)
[275.813965] Starting dynamic subtest: bcs0
[275.821155] Dynamic subtest bcs0: SKIP (0.014s)
[275.827225] Starting dynamic subtest: vcs0
[275.847685] Dynamic subtest vcs0: SKIP (0.027s)
[275.853958] Starting dynamic subtest: vcs1
[275.858693] Dynamic subtest vcs1: SKIP (0.010s)
[275.864897] Starting dynamic subtest: vecs0
[275.878108] Dynamic subtest vecs0: SKIP (0.021s)
[275.884072] Starting dynamic subtest: ccs0
[275.897182] Dynamic subtest ccs0: SKIP (0.019s)
[275.901735] Subtest saturated-hostile-nopreempt: SKIP (0.173s)
[275.946831] [086/130] (763s left) gem_ctx_param (invalid-set-no-zeromap)
[275.988631] Starting subtest: invalid-set-no-zeromap
[275.990997] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[276.036918] [087/130] (763s left) syncobj_wait (invalid-reset-zero-handles)
[276.090088] Starting subtest: invalid-reset-zero-handles
[276.092309] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[276.163260] [088/130] (763s left) kms_atomic (plane-invalid-params)
[276.248643] Starting subtest: plane-invalid-params
[276.250932] Starting dynamic subtest: pipe-A-eDP-1
[276.640163] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[276.642348] Subtest plane-invalid-params: SUCCESS (0.391s)
[277.528945] [089/130] (762s left) kms_cursor_crc (cursor-sliding-256x85)
[277.731262] Starting subtest: cursor-sliding-256x85
[277.766606] Subtest cursor-sliding-256x85: SKIP (0.028s)
[277.876179] [090/130] (761s left) kms_universal_plane (universal-plane-pageflip-windowed)
[277.963375] Starting subtest: universal-plane-pageflip-windowed
[277.965709] Starting dynamic subtest: pipe-A-eDP-1
[278.527312] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[278.536023] Starting dynamic subtest: pipe-B-eDP-1
[279.859923] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.331s)
[279.868983] Starting dynamic subtest: pipe-C-eDP-1
[281.129879] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.270s)
[281.142879] Starting dynamic subtest: pipe-D-eDP-1
[282.408137] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.277s)
[282.410730] Subtest universal-plane-pageflip-windowed: SUCCESS (4.443s)
[283.287689] [091/130] (756s left) kms_cursor_crc (cursor-sliding-512x170)
[283.503740] Starting subtest: cursor-sliding-512x170
[283.507888] Subtest cursor-sliding-512x170: SKIP (0.001s)
[283.623670] [092/130] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[283.718536] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[283.722692] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[283.813333] [093/130] (756s left) gem_spin_batch (spin-all-new)
[283.867517] Starting subtest: spin-all-new
[283.877957] Subtest spin-all-new: SUCCESS (0.011s)
[285.308799] [094/130] (754s left) gem_exec_reloc (basic-write-wc-noreloc)
[285.360859] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[285.405132] [095/130] (754s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[285.494063] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[285.498281] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[285.561232] [096/130] (754s left) gem_wait (invalid-flags)
[285.610989] Starting subtest: invalid-flags
[285.612976] Subtest invalid-flags: SUCCESS (0.000s)
[285.791041] [097/130] (754s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[286.156100] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[287.040826] [098/130] (752s left) i915_pm_rps (fence-order)
[287.092168] Starting subtest: fence-order
[290.506731] Subtest fence-order: SUCCESS (3.411s)
[290.562492] [099/130] (749s left) kms_psr (pr-dpms)
[290.655415] Starting subtest: pr-dpms
[290.659858] Subtest pr-dpms: SKIP (0.000s)
[290.703737] [100/130] (749s left) kms_cursor_crc (cursor-offscreen-32x10)
[290.844116] Starting subtest: cursor-offscreen-32x10
[290.877237] Subtest cursor-offscreen-32x10: SKIP (0.028s)
[291.094586] [101/130] (748s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[291.176320] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[292.030455] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.855s)
[292.117107] [102/130] (747s left) testdisplay
[292.427224] [103/130] (747s left) syncobj_timeline (multi-wait-available-signaled)
[292.471389] Starting subtest: multi-wait-available-signaled
[292.473204] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[292.543430] [104/130] (747s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[292.617042] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[292.619136] Starting dynamic subtest: pipe-A-eDP-1
[292.638534] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[292.647007] Starting dynamic subtest: pipe-B-eDP-1
[292.666002] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[292.671773] Starting dynamic subtest: pipe-C-eDP-1
[292.689963] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[292.695800] Starting dynamic subtest: pipe-D-eDP-1
[292.714258] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[292.717715] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[294.931656] [105/130] (745s left) kms_display_modes (mst-extended-mode-negative)
[295.021245] Starting subtest: mst-extended-mode-negative
[295.025604] Subtest mst-extended-mode-negative: SKIP (0.000s)
[295.049821] [106/130] (745s left) core_setmaster (master-drop-set-user)
[295.743982] Starting subtest: master-drop-set-user
[295.757834] Subtest master-drop-set-user: SUCCESS (0.015s)
[295.773665] [107/130] (744s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[295.864069] Starting subtest: yf-tiled-8bpp-rotate-90
[295.868488] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[295.936448] [108/130] (744s left) kms_rotation_crc (sprite-rotation-90)
[296.012983] Starting subtest: sprite-rotation-90
[296.016852] Subtest sprite-rotation-90: SKIP (0.001s)
[296.062168] [109/130] (744s left) syncobj_basic (bad-destroy-pad)
[296.097491] Starting subtest: bad-destroy-pad
[296.099536] Subtest bad-destroy-pad: SUCCESS (0.000s)
[296.169044] [110/130] (743s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[296.250793] Starting subtest: primary-yf-tiled-reflect-x-90
[296.254845] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[296.300185] [111/130] (743s left) gem_madvise (dontneed-after-mmap)
[296.328185] Starting subtest: dontneed-after-mmap
[296.346749] Subtest dontneed-after-mmap: SUCCESS (0.020s)
[296.430099] [112/130] (743s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[296.480576] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[296.588798] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[296.638189] [113/130] (743s left) gem_mmap_gtt (medium-copy-xy)
[296.690001] Subtest medium-copy-XY: SKIP (0.000s)
[296.723677] [114/130] (743s left) gem_softpin (allocator-evict)
[296.777333] Starting subtest: allocator-evict
[296.779965] Starting dynamic subtest: rcs0
[319.569038] Dynamic subtest rcs0: SUCCESS (22.792s)
[319.576115] Starting dynamic subtest: bcs0
[343.577403] Dynamic subtest bcs0: SUCCESS (24.006s)
[343.585797] Starting dynamic subtest: vcs0
[366.692032] Dynamic subtest vcs0: SUCCESS (23.113s)
[366.698792] Starting dynamic subtest: vcs1
[389.852844] Dynamic subtest vcs1: SUCCESS (23.158s)
[389.861974] Starting dynamic subtest: vecs0
[413.190555] Dynamic subtest vecs0: SUCCESS (23.335s)
[413.198568] Starting dynamic subtest: ccs0
[436.859555] Dynamic subtest ccs0: SUCCESS (23.666s)
[436.862366] Subtest allocator-evict: SUCCESS (140.075s)
[436.913611] [115/130] (603s left) syncobj_timeline (wait-all-interrupted)
[437.002627] Starting subtest: wait-all-interrupted
[437.108411] Subtest wait-all-interrupted: SUCCESS (0.104s)
[437.164078] [116/130] (603s left) gem_exec_fence (keep-in-fence)
[437.321957] Starting subtest: keep-in-fence
[437.324620] Starting dynamic subtest: rcs0
[437.379025] Dynamic subtest rcs0: SUCCESS (0.059s)
[437.386133] Starting dynamic subtest: bcs0
[437.437393] Dynamic subtest bcs0: SUCCESS (0.057s)
[437.447708] Starting dynamic subtest: vcs0
[439.280848] Dynamic subtest vcs0: SUCCESS (1.841s)
[439.291851] Starting dynamic subtest: vcs1
[441.264934] Dynamic subtest vcs1: SUCCESS (1.983s)
[441.275775] Starting dynamic subtest: vecs0
[443.315475] Dynamic subtest vecs0: SUCCESS (2.047s)
[443.323703] Starting dynamic subtest: ccs0
[443.501689] Dynamic subtest ccs0: SUCCESS (0.188s)
[443.504592] Subtest keep-in-fence: SUCCESS (6.181s)
[445.341080] [117/130] (594s left) i915_pm_rpm (reg-read-ioctl)
[447.386996] Starting subtest: reg-read-ioctl
[449.680102] Subtest reg-read-ioctl: SUCCESS (2.293s)
[450.028547] [118/130] (590s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[450.124466] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[450.128771] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[450.194762] [119/130] (590s left) gem_exec_reloc (basic-wc-read-noreloc)
[450.250558] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[450.299962] [120/130] (589s left) kms_hdr (bpc-switch-dpms)
[450.383298] Starting subtest: bpc-switch-dpms
[451.503526] Starting dynamic subtest: pipe-A-eDP-1
[456.242253] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.738s)
[456.245015] Subtest bpc-switch-dpms: SUCCESS (5.858s)
[456.329996] [121/130] (583s left) gen7_exec_parse (batch-without-end)
[456.379655] Subtest batch-without-end: SKIP (0.000s)
[456.426791] [122/130] (583s left) kms_plane_scaling (planes-upscale-factor-0-25)
[456.508460] Starting subtest: planes-upscale-factor-0-25
[456.510785] Starting dynamic subtest: pipe-A
[456.573962] Dynamic subtest pipe-A: SUCCESS (0.065s)
[456.581659] Starting dynamic subtest: pipe-B
[457.766271] Dynamic subtest pipe-B: SUCCESS (1.191s)
[457.776457] Starting dynamic subtest: pipe-C
[458.958204] Dynamic subtest pipe-C: SUCCESS (1.192s)
[458.966425] Starting dynamic subtest: pipe-D
[460.110795] Dynamic subtest pipe-D: SUCCESS (1.152s)
[460.113275] Subtest planes-upscale-factor-0-25: SUCCESS (3.603s)
[461.333276] [123/130] (578s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[461.417550] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[466.293757] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.881s)
[466.371119] [124/130] (573s left) gem_exec_flush (basic-wb-rw-default)
[466.427182] Starting subtest: basic-wb-rw-default
[471.860523] Subtest basic-wb-rw-default: SUCCESS (5.420s)
[472.006777] [125/130] (568s left) syncobj_timeline (signal-point-0)
[472.045515] Starting subtest: signal-point-0
[472.048077] Subtest signal-point-0: SUCCESS (0.000s)
[472.108750] [126/130] (568s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[472.200415] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[472.202601] Starting dynamic subtest: pipe-A-eDP-1
[472.224729] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[472.232189] Starting dynamic subtest: pipe-B-eDP-1
[472.254023] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[472.261236] Starting dynamic subtest: pipe-C-eDP-1
[472.282933] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[472.289581] Starting dynamic subtest: pipe-D-eDP-1
[472.311796] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[472.316236] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[474.514445] [127/130] (565s left) gem_exec_flush (basic-uc-ro-default)
[474.577151] Starting subtest: basic-uc-ro-default
[480.008560] Subtest basic-uc-ro-default: SUCCESS (5.424s)
[480.160598] [128/130] (560s left) gem_busy (busy)
[480.220441] Starting subtest: busy
[480.223732] Starting dynamic subtest: all-engines
[480.304317] Dynamic subtest all-engines: SUCCESS (0.083s)
[480.313534] Starting dynamic subtest: rcs0
[482.296232] Dynamic subtest rcs0: SUCCESS (1.992s)
[482.305165] Starting dynamic subtest: bcs0
[482.345980] Dynamic subtest bcs0: SUCCESS (0.049s)
[482.354614] Starting dynamic subtest: vcs0
[482.388272] Dynamic subtest vcs0: SUCCESS (0.039s)
[482.396204] Starting dynamic subtest: vcs1
[484.277685] Dynamic subtest vcs1: SUCCESS (1.887s)
[484.286963] Starting dynamic subtest: vecs0
[486.325290] Dynamic subtest vecs0: SUCCESS (2.047s)
[486.334883] Starting dynamic subtest: ccs0
[488.308783] Dynamic subtest ccs0: SUCCESS (1.984s)
[488.311350] Subtest busy: SUCCESS (8.087s)
[488.658823] [129/130] (551s left) kms_feature_discovery (display-4x)
[488.754390] Starting subtest: display-4x
[488.758770] Subtest display-4x: SKIP (0.000s)
[488.800879] [130/130] (551s left) perf_pmu (semaphore-busy)
[488.832247] Starting subtest: semaphore-busy
[488.834329] Starting dynamic subtest: rcs0
[490.473861] Dynamic subtest rcs0: SUCCESS (1.639s)
[490.482351] Starting dynamic subtest: bcs0
[492.101984] Dynamic subtest bcs0: SUCCESS (1.627s)
[492.109387] Starting dynamic subtest: vcs0
[495.850426] Dynamic subtest vcs0: SUCCESS (3.747s)
[495.859022] Starting dynamic subtest: vcs1
[501.803253] Dynamic subtest vcs1: SUCCESS (5.950s)
[501.810960] Starting dynamic subtest: vecs0
[507.818552] Dynamic subtest vecs0: SUCCESS (6.014s)
[507.826363] Starting dynamic subtest: ccs0
[510.893922] Dynamic subtest ccs0: SUCCESS (3.074s)
[510.896444] Subtest semaphore-busy: SUCCESS (22.059s)
[510.986042] Closing watchdogs
Done.