[59.499896] Initializing watchdogs
[59.499935]   /dev/watchdog0
[59.514459] [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]
[59.519059] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.521440] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.525253] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[59.821194] Starting subtest: noheartbeat
[59.826086] Subtest noheartbeat: SKIP (0.000s)
[59.956641] [002/132] (960s left) prime_vgem (basic-gtt)
[60.058739] Starting subtest: basic-gtt
[60.063507] Subtest basic-gtt: SKIP (0.000s)
[60.219135] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[60.222137] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[60.337041] Starting subtest: fbc-1p-pri-indfb-multidraw
[73.813029] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.480s)
[75.199603] [004/132] (944s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[75.307550] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[84.679562] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.374s)
[84.860423] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[84.964181] Starting subtest: rmfb-ioctl
[84.966252] Starting dynamic subtest: pipe-A-eDP-1
[85.067813] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[85.077836] Starting dynamic subtest: pipe-B-eDP-1
[86.282663] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.213s)
[86.290984] Starting dynamic subtest: pipe-C-eDP-1
[87.398071] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.114s)
[87.407849] Starting dynamic subtest: pipe-D-eDP-1
[88.534548] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[88.536995] Subtest rmfb-ioctl: SUCCESS (3.573s)
[89.765898] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[89.878410] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[96.435673] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.560s)
[96.604849] [007/132] (923s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[96.995701] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[97.782432] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[97.897116] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[97.899480] Starting dynamic subtest: pipe-A-default-mode
[99.054759] Dynamic subtest pipe-A-default-mode: SKIP (1.156s)
[99.058912] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.157s)
[99.257904] [009/132] (921s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[99.356653] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[99.360817] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[99.472267] [010/132] (920s left) gem_exec_reloc (basic-write-read-noreloc)
[99.537834] Subtest basic-write-read-noreloc: SKIP (0.000s)
[99.617168] [011/132] (920s left) kms_draw_crc (draw-method-blt)
[99.726767] Starting subtest: draw-method-blt
[99.728689] Starting dynamic subtest: xrgb8888-untiled
[99.960066] Dynamic subtest xrgb8888-untiled: SUCCESS (0.235s)
[99.968465] Starting dynamic subtest: rgb565-untiled
[100.171045] Dynamic subtest rgb565-untiled: SUCCESS (0.211s)
[100.178592] Starting dynamic subtest: xrgb2101010-untiled
[100.393369] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[100.401576] Starting dynamic subtest: xbgr16161616f-untiled
[100.593437] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.200s)
[100.601228] Starting dynamic subtest: xrgb8888-xtiled
[100.704494] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.111s)
[100.711482] Starting dynamic subtest: rgb565-xtiled
[100.826992] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[100.834601] Starting dynamic subtest: xrgb2101010-xtiled
[100.937748] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[100.945707] Starting dynamic subtest: xbgr16161616f-xtiled
[101.049060] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[101.057069] Starting dynamic subtest: xrgb8888-4tiled
[101.160047] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.110s)
[101.167742] Starting dynamic subtest: rgb565-4tiled
[101.271337] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s)
[101.280187] Starting dynamic subtest: xrgb2101010-4tiled
[101.381996] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.109s)
[101.388902] Starting dynamic subtest: xbgr16161616f-4tiled
[101.493605] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[101.495655] Subtest draw-method-blt: SUCCESS (1.768s)
[101.653688] [012/132] (918s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[101.765497] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[101.767412] Starting dynamic subtest: pipe-A-eDP-1
[101.786991] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[101.795876] Starting dynamic subtest: pipe-B-eDP-1
[101.813469] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[101.819947] Starting dynamic subtest: pipe-C-eDP-1
[101.836836] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[101.843486] Starting dynamic subtest: pipe-D-eDP-1
[101.860871] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[101.864078] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[104.107930] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[104.241825] Starting subtest: gen12-group-exclusive-stream-sample-oa
[104.261496] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.019s)
[104.298478] [014/132] (916s left) gem_create (hog-create)
[104.362435] Starting subtest: hog-create
[104.364747] Starting dynamic subtest: smem0
[137.950615] Dynamic subtest smem0: SUCCESS (33.586s)
[137.953071] Subtest hog-create: SUCCESS (33.587s)
[138.160552] [015/132] (882s left) kms_cdclk (plane-scaling)
[138.266672] Starting subtest: plane-scaling
[138.268811] Starting dynamic subtest: pipe-A-eDP-1
[138.553834] Dynamic subtest pipe-A-eDP-1: SKIP (0.286s)
[138.562743] Starting dynamic subtest: pipe-B-eDP-1
[138.567093] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[138.574665] Starting dynamic subtest: pipe-C-eDP-1
[138.579161] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[138.586663] Starting dynamic subtest: pipe-D-eDP-1
[138.590845] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[138.595163] Subtest plane-scaling: SKIP (0.293s)
[139.416245] [016/132] (881s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[139.513612] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[139.517619] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[139.622815] [017/132] (881s left) gem_softpin (softpin)
[139.685689] Starting subtest: softpin
[171.936687] Subtest softpin: SUCCESS (32.246s)
[172.099288] [018/132] (848s left) kms_joiner (basic-force-ultra-joiner)
[172.220993] Starting subtest: basic-force-ultra-joiner
[172.225225] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[172.306128] [019/132] (848s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[172.407377] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[172.418618] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.010s)
[172.546858] [020/132] (848s left) syncobj_timeline (reset-multiple-signaled)
[172.614072] Starting subtest: reset-multiple-signaled
[172.616506] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[172.753655] [021/132] (848s left) drm_mm (drm_mm)
[172.790767] Starting subtest: drm_mm
[172.834701] Starting dynamic subtest: drm_test_mm_init
[172.979816] Dynamic subtest drm_test_mm_init: SUCCESS (0.146s)
[172.992372] Starting dynamic subtest: drm_test_mm_debug
[173.146563] Dynamic subtest drm_test_mm_debug: SUCCESS (0.164s)
[173.155671] Starting dynamic subtest: drm_test_mm_align32
[173.300731] Dynamic subtest drm_test_mm_align32: SUCCESS (0.155s)
[173.310741] Starting dynamic subtest: drm_test_mm_align64
[173.391823] Dynamic subtest drm_test_mm_align64: SUCCESS (0.091s)
[173.400920] Starting dynamic subtest: drm_test_mm_lowest
[173.534652] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.141s)
[173.543901] Starting dynamic subtest: drm_test_mm_highest
[173.662574] Dynamic subtest drm_test_mm_highest: SUCCESS (0.126s)
[173.664639] Subtest drm_mm: SUCCESS (0.869s)
[173.856248] [022/132] (847s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[178.847385] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[178.851836] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[179.731884] [023/132] (841s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[179.828088] Starting subtest: plane-ctm3x4-lut1d
[179.840387] Starting dynamic subtest: pipe-A-eDP-1
[180.567836] Dynamic subtest pipe-A-eDP-1: FAIL (0.331s)
[181.203677] Starting dynamic subtest: pipe-B-eDP-1
[182.043299] Dynamic subtest pipe-B-eDP-1: FAIL (0.314s)
[182.644138] Starting dynamic subtest: pipe-C-eDP-1
[183.481244] Dynamic subtest pipe-C-eDP-1: FAIL (0.245s)
[183.980807] Starting dynamic subtest: pipe-D-eDP-1
[184.810229] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[184.812274] Subtest plane-ctm3x4-lut1d: FAIL (4.676s)
[185.384952] [024/132] (835s left) kms_psr (fbc-psr-primary-mmap-gtt)
[185.484712] Starting subtest: fbc-psr-primary-mmap-gtt
[185.486590] Starting dynamic subtest: eDP-1
[185.490599] Dynamic subtest eDP-1: SKIP (0.000s)
[185.494617] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[185.579334] [025/132] (835s left) gem_exec_schedule (u-semaphore-user)
[185.649522] Starting subtest: u-semaphore-user
[185.700468] Subtest u-semaphore-user: SUCCESS (0.058s)
[186.967904] [026/132] (834s left) gem_userptr_blits (unsync-unmap-cycles)
[187.040077] Subtest unsync-unmap-cycles: SKIP (0.000s)
[187.208178] [027/132] (833s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[187.307141] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[187.309487] Starting dynamic subtest: pipe-A-default-mode
[187.599520] Dynamic subtest pipe-A-default-mode: SKIP (0.289s)
[187.604408] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.290s)
[188.428853] [028/132] (832s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[188.524787] Starting subtest: fbc-rgb565-draw-pwrite
[193.033981] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.513s)
[194.240728] [029/132] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[194.346624] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[201.875266] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.531s)
[202.067985] [030/132] (819s left) kms_cursor_edge_walk (256x256-right-edge)
[202.165161] Starting subtest: 256x256-right-edge
[202.167480] Starting dynamic subtest: pipe-A-eDP-1
[205.604083] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.440s)
[205.613762] Starting dynamic subtest: pipe-D-eDP-1
[208.721877] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[208.724215] Subtest 256x256-right-edge: SUCCESS (6.558s)
[209.565888] [031/132] (811s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[209.675319] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[214.014049] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.341s)
[215.160305] [032/132] (806s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[215.273043] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[215.277607] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[215.414826] [033/132] (805s left) i915_pm_rc6_residency (media-rc6-accuracy)
[215.487824] Starting subtest: media-rc6-accuracy
[215.491986] Subtest media-rc6-accuracy: SKIP (0.000s)
[215.630424] [034/132] (805s left) kms_async_flips (async-flip-hang)
[215.721299] Starting subtest: async-flip-hang
[216.094330] Starting dynamic subtest: pipe-A-eDP-1
[231.348901] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.251s)
[231.738387] Starting dynamic subtest: pipe-B-eDP-1
[247.347691] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.614s)
[247.721464] Starting dynamic subtest: pipe-C-eDP-1
[263.407249] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.688s)
[263.787276] Starting dynamic subtest: pipe-D-eDP-1
[279.341974] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.560s)
[279.344461] Subtest async-flip-hang: SUCCESS (63.618s)
[280.599078] [035/132] (740s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[280.701369] Starting subtest: fbc-rgb101010-draw-render
[285.281224] Subtest fbc-rgb101010-draw-render: SUCCESS (4.582s)
[286.500480] [036/132] (734s left) kms_pm_dc (dc5-psr)
[287.596290] Starting subtest: dc5-psr
[290.563535] Subtest dc5-psr: SUCCESS (2.966s)
[291.405643] [037/132] (730s left) kms_getfb (getfb-handle-valid)
[291.515826] Starting subtest: getfb-handle-valid
[291.518077] Subtest getfb-handle-valid: SUCCESS (0.000s)
[291.652424] [038/132] (729s left) perf (oa-formats)
[291.761644] Starting subtest: oa-formats
[291.764103] Starting dynamic subtest: 0-rcs0
[291.773998] Dynamic subtest 0-rcs0: SUCCESS (0.012s)
[291.782977] Starting dynamic subtest: 1-vecs0
[291.796788] Dynamic subtest 1-vecs0: SUCCESS (0.022s)
[291.798967] Subtest oa-formats: SUCCESS (0.036s)
[291.836410] [039/132] (729s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[291.899250] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[291.903857] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[292.106643] [040/132] (729s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[292.213071] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[292.217261] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[292.345224] [041/132] (729s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[292.437321] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[292.439671] Starting dynamic subtest: pipe-A-eDP-1
[308.470268] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.004s)
[308.479860] Starting dynamic subtest: pipe-B-eDP-1
[325.416930] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.360s)
[325.426333] Starting dynamic subtest: pipe-C-eDP-1
[342.414823] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.357s)
[342.424376] Starting dynamic subtest: pipe-D-eDP-1
[359.412620] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.371s)
[359.414896] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (12.098s)
[360.700892] [042/132] (660s left) gem_exec_fence (keep-in-fence)
[360.895689] Starting subtest: keep-in-fence
[360.897769] Starting dynamic subtest: rcs0
[360.966628] Dynamic subtest rcs0: SUCCESS (0.074s)
[360.977815] Starting dynamic subtest: bcs0
[361.021205] Dynamic subtest bcs0: SUCCESS (0.052s)
[361.032206] Starting dynamic subtest: vcs0
[362.795850] Dynamic subtest vcs0: SUCCESS (1.768s)
[362.807212] Starting dynamic subtest: vcs1
[364.777908] Dynamic subtest vcs1: SUCCESS (1.977s)
[364.789490] Starting dynamic subtest: vecs0
[366.762870] Dynamic subtest vecs0: SUCCESS (1.980s)
[366.774665] Starting dynamic subtest: ccs0
[366.998690] Dynamic subtest ccs0: SUCCESS (0.234s)
[367.001861] Subtest keep-in-fence: SUCCESS (6.100s)
[368.876367] [043/132] (652s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[368.999842] Starting subtest: psr-rgb565-draw-pwrite
[376.027226] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.025s)
[376.153335] [044/132] (645s left) perf (per-context-mode-unprivileged)
[376.288774] Starting subtest: per-context-mode-unprivileged
[376.293537] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[376.334583] [045/132] (645s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[376.434057] Starting subtest: x-tiled-8bpp-rotate-0
[377.617881] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.182s)
[377.754690] [046/132] (644s left) gem_lmem_swapping (parallel-random-verify-ccs)
[377.796278] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[377.839080] [047/132] (644s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[377.934763] Starting subtest: 4-tiled-64bpp-rotate-180
[379.450872] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.514s)
[379.700585] [048/132] (642s left) gem_exec_schedule (preempt)
[379.784888] Starting subtest: preempt
[379.786768] Starting dynamic subtest: rcs0
[379.803448] Dynamic subtest rcs0: SUCCESS (0.024s)
[379.815035] Starting dynamic subtest: bcs0
[379.826927] Dynamic subtest bcs0: SUCCESS (0.023s)
[379.835632] Starting dynamic subtest: vcs0
[379.850001] Dynamic subtest vcs0: SUCCESS (0.022s)
[379.860299] Starting dynamic subtest: vcs1
[379.869426] Dynamic subtest vcs1: SUCCESS (0.019s)
[379.878048] Starting dynamic subtest: vecs0
[379.891984] Dynamic subtest vecs0: SUCCESS (0.021s)
[379.901309] Starting dynamic subtest: ccs0
[379.912453] Dynamic subtest ccs0: SUCCESS (0.020s)
[379.914328] Subtest preempt: SUCCESS (0.133s)
[381.928927] [049/132] (640s left) kms_flip (2x-wf_vblank-ts-check)
[382.030206] Starting subtest: 2x-wf_vblank-ts-check
[382.038995] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s)
[382.185802] [050/132] (639s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[382.283248] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[382.285563] Starting dynamic subtest: pipe-A-eDP-1
[382.306032] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[382.319675] Starting dynamic subtest: pipe-B-eDP-1
[382.340227] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[382.350842] Starting dynamic subtest: pipe-C-eDP-1
[382.370849] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[382.380944] Starting dynamic subtest: pipe-D-eDP-1
[382.400774] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[382.403158] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[384.537865] [051/132] (637s left) sw_sync (alloc_timeline)
[384.575321] Starting subtest: alloc_timeline
[384.577743] Subtest alloc_timeline: SUCCESS (0.000s)
[384.650118] [052/132] (637s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[384.713610] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[384.716009] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[384.826994] [053/132] (637s left) kms_atomic_transition (modeset-transition-nonblocking)
[384.917079] Starting subtest: modeset-transition-nonblocking
[384.919033] Starting dynamic subtest: 1x-outputs
[395.957523] Dynamic subtest 1x-outputs: SUCCESS (11.036s)
[395.960009] Subtest modeset-transition-nonblocking: SUCCESS (11.037s)
[396.841106] [054/132] (625s left) kms_pm_rpm (cursor)
[398.880608] Starting subtest: cursor
[398.885347] Subtest cursor: SKIP (0.000s)
[399.257491] [055/132] (622s left) kms_selftest (drm_damage_helper)
[399.298812] Starting subtest: drm_damage_helper
[399.318560] Starting dynamic subtest: drm_test_damage_iter_no_damage
[399.450580] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.132s)
[399.459802] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[399.595124] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.143s)
[399.605692] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[399.741008] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.145s)
[399.750802] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[399.871484] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.130s)
[399.881192] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[399.992484] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.119s)
[400.006047] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[400.121021] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.128s)
[400.131458] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[400.262980] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.141s)
[400.272129] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[400.394187] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.128s)
[400.404591] Starting dynamic subtest: drm_test_damage_iter_single_damage
[400.526028] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.132s)
[400.536135] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[400.629161] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.102s)
[400.638603] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[400.753776] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.122s)
[400.763820] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[400.868123] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.112s)
[400.878154] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[401.004831] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.136s)
[401.014443] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[401.145026] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.139s)
[401.155689] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[401.273010] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.126s)
[401.282161] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[401.391494] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.115s)
[401.406467] Starting dynamic subtest: drm_test_damage_iter_damage
[401.519918] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.129s)
[401.530463] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[401.642187] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.121s)
[401.652421] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[401.788111] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.145s)
[401.798128] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[401.919029] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.128s)
[401.928110] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[402.012276] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.093s)
[402.014583] Subtest drm_damage_helper: SUCCESS (2.714s)
[402.238360] [056/132] (620s left) gem_userptr_blits (unsync-overlap)
[402.323554] Subtest unsync-overlap: SKIP (0.000s)
[402.495775] [057/132] (619s left) kms_psr (pr-sprite-mmap-gtt)
[402.605045] Starting subtest: pr-sprite-mmap-gtt
[402.609166] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[402.699836] [058/132] (619s left) kms_psr (psr-sprite-mmap-gtt)
[402.802342] Starting subtest: psr-sprite-mmap-gtt
[402.804657] Starting dynamic subtest: eDP-1
[404.257900] Dynamic subtest eDP-1: SKIP (1.452s)
[404.262649] Subtest psr-sprite-mmap-gtt: SKIP (1.454s)
[404.475355] [059/132] (617s left) gem_exec_parallel (basic)
[404.545327] Starting subtest: basic
[404.547741] Starting dynamic subtest: rcs0
[405.975667] Dynamic subtest rcs0: SUCCESS (1.429s)
[405.985021] Starting dynamic subtest: bcs0
[407.418012] Dynamic subtest bcs0: SUCCESS (1.441s)
[407.428287] Starting dynamic subtest: vcs0
[410.729598] Dynamic subtest vcs0: SUCCESS (3.306s)
[410.741866] Starting dynamic subtest: vcs1
[412.713043] Dynamic subtest vcs1: SUCCESS (1.980s)
[412.722714] Starting dynamic subtest: vecs0
[414.762511] Dynamic subtest vecs0: SUCCESS (2.046s)
[414.772609] Starting dynamic subtest: ccs0
[416.245227] Dynamic subtest ccs0: SUCCESS (1.480s)
[416.247696] Subtest basic: SUCCESS (11.695s)
[416.457762] [060/132] (605s left) kms_content_protection (content-type-change)
[416.601590] Starting subtest: content-type-change
[416.606120] Subtest content-type-change: SKIP (0.000s)
[416.767115] [061/132] (605s left) gem_mmap_wc (write-read-distinct)
[416.831165] Subtest write-read-distinct: SKIP (0.000s)
[416.902023] [062/132] (605s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[417.001646] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[417.804191] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.803s)
[417.991353] [063/132] (604s left) gem_ctx_persistence (engines-mixed-process)
[418.068991] Starting subtest: engines-mixed-process
[418.071305] Starting dynamic subtest: rcs0
[419.774637] Dynamic subtest rcs0: SUCCESS (1.704s)
[419.786346] Starting dynamic subtest: bcs0
[421.743276] Dynamic subtest bcs0: SUCCESS (1.963s)
[421.755052] Starting dynamic subtest: vcs0
[423.771611] Dynamic subtest vcs0: SUCCESS (2.026s)
[423.782223] Starting dynamic subtest: vcs1
[425.752537] Dynamic subtest vcs1: SUCCESS (1.982s)
[425.763790] Starting dynamic subtest: vecs0
[427.831720] Dynamic subtest vecs0: SUCCESS (2.077s)
[427.843241] Starting dynamic subtest: ccs0
[429.824853] Dynamic subtest ccs0: SUCCESS (1.990s)
[429.827288] Subtest engines-mixed-process: SUCCESS (11.751s)
[429.930721] [064/132] (592s left) kms_chamelium_edid (vga-edid-read)
[430.349368] Subtest vga-edid-read: SKIP (0.000s)
[431.180578] [065/132] (591s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[431.312280] Starting subtest: 2x-long-flip-vs-cursor-atomic
[431.316798] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[431.480651] [066/132] (591s left) gem_exec_create (legacy)
[431.539951] Starting subtest: legacy
[431.542246] Starting dynamic subtest: smem
[433.630627] Dynamic subtest smem: SUCCESS (2.089s)
[433.633109] Subtest legacy: SUCCESS (2.091s)
[433.839804] [067/132] (588s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[433.944100] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[433.948100] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[434.086867] [068/132] (588s left) gem_softpin (zero)
[434.148482] Starting subtest: zero
[434.153174] Subtest zero: SUCCESS (0.004s)
[434.296621] [069/132] (588s left) gem_exec_flush (basic-wb-ro-before-default)
[434.356241] Starting subtest: basic-wb-ro-before-default
[439.831427] Subtest basic-wb-ro-before-default: SUCCESS (5.473s)
[440.044043] [070/132] (582s left) kms_big_fb (linear-64bpp-rotate-90)
[440.158317] Starting subtest: linear-64bpp-rotate-90
[440.179833] Subtest linear-64bpp-rotate-90: SKIP (0.021s)
[440.339494] [071/132] (582s left) gem_userptr_blits (set-cache-level)
[440.416291] Starting subtest: set-cache-level
[440.420437] Subtest set-cache-level: SKIP (0.000s)
[440.580858] [072/132] (582s left) perf_pmu (busy)
[440.622099] Starting subtest: busy
[440.624690] Starting dynamic subtest: rcs0
[441.244407] Dynamic subtest rcs0: SUCCESS (0.623s)
[441.257360] Starting dynamic subtest: bcs0
[441.813459] Dynamic subtest bcs0: SUCCESS (0.567s)
[441.826171] Starting dynamic subtest: vcs0
[443.754180] Dynamic subtest vcs0: SUCCESS (1.936s)
[443.768537] Starting dynamic subtest: vcs1
[445.802399] Dynamic subtest vcs1: SUCCESS (2.044s)
[445.815589] Starting dynamic subtest: vecs0
[447.786351] Dynamic subtest vecs0: SUCCESS (1.980s)
[447.799820] Starting dynamic subtest: ccs0
[448.351700] Dynamic subtest ccs0: SUCCESS (0.564s)
[448.354709] Subtest busy: SUCCESS (7.728s)
[448.492930] [073/132] (574s left) gem_basic (multigpu-create-close)
[448.560487] Starting subtest: multigpu-create-close
[448.564270] Subtest multigpu-create-close: SKIP (0.000s)
[448.692709] [074/132] (574s left) gen9_exec_parse (bb-secure)
[448.737306] Subtest bb-secure: SKIP (0.000s)
[448.840023] [075/132] (574s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[448.907531] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[448.909826] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[449.038787] [076/132] (574s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[454.011576] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[454.016107] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[454.872087] [077/132] (568s left) kms_lease (lease-invalid-crtc)
[454.985450] Starting subtest: lease-invalid-crtc
[454.987712] Starting dynamic subtest: pipe-A-eDP-1
[454.990040] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[455.000509] Starting dynamic subtest: pipe-B-eDP-1
[455.002411] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[455.011819] Starting dynamic subtest: pipe-C-eDP-1
[455.014120] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[455.023795] Starting dynamic subtest: pipe-D-eDP-1
[455.026124] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[455.028074] Subtest lease-invalid-crtc: SUCCESS (0.014s)
[455.098423] [078/132] (568s left) gem_pwrite_snooped
[455.260639] [079/132] (568s left) gem_exec_reloc (basic-write-wc-noreloc)
[455.321562] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[455.406873] [080/132] (567s left) kms_flip (wf_vblank-ts-check-interruptible)
[455.501221] Starting subtest: wf_vblank-ts-check-interruptible
[455.503390] Starting dynamic subtest: A-eDP1
[464.372810] Dynamic subtest A-eDP1: SUCCESS (8.879s)
[464.384168] Starting dynamic subtest: B-eDP1
[473.295391] Dynamic subtest B-eDP1: SUCCESS (8.927s)
[473.307058] Starting dynamic subtest: C-eDP1
[482.216701] Dynamic subtest C-eDP1: SUCCESS (8.923s)
[482.228009] Starting dynamic subtest: D-eDP1
[491.058042] Dynamic subtest D-eDP1: SUCCESS (8.841s)
[491.060786] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.580s)
[492.316014] [081/132] (531s left) gem_ctx_persistence (legacy-engines-queued)
[492.389992] Starting subtest: legacy-engines-queued
[492.392404] Starting dynamic subtest: render
[492.519277] Dynamic subtest render: SUCCESS (0.128s)
[492.535306] Starting dynamic subtest: bsd1
[493.704038] Dynamic subtest bsd1: SUCCESS (1.180s)
[493.717091] Starting dynamic subtest: bsd2
[495.752405] Dynamic subtest bsd2: SUCCESS (2.045s)
[495.765699] Starting dynamic subtest: blt
[495.833777] Dynamic subtest blt: SUCCESS (0.080s)
[495.846043] Starting dynamic subtest: vebox
[497.736340] Dynamic subtest vebox: SUCCESS (1.898s)
[497.739252] Subtest legacy-engines-queued: SUCCESS (5.347s)
[497.841337] [082/132] (525s left) gem_exercise_blt (fast-copy)
[497.895267] Starting subtest: fast-copy
[497.897582] Starting dynamic subtest: linear-smem-smem
[497.922403] Dynamic subtest linear-smem-smem: SUCCESS (0.026s)
[497.935498] Starting dynamic subtest: xmajor-smem-smem
[497.940121] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[497.951325] Starting dynamic subtest: tile4-smem-smem
[497.957056] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[497.967098] Starting dynamic subtest: tile64-smem-smem
[497.981738] Dynamic subtest tile64-smem-smem: SUCCESS (0.023s)
[497.984102] Subtest fast-copy: SUCCESS (0.086s)
[498.153470] [083/132] (525s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[498.257165] Starting subtest: bo-too-small-due-to-tiling
[498.261528] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[498.397363] [084/132] (525s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[498.465586] Starting subtest: multi-wait-all-for-submit-signaled
[498.468043] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[498.606842] [085/132] (524s left) kms_cursor_crc (cursor-random-512x512)
[498.892528] Starting subtest: cursor-random-512x512
[498.897004] Subtest cursor-random-512x512: SKIP (0.001s)
[499.104019] [086/132] (524s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[499.158229] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[499.216470] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.005s)
[499.310340] [087/132] (524s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[499.413594] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[499.415541] Starting dynamic subtest: pipe-A-default-mode
[499.709721] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[499.714353] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.295s)
[500.573967] [088/132] (523s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[500.673194] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[500.675438] Starting dynamic subtest: pipe-A-eDP-1
[500.698600] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[500.713775] Starting dynamic subtest: pipe-B-eDP-1
[500.735821] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[500.745287] Starting dynamic subtest: pipe-C-eDP-1
[500.767370] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[500.776157] Starting dynamic subtest: pipe-D-eDP-1
[500.798100] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[500.802424] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[502.997276] [089/132] (520s left) kms_plane_multiple (2x-tiling-none)
[503.102157] Starting subtest: 2x-tiling-none
[503.106772] Subtest 2x-tiling-none: SKIP (0.000s)
[503.205343] [090/132] (520s left) kms_psr (fbc-psr2-sprite-blt)
[503.311581] Starting subtest: fbc-psr2-sprite-blt
[503.313927] Starting dynamic subtest: eDP-1
[503.318400] Dynamic subtest eDP-1: SKIP (0.000s)
[503.322572] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[503.411828] [091/132] (520s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[503.524078] Starting subtest: y-tiled-16bpp-rotate-270
[503.528360] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[503.662527] [092/132] (520s left) i915_query (engine-info-invalid)
[503.719776] Starting subtest: engine-info-invalid
[503.721783] Subtest engine-info-invalid: SUCCESS (0.000s)
[503.857601] [093/132] (520s left) gem_ctx_param (invalid-size-set)
[503.922925] Starting subtest: invalid-size-set
[503.925143] Subtest invalid-size-set: SUCCESS (0.001s)
[504.059376] [094/132] (519s left) kms_fbcon_fbt (psr-suspend)
[504.127464] Starting subtest: psr-suspend
[553.280040] Subtest psr-suspend: SUCCESS (19.626s)
[553.372414] [095/132] (470s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[553.435284] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[553.545933] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[553.649471] [096/132] (470s left) kms_pm_rpm (universal-planes)
[555.782967] Starting subtest: universal-planes
[555.785653] Starting dynamic subtest: plane-33
[564.471667] Dynamic subtest plane-33: SUCCESS (8.681s)
[564.484592] Starting dynamic subtest: plane-63
[572.777651] Dynamic subtest plane-63: SUCCESS (8.298s)
[572.790029] Starting dynamic subtest: plane-93
[581.408223] Dynamic subtest plane-93: SUCCESS (8.626s)
[581.419905] Starting dynamic subtest: plane-123
[589.713111] Dynamic subtest plane-123: SUCCESS (8.304s)
[589.725881] Starting dynamic subtest: plane-133
[598.214095] Dynamic subtest plane-133: SUCCESS (8.498s)
[598.227413] Starting dynamic subtest: plane-143
[606.473357] Dynamic subtest plane-143: SUCCESS (8.255s)
[606.476284] Subtest universal-planes: SUCCESS (50.681s)
[606.875310] [097/132] (417s left) gem_exec_reloc (basic-write-read)
[606.943260] Subtest basic-write-read: SKIP (0.000s)
[607.035736] [098/132] (417s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[607.141906] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[607.145809] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[607.256735] [099/132] (416s left) kms_cursor_crc (cursor-onscreen-32x32)
[607.421021] Starting subtest: cursor-onscreen-32x32
[607.467824] Subtest cursor-onscreen-32x32: SKIP (0.041s)
[607.798343] [100/132] (416s left) syncobj_wait (invalid-wait-bad-flags)
[607.871144] Starting subtest: invalid-wait-bad-flags
[607.873313] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[608.009632] [101/132] (416s left) gem_exec_whisper (basic-contexts-forked-all)
[608.075309] Starting subtest: basic-contexts-forked-all
[628.310756] Subtest basic-contexts-forked-all: SUCCESS (20.230s)
[628.519763] [102/132] (395s left) gem_fenced_exec_thrash (2-spare-fences)
[628.585487] Subtest 2-spare-fences: SKIP (0.000s)
[628.662569] [103/132] (395s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[628.757931] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[628.761804] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[628.891539] [104/132] (395s left) api_intel_bb (add-remove-objects)
[628.949325] Starting subtest: add-remove-objects
[628.954745] Subtest add-remove-objects: SUCCESS (0.008s)
[629.120064] [105/132] (395s left) kms_vblank (invalid)
[629.216500] Starting subtest: invalid
[629.218290] Starting dynamic subtest: pipe-A-eDP-1
[629.620888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.405s)
[629.623159] Subtest invalid: SUCCESS (0.406s)
[630.503163] [106/132] (393s left) gem_mmap_wc (bad-object)
[630.565975] Subtest bad-object: SKIP (0.000s)
[630.632964] [107/132] (393s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[630.735557] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[630.739784] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[630.870044] [108/132] (393s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[630.931298] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[631.018727] [109/132] (393s left) gem_tiled_fence_blits (normal)
[631.066223] Subtest normal: SKIP (0.000s)
[631.152417] [110/132] (393s left) gem_exec_alignment (pi)
[631.210952] Starting subtest: pi
[682.268289] Subtest pi: SUCCESS (51.054s)
[683.441786] [111/132] (341s left) gem_mmap (bad-object)
[683.548382] Subtest bad-object: SKIP (0.000s)
[683.610670] [112/132] (341s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[683.715802] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[683.718099] Starting dynamic subtest: pipe-A
[683.956479] Dynamic subtest pipe-A: SUCCESS (0.243s)
[683.967632] Starting dynamic subtest: pipe-B
[685.281275] Dynamic subtest pipe-B: SUCCESS (1.324s)
[685.291894] Starting dynamic subtest: pipe-C
[686.620781] Dynamic subtest pipe-C: SUCCESS (1.337s)
[686.631536] Starting dynamic subtest: pipe-D
[687.932432] Dynamic subtest pipe-D: SUCCESS (1.310s)
[687.935214] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.219s)
[689.128520] [113/132] (335s left) i915_hangman (engine-error-state-capture)
[689.203414] Starting subtest: engine-error-state-capture
[689.205759] Starting dynamic subtest: rcs0
[692.237980] Dynamic subtest rcs0: SUCCESS (3.035s)
[692.251538] Starting dynamic subtest: bcs0
[698.252131] Dynamic subtest bcs0: SUCCESS (6.011s)
[698.265734] Starting dynamic subtest: vcs0
[704.204381] Dynamic subtest vcs0: SUCCESS (5.949s)
[704.217803] Starting dynamic subtest: vcs1
[709.194741] Dynamic subtest vcs1: SUCCESS (4.987s)
[709.208300] Starting dynamic subtest: vecs0
[714.188754] Dynamic subtest vecs0: SUCCESS (4.991s)
[714.202636] Starting dynamic subtest: ccs0
[719.244208] Dynamic subtest ccs0: SUCCESS (5.051s)
[719.246925] Subtest engine-error-state-capture: SUCCESS (30.038s)
[719.350563] [114/132] (305s left) perf (low-oa-exponent-permissions)
[719.490210] Starting subtest: low-oa-exponent-permissions
[719.523218] Subtest low-oa-exponent-permissions: SUCCESS (0.033s)
[719.573995] [115/132] (305s left) gem_softpin (noreloc)
[719.632951] Starting subtest: noreloc
[725.026187] Subtest noreloc: SUCCESS (5.391s)
[725.200928] [116/132] (299s left) perf (enable-disable)
[725.301748] Starting subtest: enable-disable
[725.304175] Starting dynamic subtest: 0-rcs0
[727.570755] Dynamic subtest 0-rcs0: SUCCESS (2.266s)
[727.581293] Starting dynamic subtest: 1-vcs0
[732.038080] Dynamic subtest 1-vcs0: SUCCESS (4.465s)
[732.040440] Subtest enable-disable: SUCCESS (6.733s)
[732.089082] [117/132] (292s left) kms_pipe_crc_basic (nonblocking-crc)
[732.195971] Starting subtest: nonblocking-crc
[732.197895] Starting dynamic subtest: pipe-A-eDP-1
[732.933270] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[732.944892] Starting dynamic subtest: pipe-B-eDP-1
[734.306553] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.370s)
[734.317942] Starting dynamic subtest: pipe-C-eDP-1
[735.725339] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.417s)
[735.743214] Starting dynamic subtest: pipe-D-eDP-1
[737.051393] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[737.053928] Subtest nonblocking-crc: SUCCESS (4.856s)
[737.884040] [118/132] (287s left) sysfs_preempt_timeout (timeout)
[737.949960] Starting subtest: timeout
[737.952276] Starting dynamic subtest: vecs0
[747.324359] Dynamic subtest vecs0: SUCCESS (9.368s)
[747.337891] Starting dynamic subtest: ccs0
[748.741343] Dynamic subtest ccs0: SUCCESS (1.416s)
[748.754273] Starting dynamic subtest: vcs0
[758.258589] Dynamic subtest vcs0: SUCCESS (9.511s)
[758.274539] Starting dynamic subtest: bcs0
[759.563871] Dynamic subtest bcs0: SUCCESS (1.304s)
[759.580904] Starting dynamic subtest: rcs0
[760.864045] Dynamic subtest rcs0: SUCCESS (1.298s)
[760.876443] Starting dynamic subtest: vcs1
[770.305506] Dynamic subtest vcs1: SUCCESS (9.435s)
[770.307905] Subtest timeout: SUCCESS (32.345s)
[770.404062] [119/132] (254s left) gem_mmap_gtt (hang-busy)
[770.450257] Subtest hang-busy: SKIP (0.000s)
[770.518433] [120/132] (254s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[770.636969] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[770.640978] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[770.780953] [121/132] (254s left) i915_drm_fdinfo (all-busy-idle-check-all)
[770.827327] Starting subtest: all-busy-idle-check-all
[770.834291] Subtest all-busy-idle-check-all: SKIP (0.005s)
[770.930746] [122/132] (254s left) perf_pmu (init-sema)
[770.968048] Starting subtest: init-sema
[770.970356] Starting dynamic subtest: rcs0
[770.972573] Dynamic subtest rcs0: SUCCESS (0.001s)
[770.983345] Starting dynamic subtest: bcs0
[770.985529] Dynamic subtest bcs0: SUCCESS (0.000s)
[770.995545] Starting dynamic subtest: vcs0
[770.997756] Dynamic subtest vcs0: SUCCESS (0.000s)
[771.007661] Starting dynamic subtest: vcs1
[771.009612] Dynamic subtest vcs1: SUCCESS (0.000s)
[771.019232] Starting dynamic subtest: vecs0
[771.021157] Dynamic subtest vecs0: SUCCESS (0.000s)
[771.030653] Starting dynamic subtest: ccs0
[771.032583] Dynamic subtest ccs0: SUCCESS (0.000s)
[771.034459] Subtest init-sema: SUCCESS (0.006s)
[771.124768] [123/132] (254s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[771.235741] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[778.269903] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.040s)
[778.489017] [124/132] (246s left) gem_exec_schedule (fairslice)
[778.598266] Starting subtest: fairslice
[778.600207] Starting dynamic subtest: rcs0
[780.618410] Dynamic subtest rcs0: SUCCESS (2.020s)
[780.630790] Starting dynamic subtest: bcs0
[782.637139] Dynamic subtest bcs0: SUCCESS (2.017s)
[782.649328] Starting dynamic subtest: vcs0
[784.655005] Dynamic subtest vcs0: SUCCESS (2.018s)
[784.666285] Starting dynamic subtest: vcs1
[786.673569] Dynamic subtest vcs1: SUCCESS (2.017s)
[786.684544] Starting dynamic subtest: vecs0
[788.688200] Dynamic subtest vecs0: SUCCESS (2.016s)
[788.699002] Starting dynamic subtest: ccs0
[790.707852] Dynamic subtest ccs0: SUCCESS (2.019s)
[790.710028] Subtest fairslice: SUCCESS (12.112s)
[791.030005] [125/132] (234s left) gem_exec_reloc (basic-wc-cpu)
[791.102732] Subtest basic-wc-cpu: SKIP (0.000s)
[791.184581] [126/132] (234s left) syncobj_timeline (wait-all-for-submit-complex)
[791.246179] Starting subtest: wait-all-for-submit-complex
[791.459263] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[791.562121] [127/132] (233s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[791.684401] Starting subtest: cursorB-vs-flipB-legacy
[791.688943] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[791.854342] [128/132] (233s left) gem_exec_flush (basic-wb-set-default)
[791.911030] Starting subtest: basic-wb-set-default
[797.361494] Subtest basic-wb-set-default: SUCCESS (5.452s)
[797.575848] [129/132] (227s left) gem_pxp (reject-modify-context-protection-off-2)
[797.704231] Starting subtest: reject-modify-context-protection-off-2
[797.706530] Subtest reject-modify-context-protection-off-2: SUCCESS (0.001s)
[797.855022] [130/132] (227s left) api_intel_bb (misplaced-blitter)
[797.919667] Starting subtest: misplaced-blitter
[797.928139] Subtest misplaced-blitter: SUCCESS (0.010s)
[798.092627] [131/132] (227s left) kms_psr (fbc-pr-dpms)
[798.203875] Starting subtest: fbc-pr-dpms
[798.207946] Subtest fbc-pr-dpms: SKIP (0.000s)
[798.305128] [132/132] (227s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[798.412679] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[798.417144] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[798.549312] 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.