[57.181380] Initializing watchdogs
[57.181431]   /dev/watchdog0
[57.195710] [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]
[57.200572] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.202827] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.206252] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[57.485644] Starting subtest: noheartbeat
[57.490293] Subtest noheartbeat: SKIP (0.000s)
[57.611521] [002/132] (960s left) prime_vgem (basic-gtt)
[57.711070] Starting subtest: basic-gtt
[57.715491] Subtest basic-gtt: SKIP (0.000s)
[57.834320] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[57.837737] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[57.939092] Starting subtest: fbc-1p-pri-indfb-multidraw
[71.279579] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.391s)
[72.737508] [004/132] (945s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[72.839262] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[82.151348] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.316s)
[82.300602] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[82.401409] Starting subtest: rmfb-ioctl
[82.403753] Starting dynamic subtest: pipe-A-eDP-1
[82.508305] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[82.515802] Starting dynamic subtest: pipe-B-eDP-1
[83.699039] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.188s)
[83.711404] Starting dynamic subtest: pipe-C-eDP-1
[84.920111] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.217s)
[84.934481] Starting dynamic subtest: pipe-D-eDP-1
[86.073209] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.150s)
[86.075700] Subtest rmfb-ioctl: SUCCESS (3.671s)
[87.299016] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[87.403830] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[94.001804] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.599s)
[94.160301] [007/132] (923s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[94.550603] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[95.323559] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[95.421088] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[95.423529] Starting dynamic subtest: pipe-A-default-mode
[96.609601] Dynamic subtest pipe-A-default-mode: SKIP (1.187s)
[96.613759] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.188s)
[96.794142] [009/132] (921s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[96.924527] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[96.928592] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[97.061694] [010/132] (921s left) gem_exec_reloc (basic-write-read-noreloc)
[97.122894] Subtest basic-write-read-noreloc: SKIP (0.000s)
[97.203488] [011/132] (920s left) kms_draw_crc (draw-method-blt)
[97.292286] Starting subtest: draw-method-blt
[97.294606] Starting dynamic subtest: xrgb8888-untiled
[97.523499] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[97.531728] Starting dynamic subtest: rgb565-untiled
[97.756968] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[97.764251] Starting dynamic subtest: xrgb2101010-untiled
[97.978803] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[97.985993] Starting dynamic subtest: xbgr16161616f-untiled
[98.201152] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.222s)
[98.208619] Starting dynamic subtest: xrgb8888-xtiled
[98.312373] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.111s)
[98.320690] Starting dynamic subtest: rgb565-xtiled
[98.423484] Dynamic subtest rgb565-xtiled: SUCCESS (0.111s)
[98.431795] Starting dynamic subtest: xrgb2101010-xtiled
[98.534664] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[98.543169] Starting dynamic subtest: xbgr16161616f-xtiled
[98.645861] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[98.654059] Starting dynamic subtest: xrgb8888-4tiled
[98.779420] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[98.790347] Starting dynamic subtest: rgb565-4tiled
[98.901485] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[98.909378] Starting dynamic subtest: xrgb2101010-4tiled
[99.012291] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[99.020041] Starting dynamic subtest: xbgr16161616f-4tiled
[99.123450] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[99.125467] Subtest draw-method-blt: SUCCESS (1.834s)
[99.285852] [012/132] (918s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[99.380304] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[99.382276] Starting dynamic subtest: pipe-A-eDP-1
[99.402040] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[99.411108] Starting dynamic subtest: pipe-B-eDP-1
[99.430376] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[99.439902] Starting dynamic subtest: pipe-C-eDP-1
[99.457671] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[99.465402] Starting dynamic subtest: pipe-D-eDP-1
[99.483921] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[99.487534] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[101.662730] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[101.805945] Starting subtest: gen12-group-exclusive-stream-sample-oa
[101.825060] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.019s)
[101.871797] [014/132] (916s left) gem_create (hog-create)
[101.929133] Starting subtest: hog-create
[101.931499] Starting dynamic subtest: smem0
[135.919756] Dynamic subtest smem0: SUCCESS (33.987s)
[135.922693] Subtest hog-create: SUCCESS (33.988s)
[136.132232] [015/132] (882s left) kms_cdclk (plane-scaling)
[136.227103] Starting subtest: plane-scaling
[136.229518] Starting dynamic subtest: pipe-A-eDP-1
[136.527452] Dynamic subtest pipe-A-eDP-1: SKIP (0.295s)
[136.536747] Starting dynamic subtest: pipe-B-eDP-1
[136.541562] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[136.548958] Starting dynamic subtest: pipe-C-eDP-1
[136.553572] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[136.560929] Starting dynamic subtest: pipe-D-eDP-1
[136.565331] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[136.569816] Subtest plane-scaling: SKIP (0.302s)
[137.387680] [016/132] (880s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[137.509642] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[137.513824] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[137.636453] [017/132] (880s left) gem_softpin (softpin)
[137.714286] Starting subtest: softpin
[169.963436] Subtest softpin: SUCCESS (32.240s)
[170.116260] [018/132] (848s left) kms_joiner (basic-force-ultra-joiner)
[170.225222] Starting subtest: basic-force-ultra-joiner
[170.229851] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[170.314829] [019/132] (848s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[170.421149] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[170.432578] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[170.543750] [020/132] (847s left) syncobj_timeline (reset-multiple-signaled)
[170.611273] Starting subtest: reset-multiple-signaled
[170.613588] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[170.751768] [021/132] (847s left) drm_mm (drm_mm)
[170.793020] Starting subtest: drm_mm
[170.839233] Starting dynamic subtest: drm_test_mm_init
[170.920509] Dynamic subtest drm_test_mm_init: SUCCESS (0.085s)
[170.932782] Starting dynamic subtest: drm_test_mm_debug
[171.058776] Dynamic subtest drm_test_mm_debug: SUCCESS (0.135s)
[171.071198] Starting dynamic subtest: drm_test_mm_align32
[171.190992] Dynamic subtest drm_test_mm_align32: SUCCESS (0.132s)
[171.198424] Starting dynamic subtest: drm_test_mm_align64
[171.334391] Dynamic subtest drm_test_mm_align64: SUCCESS (0.143s)
[171.341826] Starting dynamic subtest: drm_test_mm_lowest
[171.494376] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.160s)
[171.501495] Starting dynamic subtest: drm_test_mm_highest
[171.623766] Dynamic subtest drm_test_mm_highest: SUCCESS (0.127s)
[171.625756] Subtest drm_mm: SUCCESS (0.831s)
[171.828359] [022/132] (846s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[176.830687] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[176.835493] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[177.704543] [023/132] (840s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[177.813221] Starting subtest: plane-ctm3x4-lut1d
[177.828153] Starting dynamic subtest: pipe-A-eDP-1
[178.559207] Dynamic subtest pipe-A-eDP-1: FAIL (0.334s)
[179.269033] Starting dynamic subtest: pipe-B-eDP-1
[180.101972] Dynamic subtest pipe-B-eDP-1: FAIL (0.302s)
[180.612171] Starting dynamic subtest: pipe-C-eDP-1
[181.494612] Dynamic subtest pipe-C-eDP-1: FAIL (0.290s)
[181.947328] Starting dynamic subtest: pipe-D-eDP-1
[182.804084] Dynamic subtest pipe-D-eDP-1: FAIL (0.269s)
[182.806122] Subtest plane-ctm3x4-lut1d: FAIL (4.687s)
[183.351986] [024/132] (835s left) kms_psr (fbc-psr-primary-mmap-gtt)
[183.476178] Starting subtest: fbc-psr-primary-mmap-gtt
[183.478395] Starting dynamic subtest: eDP-1
[183.482404] Dynamic subtest eDP-1: SKIP (0.000s)
[183.486423] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[183.571270] [025/132] (835s left) gem_exec_schedule (u-semaphore-user)
[183.648124] Starting subtest: u-semaphore-user
[183.699056] Subtest u-semaphore-user: SUCCESS (0.058s)
[184.979996] [026/132] (833s left) gem_userptr_blits (unsync-unmap-cycles)
[185.050614] Subtest unsync-unmap-cycles: SKIP (0.000s)
[185.217811] [027/132] (833s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[185.326431] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[185.328688] Starting dynamic subtest: pipe-A-default-mode
[185.618206] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[185.622833] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.290s)
[186.518890] [028/132] (832s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[186.627590] Starting subtest: fbc-rgb565-draw-pwrite
[191.244618] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.619s)
[192.382509] [029/132] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[192.487803] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[200.074085] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.589s)
[200.263424] [030/132] (818s left) kms_cursor_edge_walk (256x256-right-edge)
[200.356070] Starting subtest: 256x256-right-edge
[200.358259] Starting dynamic subtest: pipe-A-eDP-1
[203.868504] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.511s)
[203.877095] Starting dynamic subtest: pipe-D-eDP-1
[206.993730] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[206.996051] Subtest 256x256-right-edge: SUCCESS (6.638s)
[207.812875] [031/132] (811s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[207.916483] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[212.301428] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.388s)
[213.505373] [032/132] (805s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[213.596213] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[213.600117] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[213.724444] [033/132] (805s left) i915_pm_rc6_residency (media-rc6-accuracy)
[213.782619] Starting subtest: media-rc6-accuracy
[213.787136] Subtest media-rc6-accuracy: SKIP (0.000s)
[213.887322] [034/132] (805s left) kms_async_flips (async-flip-hang)
[213.992168] Starting subtest: async-flip-hang
[214.365237] Starting dynamic subtest: pipe-A-eDP-1
[229.428693] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.061s)
[229.813189] Starting dynamic subtest: pipe-B-eDP-1
[245.359920] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.552s)
[245.749377] Starting dynamic subtest: pipe-C-eDP-1
[261.355674] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.609s)
[261.728340] Starting dynamic subtest: pipe-D-eDP-1
[277.357133] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.633s)
[277.359624] Subtest async-flip-hang: SUCCESS (63.364s)
[278.567922] [035/132] (740s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[278.681163] Starting subtest: fbc-rgb101010-draw-render
[283.302513] Subtest fbc-rgb101010-draw-render: SUCCESS (4.625s)
[284.476447] [036/132] (734s left) kms_pm_dc (dc5-psr)
[285.585357] Starting subtest: dc5-psr
[288.473408] Subtest dc5-psr: SUCCESS (2.888s)
[289.292736] [037/132] (729s left) kms_getfb (getfb-handle-valid)
[289.388324] Starting subtest: getfb-handle-valid
[289.390753] Subtest getfb-handle-valid: SUCCESS (0.000s)
[289.518075] [038/132] (729s left) perf (oa-formats)
[289.629419] Starting subtest: oa-formats
[289.631887] Starting dynamic subtest: 0-rcs0
[289.641886] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[289.651423] Starting dynamic subtest: 1-vcs1
[289.664195] Dynamic subtest 1-vcs1: SUCCESS (0.021s)
[289.666487] Subtest oa-formats: SUCCESS (0.035s)
[289.714072] [039/132] (729s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[289.774275] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[289.778945] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[289.977791] [040/132] (729s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[290.087903] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[290.092298] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[290.233445] [041/132] (729s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[290.334065] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[290.336176] Starting dynamic subtest: pipe-A-eDP-1
[307.304932] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.102s)
[307.316040] Starting dynamic subtest: pipe-B-eDP-1
[324.272102] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.502s)
[324.280859] Starting dynamic subtest: pipe-C-eDP-1
[341.232541] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.434s)
[341.241611] Starting dynamic subtest: pipe-D-eDP-1
[358.227775] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.366s)
[358.230202] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (12.408s)
[359.446985] [042/132] (659s left) gem_exec_fence (keep-in-fence)
[359.622287] Starting subtest: keep-in-fence
[359.625159] Starting dynamic subtest: rcs0
[359.701841] Dynamic subtest rcs0: SUCCESS (0.083s)
[359.713598] Starting dynamic subtest: bcs0
[359.758717] Dynamic subtest bcs0: SUCCESS (0.057s)
[359.769577] Starting dynamic subtest: vcs0
[361.601783] Dynamic subtest vcs0: SUCCESS (1.895s)
[361.614056] Starting dynamic subtest: vcs1
[363.649732] Dynamic subtest vcs1: SUCCESS (2.092s)
[363.661581] Starting dynamic subtest: vecs0
[365.634905] Dynamic subtest vecs0: SUCCESS (2.018s)
[365.646599] Starting dynamic subtest: ccs0
[365.862012] Dynamic subtest ccs0: SUCCESS (0.229s)
[365.865206] Subtest keep-in-fence: SUCCESS (6.386s)
[367.755522] [043/132] (651s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[367.874242] Starting subtest: psr-rgb565-draw-pwrite
[374.838173] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.019s)
[375.006897] [044/132] (644s left) perf (per-context-mode-unprivileged)
[375.112909] Starting subtest: per-context-mode-unprivileged
[375.118452] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[375.167305] [045/132] (644s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[375.282171] Starting subtest: x-tiled-8bpp-rotate-0
[376.536100] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.259s)
[376.714466] [046/132] (642s left) gem_lmem_swapping (parallel-random-verify-ccs)
[376.765306] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[376.822449] [047/132] (642s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[376.919044] Starting subtest: 4-tiled-64bpp-rotate-180
[378.538011] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.624s)
[378.764341] [048/132] (640s left) gem_exec_schedule (preempt)
[378.844288] Starting subtest: preempt
[378.846803] Starting dynamic subtest: rcs0
[378.863476] Dynamic subtest rcs0: SUCCESS (0.028s)
[378.874759] Starting dynamic subtest: bcs0
[378.891201] Dynamic subtest bcs0: SUCCESS (0.027s)
[378.900592] Starting dynamic subtest: vcs0
[378.916735] Dynamic subtest vcs0: SUCCESS (0.025s)
[378.926150] Starting dynamic subtest: vcs1
[378.953373] Dynamic subtest vcs1: SUCCESS (0.036s)
[378.963601] Starting dynamic subtest: vecs0
[378.975081] Dynamic subtest vecs0: SUCCESS (0.021s)
[378.986460] Starting dynamic subtest: ccs0
[379.013833] Dynamic subtest ccs0: SUCCESS (0.038s)
[379.016502] Subtest preempt: SUCCESS (0.180s)
[380.730511] [049/132] (638s left) kms_flip (2x-wf_vblank-ts-check)
[380.835106] Starting subtest: 2x-wf_vblank-ts-check
[380.843046] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s)
[380.973148] [050/132] (638s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[381.073245] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[381.075824] Starting dynamic subtest: pipe-A-eDP-1
[381.098078] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[381.109120] Starting dynamic subtest: pipe-B-eDP-1
[381.131237] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[381.139451] Starting dynamic subtest: pipe-C-eDP-1
[381.162265] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[381.170874] Starting dynamic subtest: pipe-D-eDP-1
[381.193782] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[381.196218] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[383.306804] [051/132] (636s left) sw_sync (alloc_timeline)
[383.349284] Starting subtest: alloc_timeline
[383.351817] Subtest alloc_timeline: SUCCESS (0.000s)
[383.419376] [052/132] (636s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[383.484346] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[383.487353] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[383.604150] [053/132] (636s left) kms_atomic_transition (modeset-transition-nonblocking)
[383.710414] Starting subtest: modeset-transition-nonblocking
[383.713150] Starting dynamic subtest: 1x-outputs
[394.517108] Dynamic subtest 1x-outputs: SUCCESS (10.811s)
[394.519762] Subtest modeset-transition-nonblocking: SUCCESS (10.812s)
[395.400942] [054/132] (624s left) kms_pm_rpm (cursor)
[396.926444] Starting subtest: cursor
[396.931350] Subtest cursor: SKIP (0.000s)
[397.631182] [055/132] (622s left) kms_selftest (drm_damage_helper)
[397.666085] Starting subtest: drm_damage_helper
[397.691082] Starting dynamic subtest: drm_test_damage_iter_no_damage
[397.824441] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.135s)
[397.835084] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[397.953232] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.127s)
[397.963490] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[398.083048] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.129s)
[398.093700] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[398.173493] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.088s)
[398.182004] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[398.311707] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.138s)
[398.322042] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[398.471037] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.157s)
[398.480772] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[398.589723] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.118s)
[398.601464] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[398.718677] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.126s)
[398.728935] Starting dynamic subtest: drm_test_damage_iter_single_damage
[398.846209] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.126s)
[398.856151] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[398.939246] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.094s)
[398.949573] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[399.069908] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.128s)
[399.080654] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[399.197311] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.126s)
[399.208849] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[399.327292] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.127s)
[399.338872] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[399.453238] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.126s)
[399.464288] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[399.591208] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.135s)
[399.600772] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[399.731293] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.139s)
[399.740477] Starting dynamic subtest: drm_test_damage_iter_damage
[399.863895] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.131s)
[399.874104] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[399.997720] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.132s)
[400.008254] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[400.128433] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.130s)
[400.138433] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[400.242204] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.113s)
[400.252031] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[400.357074] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.114s)
[400.359474] Subtest drm_damage_helper: SUCCESS (2.691s)
[400.561980] [056/132] (619s left) gem_userptr_blits (unsync-overlap)
[400.637244] Subtest unsync-overlap: SKIP (0.000s)
[400.803966] [057/132] (619s left) kms_psr (pr-sprite-mmap-gtt)
[400.905702] Starting subtest: pr-sprite-mmap-gtt
[400.910051] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[401.000934] [058/132] (619s left) kms_psr (psr-sprite-mmap-gtt)
[401.099340] Starting subtest: psr-sprite-mmap-gtt
[401.102166] Starting dynamic subtest: eDP-1
[402.544680] Dynamic subtest eDP-1: SKIP (1.442s)
[402.550301] Subtest psr-sprite-mmap-gtt: SKIP (1.444s)
[402.741972] [059/132] (617s left) gem_exec_parallel (basic)
[402.809228] Starting subtest: basic
[402.812031] Starting dynamic subtest: rcs0
[404.521919] Dynamic subtest rcs0: SUCCESS (1.715s)
[404.531472] Starting dynamic subtest: bcs0
[406.182470] Dynamic subtest bcs0: SUCCESS (1.658s)
[406.192628] Starting dynamic subtest: vcs0
[409.602763] Dynamic subtest vcs0: SUCCESS (3.418s)
[409.618617] Starting dynamic subtest: vcs1
[411.587186] Dynamic subtest vcs1: SUCCESS (1.981s)
[411.597337] Starting dynamic subtest: vecs0
[413.633606] Dynamic subtest vecs0: SUCCESS (2.044s)
[413.644593] Starting dynamic subtest: ccs0
[415.421709] Dynamic subtest ccs0: SUCCESS (1.787s)
[415.424767] Subtest basic: SUCCESS (12.615s)
[415.632856] [060/132] (604s left) kms_content_protection (content-type-change)
[415.777567] Starting subtest: content-type-change
[415.783283] Subtest content-type-change: SKIP (0.000s)
[415.945087] [061/132] (604s left) gem_mmap_wc (write-read-distinct)
[415.999420] Subtest write-read-distinct: SKIP (0.000s)
[416.054723] [062/132] (604s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[416.149754] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[416.964478] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.817s)
[417.148702] [063/132] (603s left) gem_ctx_persistence (engines-mixed-process)
[417.205354] Starting subtest: engines-mixed-process
[417.207765] Starting dynamic subtest: rcs0
[418.630889] Dynamic subtest rcs0: SUCCESS (1.428s)
[418.640861] Starting dynamic subtest: bcs0
[420.646516] Dynamic subtest bcs0: SUCCESS (2.015s)
[420.659633] Starting dynamic subtest: vcs0
[422.678262] Dynamic subtest vcs0: SUCCESS (2.030s)
[422.698247] Starting dynamic subtest: vcs1
[424.672470] Dynamic subtest vcs1: SUCCESS (1.989s)
[424.682289] Starting dynamic subtest: vecs0
[426.639671] Dynamic subtest vecs0: SUCCESS (1.970s)
[426.649410] Starting dynamic subtest: ccs0
[428.628434] Dynamic subtest ccs0: SUCCESS (1.988s)
[428.631457] Subtest engines-mixed-process: SUCCESS (11.429s)
[428.740783] [064/132] (591s left) kms_chamelium_edid (vga-edid-read)
[429.141451] Subtest vga-edid-read: SKIP (0.000s)
[429.955428] [065/132] (590s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[430.075238] Starting subtest: 2x-long-flip-vs-cursor-atomic
[430.080047] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[430.239495] [066/132] (590s left) gem_exec_create (legacy)
[430.299195] Starting subtest: legacy
[430.302193] Starting dynamic subtest: smem
[432.389990] Dynamic subtest smem: SUCCESS (2.090s)
[432.393043] Subtest legacy: SUCCESS (2.092s)
[432.597379] [067/132] (587s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[432.701281] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[432.706180] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[432.828542] [068/132] (587s left) gem_softpin (zero)
[432.894319] Starting subtest: zero
[432.899960] Subtest zero: SUCCESS (0.005s)
[433.045030] [069/132] (587s left) gem_exec_flush (basic-wb-ro-before-default)
[433.101455] Starting subtest: basic-wb-ro-before-default
[438.556460] Subtest basic-wb-ro-before-default: SUCCESS (5.456s)
[438.766822] [070/132] (581s left) kms_big_fb (linear-64bpp-rotate-90)
[438.886526] Starting subtest: linear-64bpp-rotate-90
[438.907455] Subtest linear-64bpp-rotate-90: SKIP (0.020s)
[439.056822] [071/132] (581s left) gem_userptr_blits (set-cache-level)
[439.126184] Starting subtest: set-cache-level
[439.131216] Subtest set-cache-level: SKIP (0.000s)
[439.288828] [072/132] (581s left) perf_pmu (busy)
[439.326247] Starting subtest: busy
[439.329272] Starting dynamic subtest: rcs0
[439.893511] Dynamic subtest rcs0: SUCCESS (0.570s)
[439.906786] Starting dynamic subtest: bcs0
[440.466055] Dynamic subtest bcs0: SUCCESS (0.570s)
[440.479270] Starting dynamic subtest: vcs0
[441.666586] Dynamic subtest vcs0: SUCCESS (1.197s)
[441.680319] Starting dynamic subtest: vcs1
[443.650956] Dynamic subtest vcs1: SUCCESS (1.981s)
[443.664601] Starting dynamic subtest: vecs0
[445.634603] Dynamic subtest vecs0: SUCCESS (1.981s)
[445.648490] Starting dynamic subtest: ccs0
[446.211376] Dynamic subtest ccs0: SUCCESS (0.575s)
[446.214465] Subtest busy: SUCCESS (6.891s)
[446.337966] [073/132] (574s left) gem_basic (multigpu-create-close)
[446.394087] Starting subtest: multigpu-create-close
[446.399237] Subtest multigpu-create-close: SKIP (0.000s)
[446.519006] [074/132] (574s left) gen9_exec_parse (bb-secure)
[446.591186] Subtest bb-secure: SKIP (0.000s)
[446.654204] [075/132] (574s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[446.716970] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[446.719267] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[446.855895] [076/132] (573s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[451.831570] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[451.837080] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[452.712679] [077/132] (568s left) kms_lease (lease-invalid-crtc)
[452.816313] Starting subtest: lease-invalid-crtc
[452.819262] Starting dynamic subtest: pipe-A-eDP-1
[452.822277] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[452.833623] Starting dynamic subtest: pipe-B-eDP-1
[452.835969] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[452.845764] Starting dynamic subtest: pipe-C-eDP-1
[452.848295] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[452.858606] Starting dynamic subtest: pipe-D-eDP-1
[452.861334] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[452.864331] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[452.914838] [078/132] (568s left) gem_pwrite_snooped
[453.032691] [079/132] (567s left) gem_exec_reloc (basic-write-wc-noreloc)
[453.102490] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[453.189100] [080/132] (567s left) kms_flip (wf_vblank-ts-check-interruptible)
[453.290942] Starting subtest: wf_vblank-ts-check-interruptible
[453.293473] Starting dynamic subtest: A-eDP1
[462.241974] Dynamic subtest A-eDP1: SUCCESS (8.947s)
[462.252485] Starting dynamic subtest: B-eDP1
[471.178166] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[471.188485] Starting dynamic subtest: C-eDP1
[480.103291] Dynamic subtest C-eDP1: SUCCESS (8.905s)
[480.115813] Starting dynamic subtest: D-eDP1
[489.020956] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[489.023394] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.725s)
[490.268252] [081/132] (530s left) gem_ctx_persistence (legacy-engines-queued)
[490.344436] Starting subtest: legacy-engines-queued
[490.347272] Starting dynamic subtest: render
[490.424446] Dynamic subtest render: SUCCESS (0.080s)
[490.440229] Starting dynamic subtest: bsd1
[491.616903] Dynamic subtest bsd1: SUCCESS (1.187s)
[491.630206] Starting dynamic subtest: bsd2
[493.600667] Dynamic subtest bsd2: SUCCESS (1.980s)
[493.614131] Starting dynamic subtest: blt
[493.691156] Dynamic subtest blt: SUCCESS (0.088s)
[493.706719] Starting dynamic subtest: vebox
[495.584843] Dynamic subtest vebox: SUCCESS (1.888s)
[495.588080] Subtest legacy-engines-queued: SUCCESS (5.241s)
[495.689430] [082/132] (525s left) gem_exercise_blt (fast-copy)
[495.758443] Starting subtest: fast-copy
[495.760885] Starting dynamic subtest: linear-smem-smem
[495.789436] Dynamic subtest linear-smem-smem: SUCCESS (0.031s)
[495.799362] Starting dynamic subtest: xmajor-smem-smem
[495.811447] Dynamic subtest xmajor-smem-smem: SUCCESS (0.021s)
[495.822403] Starting dynamic subtest: tile4-smem-smem
[495.831199] Dynamic subtest tile4-smem-smem: SUCCESS (0.020s)
[495.844612] Starting dynamic subtest: tile64-smem-smem
[495.854571] Dynamic subtest tile64-smem-smem: SUCCESS (0.023s)
[495.857359] Subtest fast-copy: SUCCESS (0.098s)
[496.024838] [083/132] (525s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[496.125421] Starting subtest: bo-too-small-due-to-tiling
[496.131101] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[496.262801] [084/132] (524s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[496.337337] Starting subtest: multi-wait-all-for-submit-signaled
[496.339474] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[496.475086] [085/132] (524s left) kms_cursor_crc (cursor-random-512x512)
[496.764024] Starting subtest: cursor-random-512x512
[496.768300] Subtest cursor-random-512x512: SKIP (0.001s)
[496.971465] [086/132] (524s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[497.028338] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[497.101232] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.006s)
[497.152263] [087/132] (524s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[497.241872] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[497.244067] Starting dynamic subtest: pipe-A-default-mode
[497.531260] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[497.537153] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.286s)
[498.386590] [088/132] (522s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[498.483189] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[498.485551] Starting dynamic subtest: pipe-A-eDP-1
[498.510857] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[498.522260] Starting dynamic subtest: pipe-B-eDP-1
[498.546683] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[498.557113] Starting dynamic subtest: pipe-C-eDP-1
[498.583233] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[498.593869] Starting dynamic subtest: pipe-D-eDP-1
[498.620433] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[498.625662] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[500.666565] [089/132] (520s left) kms_plane_multiple (2x-tiling-none)
[500.771278] Starting subtest: 2x-tiling-none
[500.776460] Subtest 2x-tiling-none: SKIP (0.000s)
[500.872101] [090/132] (520s left) kms_psr (fbc-psr2-sprite-blt)
[500.971470] Starting subtest: fbc-psr2-sprite-blt
[500.974274] Starting dynamic subtest: eDP-1
[500.979255] Dynamic subtest eDP-1: SKIP (0.000s)
[500.984232] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[501.076274] [091/132] (520s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[501.181227] Starting subtest: y-tiled-16bpp-rotate-270
[501.186207] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[501.323112] [092/132] (520s left) i915_query (engine-info-invalid)
[501.384183] Starting subtest: engine-info-invalid
[501.387068] Subtest engine-info-invalid: SUCCESS (0.000s)
[501.527001] [093/132] (520s left) gem_ctx_param (invalid-size-set)
[501.594038] Starting subtest: invalid-size-set
[501.596762] Subtest invalid-size-set: SUCCESS (0.002s)
[501.713960] [094/132] (519s left) kms_fbcon_fbt (psr-suspend)
[501.776390] Starting subtest: psr-suspend
[551.103590] Subtest psr-suspend: SUCCESS (20.068s)
[551.173889] [095/132] (470s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[551.239496] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[551.337533] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[551.439877] [096/132] (470s left) kms_pm_rpm (universal-planes)
[553.456390] Starting subtest: universal-planes
[553.459333] Starting dynamic subtest: plane-33
[561.720742] Dynamic subtest plane-33: SUCCESS (8.347s)
[561.734742] Starting dynamic subtest: plane-63
[570.426838] Dynamic subtest plane-63: SUCCESS (8.734s)
[570.440783] Starting dynamic subtest: plane-93
[579.020397] Dynamic subtest plane-93: SUCCESS (8.599s)
[579.033358] Starting dynamic subtest: plane-123
[587.615197] Dynamic subtest plane-123: SUCCESS (8.588s)
[587.629079] Starting dynamic subtest: plane-133
[596.047606] Dynamic subtest plane-133: SUCCESS (8.429s)
[596.060099] Starting dynamic subtest: plane-143
[604.332918] Dynamic subtest plane-143: SUCCESS (8.283s)
[604.335910] Subtest universal-planes: SUCCESS (50.998s)
[604.738882] [097/132] (417s left) gem_exec_reloc (basic-write-read)
[604.814224] Subtest basic-write-read: SKIP (0.000s)
[604.898208] [098/132] (416s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[605.012155] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[605.017110] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[605.142657] [099/132] (416s left) kms_cursor_crc (cursor-onscreen-32x32)
[605.296351] Starting subtest: cursor-onscreen-32x32
[605.346841] Subtest cursor-onscreen-32x32: SKIP (0.044s)
[605.646642] [100/132] (416s left) syncobj_wait (invalid-wait-bad-flags)
[605.713101] Starting subtest: invalid-wait-bad-flags
[605.715379] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[605.847563] [101/132] (416s left) gem_exec_whisper (basic-contexts-forked-all)
[605.914125] Starting subtest: basic-contexts-forked-all
[628.050921] Subtest basic-contexts-forked-all: SUCCESS (22.141s)
[628.258852] [102/132] (393s left) gem_fenced_exec_thrash (2-spare-fences)
[628.322589] Subtest 2-spare-fences: SKIP (0.000s)
[628.403753] [103/132] (393s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[628.509198] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[628.514355] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[628.641968] [104/132] (393s left) api_intel_bb (add-remove-objects)
[628.705495] Starting subtest: add-remove-objects
[628.710399] Subtest add-remove-objects: SUCCESS (0.009s)
[628.841813] [105/132] (393s left) kms_vblank (invalid)
[628.944226] Starting subtest: invalid
[628.946947] Starting dynamic subtest: pipe-A-eDP-1
[629.353246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.410s)
[629.356265] Subtest invalid: SUCCESS (0.411s)
[630.258816] [106/132] (391s left) gem_mmap_wc (bad-object)
[630.310028] Subtest bad-object: SKIP (0.000s)
[630.374215] [107/132] (391s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[630.476393] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[630.481283] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[630.629035] [108/132] (391s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[630.696985] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[630.788899] [109/132] (391s left) gem_tiled_fence_blits (normal)
[630.857068] Subtest normal: SKIP (0.000s)
[630.925715] [110/132] (391s left) gem_exec_alignment (pi)
[630.997120] Starting subtest: pi
[682.217138] Subtest pi: SUCCESS (51.214s)
[683.376905] [111/132] (338s left) gem_mmap (bad-object)
[683.485817] Subtest bad-object: SKIP (0.000s)
[683.559676] [112/132] (338s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[683.665069] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[683.667690] Starting dynamic subtest: pipe-A
[683.940221] Dynamic subtest pipe-A: SUCCESS (0.277s)
[683.953034] Starting dynamic subtest: pipe-B
[685.233696] Dynamic subtest pipe-B: SUCCESS (1.293s)
[685.249294] Starting dynamic subtest: pipe-C
[686.575621] Dynamic subtest pipe-C: SUCCESS (1.340s)
[686.586273] Starting dynamic subtest: pipe-D
[687.912935] Dynamic subtest pipe-D: SUCCESS (1.337s)
[687.915587] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.251s)
[689.086955] [113/132] (333s left) i915_hangman (engine-error-state-capture)
[689.161863] Starting subtest: engine-error-state-capture
[689.164064] Starting dynamic subtest: rcs0
[692.269688] Dynamic subtest rcs0: SUCCESS (3.108s)
[692.283247] Starting dynamic subtest: bcs0
[698.222161] Dynamic subtest bcs0: SUCCESS (5.950s)
[698.236697] Starting dynamic subtest: vcs0
[704.173279] Dynamic subtest vcs0: SUCCESS (5.950s)
[704.187384] Starting dynamic subtest: vcs1
[709.165341] Dynamic subtest vcs1: SUCCESS (4.989s)
[709.179215] Starting dynamic subtest: vecs0
[714.221177] Dynamic subtest vecs0: SUCCESS (5.054s)
[714.235226] Starting dynamic subtest: ccs0
[720.238745] Dynamic subtest ccs0: SUCCESS (6.015s)
[720.241437] Subtest engine-error-state-capture: SUCCESS (31.083s)
[720.345763] [114/132] (302s left) perf (low-oa-exponent-permissions)
[720.482682] Starting subtest: low-oa-exponent-permissions
[720.521276] Subtest low-oa-exponent-permissions: SUCCESS (0.038s)
[720.566058] [115/132] (301s left) gem_softpin (noreloc)
[720.639234] Starting subtest: noreloc
[726.078009] Subtest noreloc: SUCCESS (5.439s)
[726.321755] [116/132] (296s left) perf (enable-disable)
[726.428191] Starting subtest: enable-disable
[726.430985] Starting dynamic subtest: 0-rcs0
[728.718317] Dynamic subtest 0-rcs0: SUCCESS (2.289s)
[728.729274] Starting dynamic subtest: 1-vcs0
[733.199147] Dynamic subtest 1-vcs0: SUCCESS (4.481s)
[733.201712] Subtest enable-disable: SUCCESS (6.772s)
[733.252578] [117/132] (289s left) kms_pipe_crc_basic (nonblocking-crc)
[733.361969] Starting subtest: nonblocking-crc
[733.364378] Starting dynamic subtest: pipe-A-eDP-1
[734.113379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.751s)
[734.127142] Starting dynamic subtest: pipe-B-eDP-1
[735.575303] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.463s)
[735.587382] Starting dynamic subtest: pipe-C-eDP-1
[736.937395] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[736.949111] Starting dynamic subtest: pipe-D-eDP-1
[738.271169] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[738.274237] Subtest nonblocking-crc: SUCCESS (4.912s)
[739.096847] [118/132] (283s left) sysfs_preempt_timeout (timeout)
[739.168335] Starting subtest: timeout
[739.171234] Starting dynamic subtest: vecs0
[749.281125] Dynamic subtest vecs0: SUCCESS (10.114s)
[749.292427] Starting dynamic subtest: ccs0
[750.631335] Dynamic subtest ccs0: SUCCESS (1.350s)
[750.645270] Starting dynamic subtest: vcs0
[760.298382] Dynamic subtest vcs0: SUCCESS (9.665s)
[760.314189] Starting dynamic subtest: bcs0
[761.593512] Dynamic subtest bcs0: SUCCESS (1.295s)
[761.611347] Starting dynamic subtest: rcs0
[762.838898] Dynamic subtest rcs0: SUCCESS (1.243s)
[762.850936] Starting dynamic subtest: vcs1
[772.303190] Dynamic subtest vcs1: SUCCESS (9.461s)
[772.306368] Subtest timeout: SUCCESS (33.143s)
[772.408845] [119/132] (250s left) gem_mmap_gtt (hang-busy)
[772.470220] Subtest hang-busy: SKIP (0.000s)
[772.538648] [120/132] (250s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[772.640596] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[772.645599] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[772.761694] [121/132] (250s left) i915_drm_fdinfo (all-busy-idle-check-all)
[772.801437] Starting subtest: all-busy-idle-check-all
[772.806806] Subtest all-busy-idle-check-all: SKIP (0.003s)
[772.898970] [122/132] (249s left) perf_pmu (init-sema)
[772.941788] Starting subtest: init-sema
[772.944473] Starting dynamic subtest: rcs0
[772.947343] Dynamic subtest rcs0: SUCCESS (0.002s)
[772.960371] Starting dynamic subtest: bcs0
[772.963110] Dynamic subtest bcs0: SUCCESS (0.000s)
[772.973365] Starting dynamic subtest: vcs0
[772.976116] Dynamic subtest vcs0: SUCCESS (0.000s)
[772.986965] Starting dynamic subtest: vcs1
[772.989650] Dynamic subtest vcs1: SUCCESS (0.001s)
[773.001144] Starting dynamic subtest: vecs0
[773.003816] Dynamic subtest vecs0: SUCCESS (0.000s)
[773.014169] Starting dynamic subtest: ccs0
[773.016895] Dynamic subtest ccs0: SUCCESS (0.000s)
[773.019349] Subtest init-sema: SUCCESS (0.008s)
[773.104759] [123/132] (249s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[773.208526] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[780.226892] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.019s)
[780.387796] [124/132] (242s left) gem_exec_schedule (fairslice)
[780.460204] Starting subtest: fairslice
[780.463139] Starting dynamic subtest: rcs0
[782.472080] Dynamic subtest rcs0: SUCCESS (2.018s)
[782.483369] Starting dynamic subtest: bcs0
[784.486049] Dynamic subtest bcs0: SUCCESS (2.014s)
[784.496949] Starting dynamic subtest: vcs0
[786.506357] Dynamic subtest vcs0: SUCCESS (2.017s)
[786.518331] Starting dynamic subtest: vcs1
[788.523562] Dynamic subtest vcs1: SUCCESS (2.018s)
[788.536055] Starting dynamic subtest: vecs0
[790.541233] Dynamic subtest vecs0: SUCCESS (2.017s)
[790.553840] Starting dynamic subtest: ccs0
[792.566385] Dynamic subtest ccs0: SUCCESS (2.021s)
[792.568905] Subtest fairslice: SUCCESS (12.112s)
[792.887006] [125/132] (230s left) gem_exec_reloc (basic-wc-cpu)
[792.954856] Subtest basic-wc-cpu: SKIP (0.000s)
[793.043838] [126/132] (229s left) syncobj_timeline (wait-all-for-submit-complex)
[793.111146] Starting subtest: wait-all-for-submit-complex
[793.325400] Subtest wait-all-for-submit-complex: SUCCESS (0.215s)
[793.431000] [127/132] (229s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[793.563951] Starting subtest: cursorB-vs-flipB-legacy
[793.568938] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[793.728542] [128/132] (229s left) gem_exec_flush (basic-wb-set-default)
[793.803655] Starting subtest: basic-wb-set-default
[799.267164] Subtest basic-wb-set-default: SUCCESS (5.464s)
[799.479786] [129/132] (223s left) gem_pxp (reject-modify-context-protection-off-2)
[799.611006] Starting subtest: reject-modify-context-protection-off-2
[799.613672] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[799.754851] [130/132] (223s left) api_intel_bb (misplaced-blitter)
[799.820962] Starting subtest: misplaced-blitter
[799.832145] Subtest misplaced-blitter: SUCCESS (0.014s)
[799.998115] [131/132] (223s left) kms_psr (fbc-pr-dpms)
[800.108364] Starting subtest: fbc-pr-dpms
[800.113177] Subtest fbc-pr-dpms: SKIP (0.000s)
[800.214994] [132/132] (223s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[800.316230] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[800.321352] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[800.443457] 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.