[57.601429] Initializing watchdogs
[57.601574]   /dev/watchdog0
[57.615792] [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.619828] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.622058] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.625352] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[57.907781] Starting subtest: noheartbeat
[57.912168] Subtest noheartbeat: SKIP (0.000s)
[58.039216] [002/132] (960s left) prime_vgem (basic-gtt)
[58.137791] Starting subtest: basic-gtt
[58.142289] Subtest basic-gtt: SKIP (0.000s)
[58.258476] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[58.261581] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[58.375936] Starting subtest: fbc-1p-pri-indfb-multidraw
[71.849277] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.542s)
[73.232553] [004/132] (945s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[73.335759] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[82.590945] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.260s)
[82.726812] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[82.814716] Starting subtest: rmfb-ioctl
[82.817013] Starting dynamic subtest: pipe-A-eDP-1
[82.919359] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[82.928824] Starting dynamic subtest: pipe-B-eDP-1
[84.116583] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.196s)
[84.125884] Starting dynamic subtest: pipe-C-eDP-1
[85.255678] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.138s)
[85.263263] Starting dynamic subtest: pipe-D-eDP-1
[86.378108] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[86.380496] Subtest rmfb-ioctl: SUCCESS (3.564s)
[87.522172] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[87.629243] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[94.131667] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.504s)
[94.291271] [007/132] (924s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[94.668558] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[95.463338] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[95.564206] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[95.566514] Starting dynamic subtest: pipe-A-default-mode
[96.740401] Dynamic subtest pipe-A-default-mode: SKIP (1.171s)
[96.744966] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.173s)
[96.921353] [009/132] (921s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[97.024466] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[97.029077] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[97.166788] [010/132] (921s left) gem_exec_reloc (basic-write-read-noreloc)
[97.235788] Subtest basic-write-read-noreloc: SKIP (0.000s)
[97.321251] [011/132] (921s left) kms_draw_crc (draw-method-blt)
[97.422257] Starting subtest: draw-method-blt
[97.424450] Starting dynamic subtest: xrgb8888-untiled
[97.641667] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[97.649204] Starting dynamic subtest: rgb565-untiled
[97.864069] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[97.874780] Starting dynamic subtest: xrgb2101010-untiled
[98.119511] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.254s)
[98.127479] Starting dynamic subtest: xbgr16161616f-untiled
[98.341686] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[98.348518] Starting dynamic subtest: xrgb8888-xtiled
[98.464079] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[98.471226] Starting dynamic subtest: rgb565-xtiled
[98.564039] Dynamic subtest rgb565-xtiled: SUCCESS (0.100s)
[98.572290] Starting dynamic subtest: xrgb2101010-xtiled
[98.664066] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.100s)
[98.672237] Starting dynamic subtest: xbgr16161616f-xtiled
[98.764390] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.099s)
[98.771009] Starting dynamic subtest: xrgb8888-4tiled
[98.875136] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.110s)
[98.883421] Starting dynamic subtest: rgb565-4tiled
[98.975047] Dynamic subtest rgb565-4tiled: SUCCESS (0.100s)
[98.983069] Starting dynamic subtest: xrgb2101010-4tiled
[99.086594] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[99.098546] Starting dynamic subtest: xbgr16161616f-4tiled
[99.197170] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[99.199121] Subtest draw-method-blt: SUCCESS (1.777s)
[99.377321] [012/132] (919s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[99.486255] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[99.488044] Starting dynamic subtest: pipe-A-eDP-1
[99.506594] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[99.515310] Starting dynamic subtest: pipe-B-eDP-1
[99.531890] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[99.538241] Starting dynamic subtest: pipe-C-eDP-1
[99.555208] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[99.561287] Starting dynamic subtest: pipe-D-eDP-1
[99.578751] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[99.582228] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[101.717874] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[101.852127] Starting subtest: gen12-group-exclusive-stream-sample-oa
[101.872694] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.020s)
[101.916228] [014/132] (916s left) gem_create (hog-create)
[101.974054] Starting subtest: hog-create
[101.976099] Starting dynamic subtest: smem0
[134.966338] Dynamic subtest smem0: SUCCESS (32.991s)
[134.969267] Subtest hog-create: SUCCESS (32.992s)
[135.180274] [015/132] (883s left) kms_cdclk (plane-scaling)
[135.274488] Starting subtest: plane-scaling
[135.276610] Starting dynamic subtest: pipe-A-eDP-1
[135.569102] Dynamic subtest pipe-A-eDP-1: SKIP (0.294s)
[135.577168] Starting dynamic subtest: pipe-B-eDP-1
[135.581417] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[135.588705] Starting dynamic subtest: pipe-C-eDP-1
[135.593149] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[135.600797] Starting dynamic subtest: pipe-D-eDP-1
[135.605063] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[135.608891] Subtest plane-scaling: SKIP (0.300s)
[136.440455] [016/132] (882s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[136.530326] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[136.534404] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[136.650547] [017/132] (882s left) gem_softpin (softpin)
[136.727734] Starting subtest: softpin
[169.021048] Subtest softpin: SUCCESS (32.288s)
[169.181545] [018/132] (849s left) kms_joiner (basic-force-ultra-joiner)
[169.293774] Starting subtest: basic-force-ultra-joiner
[169.298291] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[169.377350] [019/132] (849s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[169.472242] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[169.483771] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.010s)
[169.629330] [020/132] (849s left) syncobj_timeline (reset-multiple-signaled)
[169.701092] Starting subtest: reset-multiple-signaled
[169.703510] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[169.838934] [021/132] (849s left) drm_mm (drm_mm)
[169.873301] Starting subtest: drm_mm
[169.913355] Starting dynamic subtest: drm_test_mm_init
[170.052786] Dynamic subtest drm_test_mm_init: SUCCESS (0.137s)
[170.060043] Starting dynamic subtest: drm_test_mm_debug
[170.197353] Dynamic subtest drm_test_mm_debug: SUCCESS (0.144s)
[170.206677] Starting dynamic subtest: drm_test_mm_align32
[170.342633] Dynamic subtest drm_test_mm_align32: SUCCESS (0.145s)
[170.351363] Starting dynamic subtest: drm_test_mm_align64
[170.493548] Dynamic subtest drm_test_mm_align64: SUCCESS (0.152s)
[170.502397] Starting dynamic subtest: drm_test_mm_lowest
[170.632057] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.138s)
[170.638877] Starting dynamic subtest: drm_test_mm_highest
[170.776122] Dynamic subtest drm_test_mm_highest: SUCCESS (0.142s)
[170.778105] Subtest drm_mm: SUCCESS (0.900s)
[170.994119] [022/132] (847s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[176.003919] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[176.008519] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[176.867203] [023/132] (842s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[176.975325] Starting subtest: plane-ctm3x4-lut1d
[176.990523] Starting dynamic subtest: pipe-A-eDP-1
[177.726962] Dynamic subtest pipe-A-eDP-1: FAIL (0.344s)
[178.432467] Starting dynamic subtest: pipe-B-eDP-1
[179.236223] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[179.763768] Starting dynamic subtest: pipe-C-eDP-1
[180.625468] Dynamic subtest pipe-C-eDP-1: FAIL (0.247s)
[181.104338] Starting dynamic subtest: pipe-D-eDP-1
[181.931388] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[181.933715] Subtest plane-ctm3x4-lut1d: FAIL (4.646s)
[182.514848] [024/132] (836s left) kms_psr (fbc-psr-primary-mmap-gtt)
[182.616493] Starting subtest: fbc-psr-primary-mmap-gtt
[182.618629] Starting dynamic subtest: eDP-1
[182.622886] Dynamic subtest eDP-1: SKIP (0.000s)
[182.627181] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[182.710064] [025/132] (836s left) gem_exec_schedule (u-semaphore-user)
[182.781967] Starting subtest: u-semaphore-user
[182.840182] Subtest u-semaphore-user: SUCCESS (0.059s)
[183.953982] [026/132] (835s left) gem_userptr_blits (unsync-unmap-cycles)
[184.018400] Subtest unsync-unmap-cycles: SKIP (0.000s)
[184.166676] [027/132] (834s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[184.261998] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[184.264150] Starting dynamic subtest: pipe-A-default-mode
[184.550188] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[184.554756] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.286s)
[185.426659] [028/132] (833s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[185.535133] Starting subtest: fbc-rgb565-draw-pwrite
[190.093984] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.563s)
[191.232324] [029/132] (828s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[191.328234] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[198.801994] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.475s)
[199.001964] [030/132] (820s left) kms_cursor_edge_walk (256x256-right-edge)
[199.099538] Starting subtest: 256x256-right-edge
[199.102015] Starting dynamic subtest: pipe-A-eDP-1
[202.608728] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.508s)
[202.617208] Starting dynamic subtest: pipe-D-eDP-1
[205.736196] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[205.738608] Subtest 256x256-right-edge: SUCCESS (6.637s)
[206.559806] [031/132] (812s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[206.665726] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[211.011367] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.350s)
[212.162682] [032/132] (807s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[212.275741] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[212.280034] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[212.423347] [033/132] (806s left) i915_pm_rc6_residency (media-rc6-accuracy)
[212.475058] Starting subtest: media-rc6-accuracy
[212.479164] Subtest media-rc6-accuracy: SKIP (0.000s)
[212.615190] [034/132] (806s left) kms_async_flips (async-flip-hang)
[212.716944] Starting subtest: async-flip-hang
[213.099027] Starting dynamic subtest: pipe-A-eDP-1
[228.405738] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.305s)
[228.792188] Starting dynamic subtest: pipe-B-eDP-1
[244.400866] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.611s)
[244.772145] Starting dynamic subtest: pipe-C-eDP-1
[260.396745] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.629s)
[260.771581] Starting dynamic subtest: pipe-D-eDP-1
[276.395810] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.627s)
[276.398188] Subtest async-flip-hang: SUCCESS (63.677s)
[277.588832] [035/132] (741s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[277.699989] Starting subtest: fbc-rgb101010-draw-render
[282.284252] Subtest fbc-rgb101010-draw-render: SUCCESS (4.588s)
[283.487958] [036/132] (736s left) kms_pm_dc (dc5-psr)
[284.596175] Starting subtest: dc5-psr
[287.475531] Subtest dc5-psr: SUCCESS (2.878s)
[288.305535] [037/132] (731s left) kms_getfb (getfb-handle-valid)
[288.408962] Starting subtest: getfb-handle-valid
[288.411152] Subtest getfb-handle-valid: SUCCESS (0.000s)
[288.548357] [038/132] (731s left) perf (oa-formats)
[288.658713] Starting subtest: oa-formats
[288.661252] Starting dynamic subtest: 0-rcs0
[288.670880] Dynamic subtest 0-rcs0: SUCCESS (0.012s)
[288.680769] Starting dynamic subtest: 1-vecs0
[288.694554] Dynamic subtest 1-vecs0: SUCCESS (0.023s)
[288.696708] Subtest oa-formats: SUCCESS (0.037s)
[288.733040] [039/132] (730s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[288.793076] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[288.797343] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[289.001583] [040/132] (730s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[289.109106] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[289.113430] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[289.226763] [041/132] (730s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[289.328316] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[289.330413] Starting dynamic subtest: pipe-A-eDP-1
[306.404557] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.041s)
[306.413936] Starting dynamic subtest: pipe-B-eDP-1
[323.368085] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.499s)
[323.377369] Starting dynamic subtest: pipe-C-eDP-1
[340.320388] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.415s)
[340.329542] Starting dynamic subtest: pipe-D-eDP-1
[357.319073] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.384s)
[357.321351] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (12.344s)
[358.553618] [042/132] (661s left) gem_exec_fence (keep-in-fence)
[358.745303] Starting subtest: keep-in-fence
[358.747065] Starting dynamic subtest: rcs0
[358.812920] Dynamic subtest rcs0: SUCCESS (0.071s)
[358.826251] Starting dynamic subtest: bcs0
[358.867795] Dynamic subtest bcs0: SUCCESS (0.053s)
[358.877031] Starting dynamic subtest: vcs0
[360.833600] Dynamic subtest vcs0: SUCCESS (2.027s)
[360.845346] Starting dynamic subtest: vcs1
[362.817656] Dynamic subtest vcs1: SUCCESS (2.032s)
[362.829536] Starting dynamic subtest: vecs0
[364.864624] Dynamic subtest vecs0: SUCCESS (2.084s)
[364.876225] Starting dynamic subtest: ccs0
[365.008575] Dynamic subtest ccs0: SUCCESS (0.144s)
[365.011450] Subtest keep-in-fence: SUCCESS (6.428s)
[366.968271] [043/132] (652s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[367.084780] Starting subtest: psr-rgb565-draw-pwrite
[373.927084] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.902s)
[374.063825] [044/132] (645s left) perf (per-context-mode-unprivileged)
[374.169818] Starting subtest: per-context-mode-unprivileged
[374.173910] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[374.221188] [045/132] (645s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[374.323472] Starting subtest: x-tiled-8bpp-rotate-0
[375.487641] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.169s)
[375.643843] [046/132] (644s left) gem_lmem_swapping (parallel-random-verify-ccs)
[375.684068] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[375.726615] [047/132] (644s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[375.832859] Starting subtest: 4-tiled-64bpp-rotate-180
[377.429615] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.601s)
[377.618884] [048/132] (642s left) gem_exec_schedule (preempt)
[377.703022] Starting subtest: preempt
[377.704630] Starting dynamic subtest: rcs0
[377.722646] Dynamic subtest rcs0: SUCCESS (0.024s)
[377.731250] Starting dynamic subtest: bcs0
[377.743860] Dynamic subtest bcs0: SUCCESS (0.021s)
[377.751914] Starting dynamic subtest: vcs0
[377.763613] Dynamic subtest vcs0: SUCCESS (0.019s)
[377.772078] Starting dynamic subtest: vcs1
[377.784965] Dynamic subtest vcs1: SUCCESS (0.021s)
[377.793081] Starting dynamic subtest: vecs0
[377.804472] Dynamic subtest vecs0: SUCCESS (0.019s)
[377.812859] Starting dynamic subtest: ccs0
[377.826432] Dynamic subtest ccs0: SUCCESS (0.021s)
[377.828212] Subtest preempt: SUCCESS (0.130s)
[378.935050] [049/132] (641s left) kms_flip (2x-wf_vblank-ts-check)
[379.042002] Starting subtest: 2x-wf_vblank-ts-check
[379.054403] Subtest 2x-wf_vblank-ts-check: SKIP (0.014s)
[379.158077] [050/132] (640s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[379.252345] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[379.254651] Starting dynamic subtest: pipe-A-eDP-1
[379.275050] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[379.284487] Starting dynamic subtest: pipe-B-eDP-1
[379.302851] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[379.311755] Starting dynamic subtest: pipe-C-eDP-1
[379.331379] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[379.341362] Starting dynamic subtest: pipe-D-eDP-1
[379.360700] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[379.362629] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[381.548496] [051/132] (638s left) sw_sync (alloc_timeline)
[381.589228] Starting subtest: alloc_timeline
[381.591146] Subtest alloc_timeline: SUCCESS (0.000s)
[381.666951] [052/132] (638s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[381.736427] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[381.738345] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.001s)
[381.849434] [053/132] (638s left) kms_atomic_transition (modeset-transition-nonblocking)
[381.953577] Starting subtest: modeset-transition-nonblocking
[381.955856] Starting dynamic subtest: 1x-outputs
[393.008377] Dynamic subtest 1x-outputs: SUCCESS (11.056s)
[393.010780] Subtest modeset-transition-nonblocking: SUCCESS (11.057s)
[393.884791] [054/132] (626s left) kms_pm_rpm (cursor)
[396.016756] Starting subtest: cursor
[396.021444] Subtest cursor: SKIP (0.000s)
[396.418439] [055/132] (623s left) kms_selftest (drm_damage_helper)
[396.460875] Starting subtest: drm_damage_helper
[396.494007] Starting dynamic subtest: drm_test_damage_iter_no_damage
[396.609775] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.114s)
[396.620357] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[396.764935] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.152s)
[396.774760] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[396.881116] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.116s)
[396.890771] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[396.973767] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.092s)
[396.983949] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[397.066441] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.093s)
[397.077446] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[397.196949] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.127s)
[397.207497] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[397.325493] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.127s)
[397.335732] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[397.460584] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.135s)
[397.470894] Starting dynamic subtest: drm_test_damage_iter_single_damage
[397.613570] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.151s)
[397.622025] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[397.781333] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.167s)
[397.790114] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[397.912565] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.130s)
[397.921595] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[398.026060] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.114s)
[398.034507] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[398.173115] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.144s)
[398.192049] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[398.323770] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.149s)
[398.341943] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[398.457680] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.133s)
[398.476522] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[398.567706] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.109s)
[398.586364] Starting dynamic subtest: drm_test_damage_iter_damage
[398.704746] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.133s)
[398.722128] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[398.837560] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.132s)
[398.855792] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[398.998347] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.159s)
[399.008382] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[399.147740] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.149s)
[399.157766] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[399.277568] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.129s)
[399.279460] Subtest drm_damage_helper: SUCCESS (2.812s)
[399.497053] [056/132] (620s left) gem_userptr_blits (unsync-overlap)
[399.566451] Subtest unsync-overlap: SKIP (0.000s)
[399.736534] [057/132] (620s left) kms_psr (pr-sprite-mmap-gtt)
[399.834509] Starting subtest: pr-sprite-mmap-gtt
[399.838837] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[399.933203] [058/132] (620s left) kms_psr (psr-sprite-mmap-gtt)
[400.021809] Starting subtest: psr-sprite-mmap-gtt
[400.024002] Starting dynamic subtest: eDP-1
[401.506554] Dynamic subtest eDP-1: SKIP (1.481s)
[401.511489] Subtest psr-sprite-mmap-gtt: SKIP (1.483s)
[401.723924] [059/132] (618s left) gem_exec_parallel (basic)
[401.791499] Starting subtest: basic
[401.793898] Starting dynamic subtest: rcs0
[403.211015] Dynamic subtest rcs0: SUCCESS (1.418s)
[403.223054] Starting dynamic subtest: bcs0
[404.677812] Dynamic subtest bcs0: SUCCESS (1.464s)
[404.687165] Starting dynamic subtest: vcs0
[406.849263] Dynamic subtest vcs0: SUCCESS (2.167s)
[406.860770] Starting dynamic subtest: vcs1
[408.834381] Dynamic subtest vcs1: SUCCESS (1.982s)
[408.844346] Starting dynamic subtest: vecs0
[410.818324] Dynamic subtest vecs0: SUCCESS (1.980s)
[410.830368] Starting dynamic subtest: ccs0
[412.306422] Dynamic subtest ccs0: SUCCESS (1.487s)
[412.308782] Subtest basic: SUCCESS (10.512s)
[412.517535] [060/132] (607s left) kms_content_protection (content-type-change)
[412.666867] Starting subtest: content-type-change
[412.671589] Subtest content-type-change: SKIP (0.000s)
[412.833266] [061/132] (607s left) gem_mmap_wc (write-read-distinct)
[412.890503] Subtest write-read-distinct: SKIP (0.000s)
[412.955478] [062/132] (607s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[413.058241] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[413.826719] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.770s)
[414.030953] [063/132] (606s left) gem_ctx_persistence (engines-mixed-process)
[414.092113] Starting subtest: engines-mixed-process
[414.094355] Starting dynamic subtest: rcs0
[415.791936] Dynamic subtest rcs0: SUCCESS (1.698s)
[415.800496] Starting dynamic subtest: bcs0
[417.876477] Dynamic subtest bcs0: SUCCESS (2.085s)
[417.888464] Starting dynamic subtest: vcs0
[419.867618] Dynamic subtest vcs0: SUCCESS (1.990s)
[419.879080] Starting dynamic subtest: vcs1
[421.852294] Dynamic subtest vcs1: SUCCESS (1.982s)
[421.864399] Starting dynamic subtest: vecs0
[423.845184] Dynamic subtest vecs0: SUCCESS (1.991s)
[423.856979] Starting dynamic subtest: ccs0
[425.839607] Dynamic subtest ccs0: SUCCESS (1.991s)
[425.841550] Subtest engines-mixed-process: SUCCESS (11.746s)
[425.963275] [064/132] (594s left) kms_chamelium_edid (vga-edid-read)
[426.380996] Subtest vga-edid-read: SKIP (0.000s)
[427.219980] [065/132] (593s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[427.342722] Starting subtest: 2x-long-flip-vs-cursor-atomic
[427.347354] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[427.511534] [066/132] (593s left) gem_exec_create (legacy)
[427.575816] Starting subtest: legacy
[427.577766] Starting dynamic subtest: smem
[429.662049] Dynamic subtest smem: SUCCESS (2.089s)
[429.664510] Subtest legacy: SUCCESS (2.091s)
[429.871187] [067/132] (590s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[429.979087] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[429.983274] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[430.113261] [068/132] (590s left) gem_softpin (zero)
[430.181064] Starting subtest: zero
[430.185155] Subtest zero: SUCCESS (0.004s)
[430.353147] [069/132] (590s left) gem_exec_flush (basic-wb-ro-before-default)
[430.416546] Starting subtest: basic-wb-ro-before-default
[435.864553] Subtest basic-wb-ro-before-default: SUCCESS (5.449s)
[436.079662] [070/132] (584s left) kms_big_fb (linear-64bpp-rotate-90)
[436.187671] Starting subtest: linear-64bpp-rotate-90
[436.207847] Subtest linear-64bpp-rotate-90: SKIP (0.020s)
[436.358891] [071/132] (584s left) gem_userptr_blits (set-cache-level)
[436.421469] Starting subtest: set-cache-level
[436.425662] Subtest set-cache-level: SKIP (0.000s)
[436.583072] [072/132] (584s left) perf_pmu (busy)
[436.630142] Starting subtest: busy
[436.632598] Starting dynamic subtest: rcs0
[437.242935] Dynamic subtest rcs0: SUCCESS (0.616s)
[437.254739] Starting dynamic subtest: bcs0
[437.824144] Dynamic subtest bcs0: SUCCESS (0.579s)
[437.835312] Starting dynamic subtest: vcs0
[439.809782] Dynamic subtest vcs0: SUCCESS (1.982s)
[439.823860] Starting dynamic subtest: vcs1
[441.793900] Dynamic subtest vcs1: SUCCESS (1.981s)
[441.806664] Starting dynamic subtest: vecs0
[443.842069] Dynamic subtest vecs0: SUCCESS (2.046s)
[443.855930] Starting dynamic subtest: ccs0
[444.407736] Dynamic subtest ccs0: SUCCESS (0.565s)
[444.410166] Subtest busy: SUCCESS (7.783s)
[444.546294] [073/132] (576s left) gem_basic (multigpu-create-close)
[444.621900] Starting subtest: multigpu-create-close
[444.626593] Subtest multigpu-create-close: SKIP (0.000s)
[444.768116] [074/132] (576s left) gen9_exec_parse (bb-secure)
[444.838238] Subtest bb-secure: SKIP (0.000s)
[444.913944] [075/132] (576s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[444.968800] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[444.970988] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[445.112118] [076/132] (576s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[450.140486] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[450.144844] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[451.000246] [077/132] (570s left) kms_lease (lease-invalid-crtc)
[451.103741] Starting subtest: lease-invalid-crtc
[451.105999] Starting dynamic subtest: pipe-A-eDP-1
[451.108232] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[451.118257] Starting dynamic subtest: pipe-B-eDP-1
[451.120603] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[451.130099] Starting dynamic subtest: pipe-C-eDP-1
[451.132015] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[451.141049] Starting dynamic subtest: pipe-D-eDP-1
[451.142997] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[451.144930] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[451.200076] [078/132] (570s left) gem_pwrite_snooped
[451.358653] [079/132] (569s left) gem_exec_reloc (basic-write-wc-noreloc)
[451.431945] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[451.526891] [080/132] (569s left) kms_flip (wf_vblank-ts-check-interruptible)
[451.622972] Starting subtest: wf_vblank-ts-check-interruptible
[451.625130] Starting dynamic subtest: A-eDP1
[460.520040] Dynamic subtest A-eDP1: SUCCESS (8.894s)
[460.531525] Starting dynamic subtest: B-eDP1
[469.455886] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[469.472962] Starting dynamic subtest: C-eDP1
[478.379375] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[478.390438] Starting dynamic subtest: D-eDP1
[487.303771] Dynamic subtest D-eDP1: SUCCESS (8.923s)
[487.306064] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.679s)
[488.544721] [081/132] (532s left) gem_ctx_persistence (legacy-engines-queued)
[488.615251] Starting subtest: legacy-engines-queued
[488.617552] Starting dynamic subtest: render
[488.704506] Dynamic subtest render: SUCCESS (0.091s)
[488.716754] Starting dynamic subtest: bsd1
[489.758218] Dynamic subtest bsd1: SUCCESS (1.050s)
[489.771312] Starting dynamic subtest: bsd2
[491.807291] Dynamic subtest bsd2: SUCCESS (2.045s)
[491.820415] Starting dynamic subtest: blt
[491.844074] Dynamic subtest blt: SUCCESS (0.036s)
[491.853222] Starting dynamic subtest: vebox
[493.792160] Dynamic subtest vebox: SUCCESS (1.945s)
[493.794920] Subtest legacy-engines-queued: SUCCESS (5.180s)
[493.896145] [082/132] (527s left) gem_exercise_blt (fast-copy)
[493.954053] Starting subtest: fast-copy
[493.956492] Starting dynamic subtest: linear-smem-smem
[493.983736] Dynamic subtest linear-smem-smem: SUCCESS (0.029s)
[493.996556] Starting dynamic subtest: xmajor-smem-smem
[494.002828] Dynamic subtest xmajor-smem-smem: SUCCESS (0.018s)
[494.012318] Starting dynamic subtest: tile4-smem-smem
[494.023520] Dynamic subtest tile4-smem-smem: SUCCESS (0.020s)
[494.032729] Starting dynamic subtest: tile64-smem-smem
[494.043634] Dynamic subtest tile64-smem-smem: SUCCESS (0.020s)
[494.046209] Subtest fast-copy: SUCCESS (0.090s)
[494.193711] [083/132] (527s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[494.304805] Starting subtest: bo-too-small-due-to-tiling
[494.309301] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[494.451073] [084/132] (527s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[494.509352] Starting subtest: multi-wait-all-for-submit-signaled
[494.511735] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[494.630009] [085/132] (526s left) kms_cursor_crc (cursor-random-512x512)
[494.922182] Starting subtest: cursor-random-512x512
[494.926090] Subtest cursor-random-512x512: SKIP (0.001s)
[495.124514] [086/132] (526s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[495.188632] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[495.249747] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.005s)
[495.333883] [087/132] (526s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[495.426213] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[495.428202] Starting dynamic subtest: pipe-A-default-mode
[495.719626] Dynamic subtest pipe-A-default-mode: SKIP (0.291s)
[495.724011] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.293s)
[496.575487] [088/132] (525s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[496.664829] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[496.667163] Starting dynamic subtest: pipe-A-eDP-1
[496.691405] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[496.711600] Starting dynamic subtest: pipe-B-eDP-1
[496.735924] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[496.748625] Starting dynamic subtest: pipe-C-eDP-1
[496.770746] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[496.780339] Starting dynamic subtest: pipe-D-eDP-1
[496.803191] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[496.807393] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[498.950761] [089/132] (522s left) kms_plane_multiple (2x-tiling-none)
[499.052898] Starting subtest: 2x-tiling-none
[499.057388] Subtest 2x-tiling-none: SKIP (0.000s)
[499.155912] [090/132] (522s left) kms_psr (fbc-psr2-sprite-blt)
[499.257954] Starting subtest: fbc-psr2-sprite-blt
[499.260165] Starting dynamic subtest: eDP-1
[499.264187] Dynamic subtest eDP-1: SKIP (0.000s)
[499.269586] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[499.360872] [091/132] (522s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[499.477234] Starting subtest: y-tiled-16bpp-rotate-270
[499.481353] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[499.598113] [092/132] (522s left) i915_query (engine-info-invalid)
[499.647731] Starting subtest: engine-info-invalid
[499.649989] Subtest engine-info-invalid: SUCCESS (0.000s)
[499.790146] [093/132] (522s left) gem_ctx_param (invalid-size-set)
[499.835661] Starting subtest: invalid-size-set
[499.838197] Subtest invalid-size-set: SUCCESS (0.001s)
[499.969783] [094/132] (521s left) kms_fbcon_fbt (psr-suspend)
[500.024533] Starting subtest: psr-suspend
[550.184677] Subtest psr-suspend: SUCCESS (20.095s)
[550.280017] [095/132] (471s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[550.343027] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[550.447161] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[550.553127] [096/132] (471s left) kms_pm_rpm (universal-planes)
[552.786648] Starting subtest: universal-planes
[552.788763] Starting dynamic subtest: plane-33
[561.082705] Dynamic subtest plane-33: SUCCESS (8.387s)
[561.096629] Starting dynamic subtest: plane-63
[569.336366] Dynamic subtest plane-63: SUCCESS (8.282s)
[569.349573] Starting dynamic subtest: plane-93
[578.036806] Dynamic subtest plane-93: SUCCESS (8.705s)
[578.049501] Starting dynamic subtest: plane-123
[586.647594] Dynamic subtest plane-123: SUCCESS (8.604s)
[586.660208] Starting dynamic subtest: plane-133
[595.248736] Dynamic subtest plane-133: SUCCESS (8.596s)
[595.262338] Starting dynamic subtest: plane-143
[603.870776] Dynamic subtest plane-143: SUCCESS (8.620s)
[603.873314] Subtest universal-planes: SUCCESS (51.213s)
[604.273964] [097/132] (417s left) gem_exec_reloc (basic-write-read)
[604.335419] Subtest basic-write-read: SKIP (0.000s)
[604.400753] [098/132] (417s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[604.497727] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[604.501817] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[604.639157] [099/132] (417s left) kms_cursor_crc (cursor-onscreen-32x32)
[604.794580] Starting subtest: cursor-onscreen-32x32
[604.836532] Subtest cursor-onscreen-32x32: SKIP (0.040s)
[605.157733] [100/132] (417s left) syncobj_wait (invalid-wait-bad-flags)
[605.215413] Starting subtest: invalid-wait-bad-flags
[605.217366] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[605.344135] [101/132] (416s left) gem_exec_whisper (basic-contexts-forked-all)
[605.413802] Starting subtest: basic-contexts-forked-all
[626.288359] Subtest basic-contexts-forked-all: SUCCESS (20.874s)
[626.499540] [102/132] (395s left) gem_fenced_exec_thrash (2-spare-fences)
[626.556570] Subtest 2-spare-fences: SKIP (0.000s)
[626.648314] [103/132] (395s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[626.750944] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[626.755152] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[626.870455] [104/132] (395s left) api_intel_bb (add-remove-objects)
[626.918236] Starting subtest: add-remove-objects
[626.925558] Subtest add-remove-objects: SUCCESS (0.009s)
[627.088216] [105/132] (395s left) kms_vblank (invalid)
[627.192257] Starting subtest: invalid
[627.194532] Starting dynamic subtest: pipe-A-eDP-1
[627.611602] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.416s)
[627.613992] Subtest invalid: SUCCESS (0.418s)
[628.482982] [106/132] (393s left) gem_mmap_wc (bad-object)
[628.541798] Subtest bad-object: SKIP (0.000s)
[628.594138] [107/132] (393s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[628.698299] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[628.702526] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[628.844499] [108/132] (393s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[628.913032] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[629.003351] [109/132] (393s left) gem_tiled_fence_blits (normal)
[629.068908] Subtest normal: SKIP (0.000s)
[629.153201] [110/132] (393s left) gem_exec_alignment (pi)
[629.211900] Starting subtest: pi
[681.349938] Subtest pi: SUCCESS (52.130s)
[682.197944] [111/132] (340s left) gem_mmap (bad-object)
[682.297026] Subtest bad-object: SKIP (0.000s)
[682.364701] [112/132] (340s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[682.489400] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[682.491674] Starting dynamic subtest: pipe-A
[682.735918] Dynamic subtest pipe-A: SUCCESS (0.248s)
[682.752287] Starting dynamic subtest: pipe-B
[684.045451] Dynamic subtest pipe-B: SUCCESS (1.308s)
[684.058504] Starting dynamic subtest: pipe-C
[685.400420] Dynamic subtest pipe-C: SUCCESS (1.353s)
[685.411915] Starting dynamic subtest: pipe-D
[686.710037] Dynamic subtest pipe-D: SUCCESS (1.309s)
[686.712406] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.224s)
[687.905984] [113/132] (334s left) i915_hangman (engine-error-state-capture)
[687.970919] Starting subtest: engine-error-state-capture
[687.973122] Starting dynamic subtest: rcs0
[691.251144] Dynamic subtest rcs0: SUCCESS (3.280s)
[691.265069] Starting dynamic subtest: bcs0
[697.267894] Dynamic subtest bcs0: SUCCESS (6.015s)
[697.284698] Starting dynamic subtest: vcs0
[703.219723] Dynamic subtest vcs0: SUCCESS (5.949s)
[703.232518] Starting dynamic subtest: vcs1
[708.208777] Dynamic subtest vcs1: SUCCESS (4.987s)
[708.221622] Starting dynamic subtest: vecs0
[713.202129] Dynamic subtest vecs0: SUCCESS (4.991s)
[713.215248] Starting dynamic subtest: ccs0
[718.258386] Dynamic subtest ccs0: SUCCESS (5.054s)
[718.261240] Subtest engine-error-state-capture: SUCCESS (30.293s)
[718.374077] [114/132] (304s left) perf (low-oa-exponent-permissions)
[718.516636] Starting subtest: low-oa-exponent-permissions
[718.552207] Subtest low-oa-exponent-permissions: SUCCESS (0.036s)
[718.613803] [115/132] (304s left) gem_softpin (noreloc)
[718.670917] Starting subtest: noreloc
[724.072313] Subtest noreloc: SUCCESS (5.401s)
[724.243124] [116/132] (298s left) perf (enable-disable)
[724.346984] Starting subtest: enable-disable
[724.349246] Starting dynamic subtest: 0-rcs0
[726.628890] Dynamic subtest 0-rcs0: SUCCESS (2.280s)
[726.639923] Starting dynamic subtest: 1-vcs0
[731.093752] Dynamic subtest 1-vcs0: SUCCESS (4.464s)
[731.096350] Subtest enable-disable: SUCCESS (6.746s)
[731.142176] [117/132] (291s left) kms_pipe_crc_basic (nonblocking-crc)
[731.236380] Starting subtest: nonblocking-crc
[731.238688] Starting dynamic subtest: pipe-A-eDP-1
[731.975747] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.740s)
[731.987222] Starting dynamic subtest: pipe-B-eDP-1
[733.372429] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.395s)
[733.383993] Starting dynamic subtest: pipe-C-eDP-1
[734.744130] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.370s)
[734.755010] Starting dynamic subtest: pipe-D-eDP-1
[736.078132] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s)
[736.080403] Subtest nonblocking-crc: SUCCESS (4.844s)
[736.896022] [118/132] (286s left) sysfs_preempt_timeout (timeout)
[736.963110] Starting subtest: timeout
[736.964922] Starting dynamic subtest: vecs0
[746.356537] Dynamic subtest vecs0: SUCCESS (9.393s)
[746.369374] Starting dynamic subtest: ccs0
[747.741392] Dynamic subtest ccs0: SUCCESS (1.385s)
[747.754586] Starting dynamic subtest: vcs0
[757.355582] Dynamic subtest vcs0: SUCCESS (9.613s)
[757.371260] Starting dynamic subtest: bcs0
[758.716836] Dynamic subtest bcs0: SUCCESS (1.360s)
[758.730718] Starting dynamic subtest: rcs0
[759.985274] Dynamic subtest rcs0: SUCCESS (1.268s)
[760.001141] Starting dynamic subtest: vcs1
[769.317185] Dynamic subtest vcs1: SUCCESS (9.327s)
[769.319966] Subtest timeout: SUCCESS (32.360s)
[769.416601] [119/132] (253s left) gem_mmap_gtt (hang-busy)
[769.486201] Subtest hang-busy: SKIP (0.000s)
[769.554828] [120/132] (253s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[769.657767] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[769.661876] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[769.803875] [121/132] (253s left) i915_drm_fdinfo (all-busy-idle-check-all)
[769.856155] Starting subtest: all-busy-idle-check-all
[769.862948] Subtest all-busy-idle-check-all: SKIP (0.005s)
[769.971799] [122/132] (253s left) perf_pmu (init-sema)
[770.017978] Starting subtest: init-sema
[770.020249] Starting dynamic subtest: rcs0
[770.022429] Dynamic subtest rcs0: SUCCESS (0.001s)
[770.032946] Starting dynamic subtest: bcs0
[770.035040] Dynamic subtest bcs0: SUCCESS (0.000s)
[770.044820] Starting dynamic subtest: vcs0
[770.046628] Dynamic subtest vcs0: SUCCESS (0.000s)
[770.055853] Starting dynamic subtest: vcs1
[770.057696] Dynamic subtest vcs1: SUCCESS (0.000s)
[770.067307] Starting dynamic subtest: vecs0
[770.069315] Dynamic subtest vecs0: SUCCESS (0.000s)
[770.080782] Starting dynamic subtest: ccs0
[770.082607] Dynamic subtest ccs0: SUCCESS (0.000s)
[770.084445] Subtest init-sema: SUCCESS (0.007s)
[770.173971] [123/132] (253s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[770.272767] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[777.305301] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.030s)
[777.478881] [124/132] (245s left) gem_exec_schedule (fairslice)
[777.572024] Starting subtest: fairslice
[777.573993] Starting dynamic subtest: rcs0
[779.589528] Dynamic subtest rcs0: SUCCESS (2.020s)
[779.602021] Starting dynamic subtest: bcs0
[781.609005] Dynamic subtest bcs0: SUCCESS (2.017s)
[781.620643] Starting dynamic subtest: vcs0
[783.629699] Dynamic subtest vcs0: SUCCESS (2.019s)
[783.641440] Starting dynamic subtest: vcs1
[785.647084] Dynamic subtest vcs1: SUCCESS (2.017s)
[785.659489] Starting dynamic subtest: vecs0
[787.665878] Dynamic subtest vecs0: SUCCESS (2.015s)
[787.677797] Starting dynamic subtest: ccs0
[789.684855] Dynamic subtest ccs0: SUCCESS (2.019s)
[789.686797] Subtest fairslice: SUCCESS (12.111s)
[790.004032] [125/132] (233s left) gem_exec_reloc (basic-wc-cpu)
[790.087654] Subtest basic-wc-cpu: SKIP (0.000s)
[790.178617] [126/132] (233s left) syncobj_timeline (wait-all-for-submit-complex)
[790.240991] Starting subtest: wait-all-for-submit-complex
[790.454810] Subtest wait-all-for-submit-complex: SUCCESS (0.214s)
[790.560851] [127/132] (232s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[790.685097] Starting subtest: cursorB-vs-flipB-legacy
[790.689799] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[790.873913] [128/132] (232s left) gem_exec_flush (basic-wb-set-default)
[790.950384] Starting subtest: basic-wb-set-default
[796.413777] Subtest basic-wb-set-default: SUCCESS (5.463s)
[796.629146] [129/132] (226s left) gem_pxp (reject-modify-context-protection-off-2)
[796.754574] Starting subtest: reject-modify-context-protection-off-2
[796.756851] Subtest reject-modify-context-protection-off-2: SUCCESS (0.001s)
[796.899637] [130/132] (226s left) api_intel_bb (misplaced-blitter)
[796.964659] Starting subtest: misplaced-blitter
[796.974589] Subtest misplaced-blitter: SUCCESS (0.012s)
[797.139585] [131/132] (226s left) kms_psr (fbc-pr-dpms)
[797.244101] Starting subtest: fbc-pr-dpms
[797.248424] Subtest fbc-pr-dpms: SKIP (0.000s)
[797.343136] [132/132] (226s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[797.453583] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[797.457405] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[797.587309] 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.