[56.513562] Initializing watchdogs
[56.513610]   /dev/watchdog0
[56.539552] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.543787] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.546097] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.549297] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[56.843247] Starting subtest: noheartbeat
[56.848356] Subtest noheartbeat: SKIP (0.000s)
[56.973505] [002/132] (960s left) prime_vgem (basic-gtt)
[57.071366] Starting subtest: basic-gtt
[57.076092] Subtest basic-gtt: SKIP (0.000s)
[57.195213] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[57.198127] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[57.300421] Starting subtest: fbc-1p-pri-indfb-multidraw
[70.818019] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.522s)
[72.267881] [004/132] (944s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[72.361708] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[81.580038] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.220s)
[81.737006] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[81.845795] Starting subtest: rmfb-ioctl
[81.847687] Starting dynamic subtest: pipe-A-eDP-1
[81.950862] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[81.960299] Starting dynamic subtest: pipe-B-eDP-1
[83.101742] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.149s)
[83.114739] Starting dynamic subtest: pipe-C-eDP-1
[84.320730] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.215s)
[84.336694] Starting dynamic subtest: pipe-D-eDP-1
[85.541724] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.219s)
[85.544067] Subtest rmfb-ioctl: SUCCESS (3.698s)
[86.769953] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[86.885611] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[93.472689] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.588s)
[93.645571] [007/132] (923s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[94.050225] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[94.895261] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[94.998859] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[95.001230] Starting dynamic subtest: pipe-A-default-mode
[96.174617] Dynamic subtest pipe-A-default-mode: SKIP (1.174s)
[96.179288] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.175s)
[96.374155] [009/132] (920s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[96.474443] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[96.478841] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[96.611123] [010/132] (920s left) gem_exec_reloc (basic-write-read-noreloc)
[96.683796] Subtest basic-write-read-noreloc: SKIP (0.000s)
[96.754050] [011/132] (920s left) kms_draw_crc (draw-method-blt)
[96.850211] Starting subtest: draw-method-blt
[96.852156] Starting dynamic subtest: xrgb8888-untiled
[97.082132] Dynamic subtest xrgb8888-untiled: SUCCESS (0.234s)
[97.089228] Starting dynamic subtest: rgb565-untiled
[97.327055] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[97.334227] Starting dynamic subtest: xrgb2101010-untiled
[97.548782] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[97.556105] Starting dynamic subtest: xbgr16161616f-untiled
[97.772153] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.222s)
[97.779204] Starting dynamic subtest: xrgb8888-xtiled
[97.893332] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[97.902673] Starting dynamic subtest: rgb565-xtiled
[98.015470] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[98.023382] Starting dynamic subtest: xrgb2101010-xtiled
[98.126667] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[98.134588] Starting dynamic subtest: xbgr16161616f-xtiled
[98.237630] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[98.245720] Starting dynamic subtest: xrgb8888-4tiled
[98.348940] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.111s)
[98.356970] Starting dynamic subtest: rgb565-4tiled
[98.471021] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s)
[98.479704] Starting dynamic subtest: xrgb2101010-4tiled
[98.582736] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[98.595291] Starting dynamic subtest: xbgr16161616f-4tiled
[98.704422] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.120s)
[98.706487] Subtest draw-method-blt: SUCCESS (1.857s)
[98.881535] [012/132] (918s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[98.983231] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[98.985153] Starting dynamic subtest: pipe-A-eDP-1
[99.003330] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[99.014416] Starting dynamic subtest: pipe-B-eDP-1
[99.032445] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[99.042958] Starting dynamic subtest: pipe-C-eDP-1
[99.061971] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[99.070954] Starting dynamic subtest: pipe-D-eDP-1
[99.089725] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[99.093163] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[101.334974] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[101.484731] Starting subtest: gen12-group-exclusive-stream-sample-oa
[101.505853] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.020s)
[101.556964] [014/132] (916s left) gem_create (hog-create)
[101.611091] Starting subtest: hog-create
[101.613731] Starting dynamic subtest: smem0
[134.965071] Dynamic subtest smem0: SUCCESS (33.352s)
[134.967784] Subtest hog-create: SUCCESS (33.353s)
[135.178532] [015/132] (882s left) kms_cdclk (plane-scaling)
[135.276729] Starting subtest: plane-scaling
[135.278968] Starting dynamic subtest: pipe-A-eDP-1
[135.570329] Dynamic subtest pipe-A-eDP-1: SKIP (0.290s)
[135.579098] Starting dynamic subtest: pipe-B-eDP-1
[135.583492] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[135.591082] Starting dynamic subtest: pipe-C-eDP-1
[135.595382] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[135.602986] Starting dynamic subtest: pipe-D-eDP-1
[135.607249] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[135.611388] Subtest plane-scaling: SKIP (0.296s)
[136.441607] [016/132] (881s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[136.550706] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[136.554835] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[136.691806] [017/132] (881s left) gem_softpin (softpin)
[136.761572] Starting subtest: softpin
[169.083504] Subtest softpin: SUCCESS (32.308s)
[169.235869] [018/132] (848s left) kms_joiner (basic-force-ultra-joiner)
[169.336396] Starting subtest: basic-force-ultra-joiner
[169.340459] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[169.427153] [019/132] (848s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[169.518565] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[169.531026] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[169.630884] [020/132] (848s left) syncobj_timeline (reset-multiple-signaled)
[169.687190] Starting subtest: reset-multiple-signaled
[169.689122] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[169.805812] [021/132] (848s left) drm_mm (drm_mm)
[169.842232] Starting subtest: drm_mm
[169.896269] Starting dynamic subtest: drm_test_mm_init
[170.015555] Dynamic subtest drm_test_mm_init: SUCCESS (0.117s)
[170.025233] Starting dynamic subtest: drm_test_mm_debug
[170.095346] Dynamic subtest drm_test_mm_debug: SUCCESS (0.080s)
[170.104262] Starting dynamic subtest: drm_test_mm_align32
[170.216374] Dynamic subtest drm_test_mm_align32: SUCCESS (0.120s)
[170.225305] Starting dynamic subtest: drm_test_mm_align64
[170.313903] Dynamic subtest drm_test_mm_align64: SUCCESS (0.099s)
[170.321289] Starting dynamic subtest: drm_test_mm_lowest
[170.435945] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.120s)
[170.443080] Starting dynamic subtest: drm_test_mm_highest
[170.513340] Dynamic subtest drm_test_mm_highest: SUCCESS (0.077s)
[170.515306] Subtest drm_mm: SUCCESS (0.671s)
[170.678947] [022/132] (847s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[175.681457] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[175.686052] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[176.555149] [023/132] (841s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[176.662333] Starting subtest: plane-ctm3x4-lut1d
[176.675490] Starting dynamic subtest: pipe-A-eDP-1
[177.428510] Dynamic subtest pipe-A-eDP-1: FAIL (0.356s)
[178.113955] Starting dynamic subtest: pipe-B-eDP-1
[178.950530] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[179.549669] Starting dynamic subtest: pipe-C-eDP-1
[180.366321] Dynamic subtest pipe-C-eDP-1: FAIL (0.236s)
[180.868140] Starting dynamic subtest: pipe-D-eDP-1
[181.719665] Dynamic subtest pipe-D-eDP-1: FAIL (0.281s)
[181.722034] Subtest plane-ctm3x4-lut1d: FAIL (4.769s)
[182.272589] [024/132] (835s left) kms_psr (fbc-psr-primary-mmap-gtt)
[182.377756] Starting subtest: fbc-psr-primary-mmap-gtt
[182.379937] Starting dynamic subtest: eDP-1
[182.383846] Dynamic subtest eDP-1: SKIP (0.000s)
[182.387776] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[182.472714] [025/132] (835s left) gem_exec_schedule (u-semaphore-user)
[182.555640] Starting subtest: u-semaphore-user
[182.605551] Subtest u-semaphore-user: SUCCESS (0.058s)
[183.955844] [026/132] (834s left) gem_userptr_blits (unsync-unmap-cycles)
[184.018832] Subtest unsync-unmap-cycles: SKIP (0.000s)
[184.200569] [027/132] (833s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[184.294286] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[184.296396] Starting dynamic subtest: pipe-A-default-mode
[184.590233] Dynamic subtest pipe-A-default-mode: SKIP (0.292s)
[184.594623] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.294s)
[185.417621] [028/132] (832s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[185.526067] Starting subtest: fbc-rgb565-draw-pwrite
[190.024620] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.502s)
[191.217650] [029/132] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[191.328319] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[198.881357] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.556s)
[199.065773] [030/132] (819s left) kms_cursor_edge_walk (256x256-right-edge)
[199.163120] Starting subtest: 256x256-right-edge
[199.165489] Starting dynamic subtest: pipe-A-eDP-1
[202.580794] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.417s)
[202.592363] Starting dynamic subtest: pipe-D-eDP-1
[205.700827] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[205.703052] Subtest 256x256-right-edge: SUCCESS (6.539s)
[206.512990] [031/132] (811s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[206.626952] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[210.984121] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.362s)
[212.119827] [032/132] (806s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[212.222438] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[212.227048] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[212.362350] [033/132] (806s left) i915_pm_rc6_residency (media-rc6-accuracy)
[212.420223] Starting subtest: media-rc6-accuracy
[212.424304] Subtest media-rc6-accuracy: SKIP (0.000s)
[212.540331] [034/132] (805s left) kms_async_flips (async-flip-hang)
[212.632384] Starting subtest: async-flip-hang
[213.005289] Starting dynamic subtest: pipe-A-eDP-1
[239.412735] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.405s)
[239.802858] Starting dynamic subtest: pipe-B-eDP-1
[255.408074] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.610s)
[255.781828] Starting dynamic subtest: pipe-C-eDP-1
[271.346643] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.570s)
[271.742784] Starting dynamic subtest: pipe-D-eDP-1
[287.344623] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.604s)
[287.347188] Subtest async-flip-hang: SUCCESS (74.710s)
[288.567848] [035/132] (729s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[288.666820] Starting subtest: fbc-rgb101010-draw-render
[293.266898] Subtest fbc-rgb101010-draw-render: SUCCESS (4.603s)
[294.480162] [036/132] (724s left) kms_pm_dc (dc5-psr)
[295.598054] Starting subtest: dc5-psr
[298.567668] Subtest dc5-psr: SUCCESS (2.969s)
[299.398429] [037/132] (719s left) kms_getfb (getfb-handle-valid)
[299.494080] Starting subtest: getfb-handle-valid
[299.496410] Subtest getfb-handle-valid: SUCCESS (0.000s)
[299.642568] [038/132] (718s left) perf (oa-formats)
[299.752771] Starting subtest: oa-formats
[299.755217] Starting dynamic subtest: 0-rcs0
[299.766242] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[299.776474] Starting dynamic subtest: 1-vcs0
[299.788228] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[299.790500] Subtest oa-formats: SUCCESS (0.037s)
[299.830238] [039/132] (718s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[299.901812] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[299.906416] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[300.108940] [040/132] (718s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[300.211103] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[300.215320] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[300.343013] [041/132] (718s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[300.450114] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[300.452437] Starting dynamic subtest: pipe-A-eDP-1
[317.235792] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.324s)
[317.245605] Starting dynamic subtest: pipe-B-eDP-1
[335.188405] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.817s)
[335.198055] Starting dynamic subtest: pipe-C-eDP-1
[353.149906] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.822s)
[353.158827] Starting dynamic subtest: pipe-D-eDP-1
[371.158736] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.756s)
[371.161079] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (14.724s)
[382.419144] [042/132] (646s left) gem_exec_fence (keep-in-fence)
[382.637417] Starting subtest: keep-in-fence
[382.639359] Starting dynamic subtest: rcs0
[382.711555] Dynamic subtest rcs0: SUCCESS (0.076s)
[382.723094] Starting dynamic subtest: bcs0
[382.769983] Dynamic subtest bcs0: SUCCESS (0.056s)
[382.781313] Starting dynamic subtest: vcs0
[383.878939] Dynamic subtest vcs0: SUCCESS (1.104s)
[383.890245] Starting dynamic subtest: vcs1
[385.863412] Dynamic subtest vcs1: SUCCESS (1.979s)
[385.875106] Starting dynamic subtest: vecs0
[387.909973] Dynamic subtest vecs0: SUCCESS (2.043s)
[387.921749] Starting dynamic subtest: ccs0
[388.142100] Dynamic subtest ccs0: SUCCESS (0.230s)
[388.145082] Subtest keep-in-fence: SUCCESS (5.502s)
[390.015936] [043/132] (638s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[390.130305] Starting subtest: psr-rgb565-draw-pwrite
[397.070103] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.940s)
[397.218580] [044/132] (631s left) perf (per-context-mode-unprivileged)
[397.323696] Starting subtest: per-context-mode-unprivileged
[397.328236] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[397.371512] [045/132] (631s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[397.474035] Starting subtest: x-tiled-8bpp-rotate-0
[398.676621] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.202s)
[398.834518] [046/132] (630s left) gem_lmem_swapping (parallel-random-verify-ccs)
[398.876381] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[398.918613] [047/132] (630s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[399.046305] Starting subtest: 4-tiled-64bpp-rotate-180
[400.680919] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.632s)
[400.883711] [048/132] (628s left) gem_exec_schedule (preempt)
[400.965044] Starting subtest: preempt
[400.967035] Starting dynamic subtest: rcs0
[400.981042] Dynamic subtest rcs0: SUCCESS (0.024s)
[400.990520] Starting dynamic subtest: bcs0
[401.000526] Dynamic subtest bcs0: SUCCESS (0.019s)
[401.009431] Starting dynamic subtest: vcs0
[401.022730] Dynamic subtest vcs0: SUCCESS (0.022s)
[401.031445] Starting dynamic subtest: vcs1
[401.043655] Dynamic subtest vcs1: SUCCESS (0.020s)
[401.052310] Starting dynamic subtest: vecs0
[401.062476] Dynamic subtest vecs0: SUCCESS (0.018s)
[401.071568] Starting dynamic subtest: ccs0
[401.086611] Dynamic subtest ccs0: SUCCESS (0.024s)
[401.088835] Subtest preempt: SUCCESS (0.130s)
[402.993559] [049/132] (626s left) kms_flip (2x-wf_vblank-ts-check)
[403.081041] Starting subtest: 2x-wf_vblank-ts-check
[403.090464] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[403.194609] [050/132] (625s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[403.303836] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[403.305763] Starting dynamic subtest: pipe-A-eDP-1
[403.324903] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[403.334397] Starting dynamic subtest: pipe-B-eDP-1
[403.353746] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[403.362645] Starting dynamic subtest: pipe-C-eDP-1
[403.382081] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[403.390573] Starting dynamic subtest: pipe-D-eDP-1
[403.409623] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[403.411508] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.016s)
[405.692148] [051/132] (623s left) sw_sync (alloc_timeline)
[405.733268] Starting subtest: alloc_timeline
[405.735378] Subtest alloc_timeline: SUCCESS (0.000s)
[405.809264] [052/132] (623s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[405.868262] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[405.870452] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[405.985156] [053/132] (623s left) kms_atomic_transition (modeset-transition-nonblocking)
[406.086943] Starting subtest: modeset-transition-nonblocking
[406.089240] Starting dynamic subtest: 1x-outputs
[417.174879] Dynamic subtest 1x-outputs: SUCCESS (11.086s)
[417.177644] Subtest modeset-transition-nonblocking: SUCCESS (11.088s)
[418.055311] [054/132] (611s left) kms_pm_rpm (cursor)
[420.079870] Starting subtest: cursor
[420.084974] Subtest cursor: SKIP (0.000s)
[420.523780] [055/132] (608s left) kms_selftest (drm_damage_helper)
[420.566475] Starting subtest: drm_damage_helper
[420.594771] Starting dynamic subtest: drm_test_damage_iter_no_damage
[420.731819] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.135s)
[420.740875] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[420.863987] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.130s)
[420.878463] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[421.010256] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.146s)
[421.019186] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[421.151886] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.141s)
[421.160737] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[421.298489] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.144s)
[421.308285] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[421.435500] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.137s)
[421.444300] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[421.565124] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.130s)
[421.574331] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[421.710637] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.143s)
[421.719222] Starting dynamic subtest: drm_test_damage_iter_single_damage
[421.824241] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.112s)
[421.834556] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[421.962486] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.137s)
[421.970994] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[422.091107] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.127s)
[422.101564] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[422.248997] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.157s)
[422.259407] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[422.387157] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.137s)
[422.396329] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[422.529748] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.141s)
[422.546623] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[422.658379] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.127s)
[422.676545] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[422.794757] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.134s)
[422.812438] Starting dynamic subtest: drm_test_damage_iter_damage
[422.942012] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.147s)
[422.951109] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[423.090772] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.148s)
[423.101490] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[423.225589] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.135s)
[423.234452] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[423.363261] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.135s)
[423.372480] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[423.486592] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.121s)
[423.488803] Subtest drm_damage_helper: SUCCESS (2.917s)
[423.693538] [056/132] (605s left) gem_userptr_blits (unsync-overlap)
[423.768301] Subtest unsync-overlap: SKIP (0.000s)
[423.912874] [057/132] (605s left) kms_psr (pr-sprite-mmap-gtt)
[424.004286] Starting subtest: pr-sprite-mmap-gtt
[424.008752] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[424.103741] [058/132] (605s left) kms_psr (psr-sprite-mmap-gtt)
[424.195633] Starting subtest: psr-sprite-mmap-gtt
[424.197962] Starting dynamic subtest: eDP-1
[425.685594] Dynamic subtest eDP-1: SKIP (1.486s)
[425.690279] Subtest psr-sprite-mmap-gtt: SKIP (1.488s)
[425.890426] [059/132] (603s left) gem_exec_parallel (basic)
[425.957190] Starting subtest: basic
[425.959591] Starting dynamic subtest: rcs0
[427.402290] Dynamic subtest rcs0: SUCCESS (1.444s)
[427.411912] Starting dynamic subtest: bcs0
[428.868242] Dynamic subtest bcs0: SUCCESS (1.464s)
[428.880615] Starting dynamic subtest: vcs0
[430.854742] Dynamic subtest vcs0: SUCCESS (1.982s)
[430.867318] Starting dynamic subtest: vcs1
[432.838019] Dynamic subtest vcs1: SUCCESS (1.980s)
[432.848858] Starting dynamic subtest: vecs0
[434.886793] Dynamic subtest vecs0: SUCCESS (2.045s)
[434.899105] Starting dynamic subtest: ccs0
[436.380521] Dynamic subtest ccs0: SUCCESS (1.492s)
[436.383336] Subtest basic: SUCCESS (10.422s)
[436.593190] [060/132] (592s left) kms_content_protection (content-type-change)
[436.742125] Starting subtest: content-type-change
[436.746644] Subtest content-type-change: SKIP (0.000s)
[436.895585] [061/132] (592s left) gem_mmap_wc (write-read-distinct)
[436.960358] Subtest write-read-distinct: SKIP (0.000s)
[437.035758] [062/132] (592s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[437.138532] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[437.918340] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.781s)
[438.149590] [063/132] (591s left) gem_ctx_persistence (engines-mixed-process)
[438.218781] Starting subtest: engines-mixed-process
[438.221049] Starting dynamic subtest: rcs0
[439.881762] Dynamic subtest rcs0: SUCCESS (1.662s)
[439.894100] Starting dynamic subtest: bcs0
[441.957417] Dynamic subtest bcs0: SUCCESS (2.074s)
[441.969512] Starting dynamic subtest: vcs0
[443.891927] Dynamic subtest vcs0: SUCCESS (1.932s)
[443.902070] Starting dynamic subtest: vcs1
[445.916288] Dynamic subtest vcs1: SUCCESS (2.024s)
[445.928505] Starting dynamic subtest: vecs0
[447.906018] Dynamic subtest vecs0: SUCCESS (1.988s)
[447.918264] Starting dynamic subtest: ccs0
[449.925103] Dynamic subtest ccs0: SUCCESS (2.017s)
[449.927546] Subtest engines-mixed-process: SUCCESS (11.705s)
[450.033794] [064/132] (579s left) kms_chamelium_edid (vga-edid-read)
[450.424477] Subtest vga-edid-read: SKIP (0.000s)
[451.200185] [065/132] (578s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[451.323786] Starting subtest: 2x-long-flip-vs-cursor-atomic
[451.328401] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[451.495507] [066/132] (578s left) gem_exec_create (legacy)
[451.562550] Starting subtest: legacy
[451.564873] Starting dynamic subtest: smem
[453.651084] Dynamic subtest smem: SUCCESS (2.088s)
[453.653526] Subtest legacy: SUCCESS (2.089s)
[453.862506] [067/132] (575s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[453.966553] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[453.970868] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[454.083602] [068/132] (575s left) gem_softpin (zero)
[454.156065] Starting subtest: zero
[454.160512] Subtest zero: SUCCESS (0.004s)
[454.330599] [069/132] (575s left) gem_exec_flush (basic-wb-ro-before-default)
[454.402973] Starting subtest: basic-wb-ro-before-default
[459.855188] Subtest basic-wb-ro-before-default: SUCCESS (5.452s)
[460.069489] [070/132] (569s left) kms_big_fb (linear-64bpp-rotate-90)
[460.180691] Starting subtest: linear-64bpp-rotate-90
[460.200769] Subtest linear-64bpp-rotate-90: SKIP (0.020s)
[460.366275] [071/132] (569s left) gem_userptr_blits (set-cache-level)
[460.439135] Starting subtest: set-cache-level
[460.443768] Subtest set-cache-level: SKIP (0.000s)
[460.601787] [072/132] (569s left) perf_pmu (busy)
[460.641912] Starting subtest: busy
[460.644015] Starting dynamic subtest: rcs0
[461.265922] Dynamic subtest rcs0: SUCCESS (0.625s)
[461.278382] Starting dynamic subtest: bcs0
[461.844468] Dynamic subtest bcs0: SUCCESS (0.575s)
[461.855970] Starting dynamic subtest: vcs0
[463.877611] Dynamic subtest vcs0: SUCCESS (2.029s)
[463.888597] Starting dynamic subtest: vcs1
[465.926233] Dynamic subtest vcs1: SUCCESS (2.045s)
[465.938922] Starting dynamic subtest: vecs0
[467.909145] Dynamic subtest vecs0: SUCCESS (1.980s)
[467.921790] Starting dynamic subtest: ccs0
[468.475725] Dynamic subtest ccs0: SUCCESS (0.565s)
[468.478388] Subtest busy: SUCCESS (7.835s)
[468.596635] [073/132] (561s left) gem_basic (multigpu-create-close)
[468.655800] Starting subtest: multigpu-create-close
[468.660111] Subtest multigpu-create-close: SKIP (0.000s)
[468.796472] [074/132] (561s left) gen9_exec_parse (bb-secure)
[468.864595] Subtest bb-secure: SKIP (0.000s)
[468.960090] [075/132] (561s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[469.015462] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[469.017751] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[469.156246] [076/132] (561s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[474.195466] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[474.200182] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[475.054629] [077/132] (555s left) kms_lease (lease-invalid-crtc)
[475.150344] Starting subtest: lease-invalid-crtc
[475.152604] Starting dynamic subtest: pipe-A-eDP-1
[475.154875] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[475.165223] Starting dynamic subtest: pipe-B-eDP-1
[475.167527] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[475.177599] Starting dynamic subtest: pipe-C-eDP-1
[475.179507] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[475.189085] Starting dynamic subtest: pipe-D-eDP-1
[475.191036] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[475.192984] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[475.251884] [078/132] (555s left) gem_pwrite_snooped
[475.425181] [079/132] (554s left) gem_exec_reloc (basic-write-wc-noreloc)
[475.490405] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[475.560154] [080/132] (554s left) kms_flip (wf_vblank-ts-check-interruptible)
[475.661598] Starting subtest: wf_vblank-ts-check-interruptible
[475.663932] Starting dynamic subtest: A-eDP1
[484.563023] Dynamic subtest A-eDP1: SUCCESS (8.900s)
[484.577059] Starting dynamic subtest: B-eDP1
[493.499078] Dynamic subtest B-eDP1: SUCCESS (8.934s)
[493.512276] Starting dynamic subtest: C-eDP1
[502.424320] Dynamic subtest C-eDP1: SUCCESS (8.923s)
[502.437510] Starting dynamic subtest: D-eDP1
[511.359582] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[511.364946] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.702s)
[512.620822] [081/132] (517s left) gem_ctx_persistence (legacy-engines-queued)
[512.688693] Starting subtest: legacy-engines-queued
[512.691036] Starting dynamic subtest: render
[512.793137] Dynamic subtest render: SUCCESS (0.103s)
[512.806026] Starting dynamic subtest: bsd1
[513.893559] Dynamic subtest bsd1: SUCCESS (1.096s)
[513.906646] Starting dynamic subtest: bsd2
[515.875853] Dynamic subtest bsd2: SUCCESS (1.979s)
[515.888960] Starting dynamic subtest: blt
[515.964711] Dynamic subtest blt: SUCCESS (0.087s)
[515.976931] Starting dynamic subtest: vebox
[517.860863] Dynamic subtest vebox: SUCCESS (1.891s)
[517.863726] Subtest legacy-engines-queued: SUCCESS (5.172s)
[517.967540] [082/132] (512s left) gem_exercise_blt (fast-copy)
[518.029550] Starting subtest: fast-copy
[518.032012] Starting dynamic subtest: linear-smem-smem
[518.058037] Dynamic subtest linear-smem-smem: SUCCESS (0.028s)
[518.068882] Starting dynamic subtest: xmajor-smem-smem
[518.088623] Dynamic subtest xmajor-smem-smem: SUCCESS (0.030s)
[518.098158] Starting dynamic subtest: tile4-smem-smem
[518.112026] Dynamic subtest tile4-smem-smem: SUCCESS (0.022s)
[518.121764] Starting dynamic subtest: tile64-smem-smem
[518.134528] Dynamic subtest tile64-smem-smem: SUCCESS (0.021s)
[518.136983] Subtest fast-copy: SUCCESS (0.104s)
[518.327622] [083/132] (512s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[518.435158] Starting subtest: bo-too-small-due-to-tiling
[518.439555] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[518.576307] [084/132] (512s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[518.645022] Starting subtest: multi-wait-all-for-submit-signaled
[518.647308] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[518.787623] [085/132] (511s left) kms_cursor_crc (cursor-random-512x512)
[519.086788] Starting subtest: cursor-random-512x512
[519.090553] Subtest cursor-random-512x512: SKIP (0.001s)
[519.296422] [086/132] (511s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[519.357669] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[519.414864] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.005s)
[519.507128] [087/132] (511s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[519.592620] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[519.594582] Starting dynamic subtest: pipe-A-default-mode
[519.882165] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[519.886833] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.289s)
[520.745003] [088/132] (510s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[520.845339] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[520.847617] Starting dynamic subtest: pipe-A-eDP-1
[520.870503] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[520.882673] Starting dynamic subtest: pipe-B-eDP-1
[520.904358] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[520.913802] Starting dynamic subtest: pipe-C-eDP-1
[520.935173] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[520.944548] Starting dynamic subtest: pipe-D-eDP-1
[520.966010] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[520.970318] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[523.116605] [089/132] (507s left) kms_plane_multiple (2x-tiling-none)
[523.220942] Starting subtest: 2x-tiling-none
[523.225226] Subtest 2x-tiling-none: SKIP (0.000s)
[523.325345] [090/132] (507s left) kms_psr (fbc-psr2-sprite-blt)
[523.424080] Starting subtest: fbc-psr2-sprite-blt
[523.426260] Starting dynamic subtest: eDP-1
[523.430250] Dynamic subtest eDP-1: SKIP (0.000s)
[523.434257] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[523.526042] [091/132] (507s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[523.625842] Starting subtest: y-tiled-16bpp-rotate-270
[523.630300] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[523.748064] [092/132] (507s left) i915_query (engine-info-invalid)
[523.797283] Starting subtest: engine-info-invalid
[523.799568] Subtest engine-info-invalid: SUCCESS (0.000s)
[523.922782] [093/132] (507s left) gem_ctx_param (invalid-size-set)
[523.987050] Starting subtest: invalid-size-set
[523.989275] Subtest invalid-size-set: SUCCESS (0.001s)
[524.126052] [094/132] (506s left) kms_fbcon_fbt (psr-suspend)
[524.186385] Starting subtest: psr-suspend
[574.998466] Subtest psr-suspend: SUCCESS (20.517s)
[582.108927] [095/132] (455s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[582.168998] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[582.268948] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[582.379494] [096/132] (455s left) kms_pm_rpm (universal-planes)
[584.320218] Starting subtest: universal-planes
[584.322512] Starting dynamic subtest: plane-33
[593.042184] Dynamic subtest plane-33: SUCCESS (8.710s)
[593.054348] Starting dynamic subtest: plane-63
[601.345191] Dynamic subtest plane-63: SUCCESS (8.293s)
[601.362127] Starting dynamic subtest: plane-93
[609.649202] Dynamic subtest plane-93: SUCCESS (8.296s)
[609.662174] Starting dynamic subtest: plane-123
[618.226858] Dynamic subtest plane-123: SUCCESS (8.574s)
[618.239981] Starting dynamic subtest: plane-133
[626.614764] Dynamic subtest plane-133: SUCCESS (8.386s)
[626.626155] Starting dynamic subtest: plane-143
[635.256568] Dynamic subtest plane-143: SUCCESS (8.639s)
[635.259195] Subtest universal-planes: SUCCESS (50.915s)
[635.636666] [097/132] (402s left) gem_exec_reloc (basic-write-read)
[635.712914] Subtest basic-write-read: SKIP (0.000s)
[635.794563] [098/132] (402s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[635.906913] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[635.911301] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[636.038934] [099/132] (402s left) kms_cursor_crc (cursor-onscreen-32x32)
[636.188883] Starting subtest: cursor-onscreen-32x32
[636.237196] Subtest cursor-onscreen-32x32: SKIP (0.044s)
[636.589425] [100/132] (401s left) syncobj_wait (invalid-wait-bad-flags)
[636.649306] Starting subtest: invalid-wait-bad-flags
[636.651213] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[636.757943] [101/132] (401s left) gem_exec_whisper (basic-contexts-forked-all)
[636.839563] Starting subtest: basic-contexts-forked-all
[657.137326] Subtest basic-contexts-forked-all: SUCCESS (20.291s)
[657.343672] [102/132] (381s left) gem_fenced_exec_thrash (2-spare-fences)
[657.401214] Subtest 2-spare-fences: SKIP (0.000s)
[657.487517] [103/132] (380s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[657.592682] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[657.596790] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[657.718092] [104/132] (380s left) api_intel_bb (add-remove-objects)
[657.770946] Starting subtest: add-remove-objects
[657.776211] Subtest add-remove-objects: SUCCESS (0.007s)
[657.923594] [105/132] (380s left) kms_vblank (invalid)
[658.014207] Starting subtest: invalid
[658.016070] Starting dynamic subtest: pipe-A-eDP-1
[658.420544] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.406s)
[658.423200] Subtest invalid: SUCCESS (0.407s)
[659.271328] [106/132] (379s left) gem_mmap_wc (bad-object)
[659.331263] Subtest bad-object: SKIP (0.000s)
[659.398648] [107/132] (379s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[659.503421] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[659.507581] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[659.614539] [108/132] (379s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[659.667404] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[659.748828] [109/132] (378s left) gem_tiled_fence_blits (normal)
[659.807598] Subtest normal: SKIP (0.000s)
[659.893465] [110/132] (378s left) gem_exec_alignment (pi)
[659.943595] Starting subtest: pi
[711.997595] Subtest pi: SUCCESS (52.047s)
[713.221770] [111/132] (325s left) gem_mmap (bad-object)
[713.333933] Subtest bad-object: SKIP (0.000s)
[713.399552] [112/132] (325s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[713.506337] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[713.508633] Starting dynamic subtest: pipe-A
[713.792574] Dynamic subtest pipe-A: SUCCESS (0.288s)
[713.817971] Starting dynamic subtest: pipe-B
[715.175565] Dynamic subtest pipe-B: SUCCESS (1.381s)
[715.187593] Starting dynamic subtest: pipe-C
[716.477609] Dynamic subtest pipe-C: SUCCESS (1.300s)
[716.488695] Starting dynamic subtest: pipe-D
[717.815838] Dynamic subtest pipe-D: SUCCESS (1.337s)
[717.818186] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.313s)
[719.012677] [113/132] (319s left) i915_hangman (engine-error-state-capture)
[719.082602] Starting subtest: engine-error-state-capture
[719.084948] Starting dynamic subtest: rcs0
[722.168503] Dynamic subtest rcs0: SUCCESS (3.086s)
[722.182433] Starting dynamic subtest: bcs0
[728.183467] Dynamic subtest bcs0: SUCCESS (6.012s)
[728.197293] Starting dynamic subtest: vcs0
[734.136450] Dynamic subtest vcs0: SUCCESS (5.949s)
[734.150324] Starting dynamic subtest: vcs1
[740.152470] Dynamic subtest vcs1: SUCCESS (6.012s)
[740.165941] Starting dynamic subtest: vecs0
[746.168553] Dynamic subtest vecs0: SUCCESS (6.012s)
[746.182826] Starting dynamic subtest: ccs0
[752.184173] Dynamic subtest ccs0: SUCCESS (6.011s)
[752.187036] Subtest engine-error-state-capture: SUCCESS (33.098s)
[752.297485] [114/132] (286s left) perf (low-oa-exponent-permissions)
[752.457688] Starting subtest: low-oa-exponent-permissions
[752.486930] Subtest low-oa-exponent-permissions: SUCCESS (0.032s)
[752.532740] [115/132] (286s left) gem_softpin (noreloc)
[752.588870] Starting subtest: noreloc
[758.008337] Subtest noreloc: SUCCESS (5.417s)
[758.180595] [116/132] (280s left) perf (enable-disable)
[758.285450] Starting subtest: enable-disable
[758.287886] Starting dynamic subtest: 0-rcs0
[760.589896] Dynamic subtest 0-rcs0: SUCCESS (2.302s)
[760.600863] Starting dynamic subtest: 1-vecs0
[765.076142] Dynamic subtest 1-vecs0: SUCCESS (4.484s)
[765.078791] Subtest enable-disable: SUCCESS (6.788s)
[765.127746] [117/132] (273s left) kms_pipe_crc_basic (nonblocking-crc)
[765.230040] Starting subtest: nonblocking-crc
[765.232252] Starting dynamic subtest: pipe-A-eDP-1
[765.990897] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.761s)
[766.001878] Starting dynamic subtest: pipe-B-eDP-1
[767.395097] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.401s)
[767.405393] Starting dynamic subtest: pipe-C-eDP-1
[768.841406] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.444s)
[768.853300] Starting dynamic subtest: pipe-D-eDP-1
[770.257204] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.415s)
[770.259489] Subtest nonblocking-crc: SUCCESS (5.027s)
[771.157548] [118/132] (267s left) sysfs_preempt_timeout (timeout)
[771.225732] Starting subtest: timeout
[771.227836] Starting dynamic subtest: vecs0
[781.217446] Dynamic subtest vecs0: SUCCESS (9.986s)
[781.229304] Starting dynamic subtest: ccs0
[782.557825] Dynamic subtest ccs0: SUCCESS (1.339s)
[782.570822] Starting dynamic subtest: vcs0
[792.158170] Dynamic subtest vcs0: SUCCESS (9.594s)
[792.171295] Starting dynamic subtest: bcs0
[793.499786] Dynamic subtest bcs0: SUCCESS (1.340s)
[793.513970] Starting dynamic subtest: rcs0
[794.783661] Dynamic subtest rcs0: SUCCESS (1.282s)
[794.796933] Starting dynamic subtest: vcs1
[804.187380] Dynamic subtest vcs1: SUCCESS (9.401s)
[804.190036] Subtest timeout: SUCCESS (32.954s)
[804.292044] [119/132] (234s left) gem_mmap_gtt (hang-busy)
[804.344666] Subtest hang-busy: SKIP (0.000s)
[804.421303] [120/132] (234s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[804.526796] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[804.530910] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[804.669454] [121/132] (234s left) i915_drm_fdinfo (all-busy-idle-check-all)
[804.722243] Starting subtest: all-busy-idle-check-all
[804.726984] Subtest all-busy-idle-check-all: SKIP (0.004s)
[804.826068] [122/132] (234s left) perf_pmu (init-sema)
[804.866669] Starting subtest: init-sema
[804.868988] Starting dynamic subtest: rcs0
[804.871411] Dynamic subtest rcs0: SUCCESS (0.002s)
[804.887875] Starting dynamic subtest: bcs0
[804.889969] Dynamic subtest bcs0: SUCCESS (0.000s)
[804.902729] Starting dynamic subtest: vcs0
[804.904641] Dynamic subtest vcs0: SUCCESS (0.000s)
[804.914945] Starting dynamic subtest: vcs1
[804.916927] Dynamic subtest vcs1: SUCCESS (0.000s)
[804.928728] Starting dynamic subtest: vecs0
[804.930640] Dynamic subtest vecs0: SUCCESS (0.000s)
[804.940193] Starting dynamic subtest: ccs0
[804.942043] Dynamic subtest ccs0: SUCCESS (0.000s)
[804.943908] Subtest init-sema: SUCCESS (0.007s)
[805.031156] [123/132] (234s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[805.127694] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[813.333544] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (8.210s)
[813.486825] [124/132] (225s left) gem_exec_schedule (fairslice)
[813.565991] Starting subtest: fairslice
[813.567931] Starting dynamic subtest: rcs0
[815.590187] Dynamic subtest rcs0: SUCCESS (2.022s)
[815.601671] Starting dynamic subtest: bcs0
[817.606740] Dynamic subtest bcs0: SUCCESS (2.017s)
[817.617720] Starting dynamic subtest: vcs0
[819.626083] Dynamic subtest vcs0: SUCCESS (2.018s)
[819.637428] Starting dynamic subtest: vcs1
[821.643534] Dynamic subtest vcs1: SUCCESS (2.017s)
[821.655219] Starting dynamic subtest: vecs0
[823.662576] Dynamic subtest vecs0: SUCCESS (2.018s)
[823.674059] Starting dynamic subtest: ccs0
[825.680903] Dynamic subtest ccs0: SUCCESS (2.020s)
[825.683160] Subtest fairslice: SUCCESS (12.118s)
[826.011296] [125/132] (213s left) gem_exec_reloc (basic-wc-cpu)
[826.079358] Subtest basic-wc-cpu: SKIP (0.000s)
[826.159438] [126/132] (213s left) syncobj_timeline (wait-all-for-submit-complex)
[826.230259] Starting subtest: wait-all-for-submit-complex
[826.445910] Subtest wait-all-for-submit-complex: SUCCESS (0.214s)
[826.551104] [127/132] (212s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[826.674405] Starting subtest: cursorB-vs-flipB-legacy
[826.678714] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[826.841058] [128/132] (212s left) gem_exec_flush (basic-wb-set-default)
[826.909016] Starting subtest: basic-wb-set-default
[832.360576] Subtest basic-wb-set-default: SUCCESS (5.452s)
[832.579338] [129/132] (206s left) gem_pxp (reject-modify-context-protection-off-2)
[832.719588] Starting subtest: reject-modify-context-protection-off-2
[832.722085] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[832.868731] [130/132] (206s left) api_intel_bb (misplaced-blitter)
[832.935672] Starting subtest: misplaced-blitter
[832.942690] Subtest misplaced-blitter: SUCCESS (0.009s)
[833.115002] [131/132] (206s left) kms_psr (fbc-pr-dpms)
[833.220061] Starting subtest: fbc-pr-dpms
[833.224564] Subtest fbc-pr-dpms: SKIP (0.000s)
[833.321278] [132/132] (206s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[833.427015] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[833.431415] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[833.557995] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: prime_vgem
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_rmfb
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_chamelium_hpd
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_draw_crc
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: perf
results: parsing output: 13/ for test: gem_create
results: parsing output: 14/ for test: kms_cdclk
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_softpin
results: parsing output: 17/ for test: kms_joiner
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: drm_mm
results: parsing output: 21/ for test: kms_psr2_sf
results: parsing output: 22/ for test: kms_color_pipeline
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: gem_userptr_blits
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_cursor_edge_walk
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: i915_pm_rc6_residency
results: parsing output: 33/ for test: kms_async_flips
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_pm_dc
results: parsing output: 36/ for test: kms_getfb
results: parsing output: 37/ for test: perf
results: parsing output: 38/ for test: gem_render_copy
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: gem_exec_fence
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: perf
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: gem_lmem_swapping
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_exec_schedule
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: sw_sync
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: kms_atomic_transition
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: kms_selftest
results: parsing output: 55/ for test: gem_userptr_blits
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: gem_exec_parallel
results: parsing output: 59/ for test: kms_content_protection
results: parsing output: 60/ for test: gem_mmap_wc
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: gem_ctx_persistence
results: parsing output: 63/ for test: kms_chamelium_edid
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: gem_exec_create
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_softpin
results: parsing output: 68/ for test: gem_exec_flush
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: gem_userptr_blits
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: gem_basic
results: parsing output: 73/ for test: gen9_exec_parse
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: kms_psr2_sf
results: parsing output: 76/ for test: kms_lease
results: parsing output: 77/ for test: gem_pwrite_snooped
results: parsing output: 78/ for test: gem_exec_reloc
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: gem_ctx_persistence
results: parsing output: 81/ for test: gem_exercise_blt
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: sriov_basic
results: parsing output: 86/ for test: kms_flip_scaled_crc
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: kms_plane_multiple
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: i915_query
results: parsing output: 92/ for test: gem_ctx_param
results: parsing output: 93/ for test: kms_fbcon_fbt
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: kms_pm_rpm
results: parsing output: 96/ for test: gem_exec_reloc
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: gem_exec_whisper
results: parsing output: 101/ for test: gem_fenced_exec_thrash
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: api_intel_bb
results: parsing output: 104/ for test: kms_vblank
results: parsing output: 105/ for test: gem_mmap_wc
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: gem_tiled_fence_blits
results: parsing output: 109/ for test: gem_exec_alignment
results: parsing output: 110/ for test: gem_mmap
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: i915_hangman
results: parsing output: 113/ for test: perf
results: parsing output: 114/ for test: gem_softpin
results: parsing output: 115/ for test: perf
results: parsing output: 116/ for test: kms_pipe_crc_basic
results: parsing output: 117/ for test: sysfs_preempt_timeout
results: parsing output: 118/ for test: gem_mmap_gtt
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: i915_drm_fdinfo
results: parsing output: 121/ for test: perf_pmu
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_exec_schedule
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: gem_pxp
results: parsing output: 129/ for test: api_intel_bb
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: kms_big_fb
Done.