[57.066007] Initializing watchdogs
[57.066056]   /dev/watchdog0
[57.080772] [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.085010] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.087187] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.090702] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[57.378600] Starting subtest: noheartbeat
[57.383437] Subtest noheartbeat: SKIP (0.000s)
[57.473502] [002/132] (960s left) prime_vgem (basic-gtt)
[57.583956] Starting subtest: basic-gtt
[57.588635] Subtest basic-gtt: SKIP (0.000s)
[57.699755] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[57.702620] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[57.805773] Starting subtest: fbc-1p-pri-indfb-multidraw
[71.206309] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.406s)
[72.574127] [004/132] (945s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[72.678571] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[82.379866] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.702s)
[82.505760] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[82.604462] Starting subtest: rmfb-ioctl
[82.606935] Starting dynamic subtest: pipe-A-eDP-1
[82.710868] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[82.719025] Starting dynamic subtest: pipe-B-eDP-1
[83.871357] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.158s)
[83.881118] Starting dynamic subtest: pipe-C-eDP-1
[84.995864] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.123s)
[85.005842] Starting dynamic subtest: pipe-D-eDP-1
[86.356078] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.358s)
[86.358538] Subtest rmfb-ioctl: SUCCESS (3.753s)
[87.592922] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[87.709343] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[94.287801] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.582s)
[94.436300] [007/132] (923s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[94.818590] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[95.618597] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[95.725940] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[95.728229] Starting dynamic subtest: pipe-A-default-mode
[96.900595] Dynamic subtest pipe-A-default-mode: SKIP (1.173s)
[96.904947] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.174s)
[97.090795] [009/132] (920s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[97.187341] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[97.191532] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[97.326246] [010/132] (920s left) gem_exec_reloc (basic-write-read-noreloc)
[97.395157] Subtest basic-write-read-noreloc: SKIP (0.000s)
[97.470214] [011/132] (920s left) kms_draw_crc (draw-method-blt)
[97.562216] Starting subtest: draw-method-blt
[97.564124] Starting dynamic subtest: xrgb8888-untiled
[97.805341] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[97.814911] Starting dynamic subtest: rgb565-untiled
[98.038034] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[98.045498] Starting dynamic subtest: xrgb2101010-untiled
[98.261018] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[98.268605] Starting dynamic subtest: xbgr16161616f-untiled
[98.482301] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[98.489671] Starting dynamic subtest: xrgb8888-xtiled
[98.593720] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.110s)
[98.603012] Starting dynamic subtest: rgb565-xtiled
[98.715788] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[98.724169] Starting dynamic subtest: xrgb2101010-xtiled
[98.827133] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[98.835728] Starting dynamic subtest: xbgr16161616f-xtiled
[98.938084] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[98.945659] Starting dynamic subtest: xrgb8888-4tiled
[99.049454] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.111s)
[99.059399] Starting dynamic subtest: rgb565-4tiled
[99.171313] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s)
[99.180071] Starting dynamic subtest: xrgb2101010-4tiled
[99.282563] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[99.291073] Starting dynamic subtest: xbgr16161616f-4tiled
[99.393609] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[99.395523] Subtest draw-method-blt: SUCCESS (1.833s)
[99.544808] [012/132] (918s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[99.646076] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[99.647822] Starting dynamic subtest: pipe-A-eDP-1
[99.666067] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[99.674856] Starting dynamic subtest: pipe-B-eDP-1
[99.692433] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[99.699495] Starting dynamic subtest: pipe-C-eDP-1
[99.716745] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[99.723519] Starting dynamic subtest: pipe-D-eDP-1
[99.741216] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[99.744468] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[101.962203] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[102.108677] Starting subtest: gen12-group-exclusive-stream-sample-oa
[102.131423] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.022s)
[102.179303] [014/132] (915s left) gem_create (hog-create)
[102.232390] Starting subtest: hog-create
[102.234673] Starting dynamic subtest: smem0
[135.926545] Dynamic subtest smem0: SUCCESS (33.693s)
[135.929072] Subtest hog-create: SUCCESS (33.694s)
[136.142997] [015/132] (882s left) kms_cdclk (plane-scaling)
[136.248288] Starting subtest: plane-scaling
[136.250636] Starting dynamic subtest: pipe-A-eDP-1
[136.539380] Dynamic subtest pipe-A-eDP-1: SKIP (0.289s)
[136.548804] Starting dynamic subtest: pipe-B-eDP-1
[136.553438] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[136.561128] Starting dynamic subtest: pipe-C-eDP-1
[136.565826] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[136.573468] Starting dynamic subtest: pipe-D-eDP-1
[136.578041] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[136.582486] Subtest plane-scaling: SKIP (0.295s)
[137.387400] [016/132] (880s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[137.486892] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[137.491257] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[137.616389] [017/132] (880s left) gem_softpin (softpin)
[137.673614] Starting subtest: softpin
[169.931732] Subtest softpin: SUCCESS (32.252s)
[170.094606] [018/132] (848s left) kms_joiner (basic-force-ultra-joiner)
[170.194916] Starting subtest: basic-force-ultra-joiner
[170.199120] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[170.281634] [019/132] (848s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[170.377054] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[170.389186] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[170.543843] [020/132] (847s left) syncobj_timeline (reset-multiple-signaled)
[170.608972] Starting subtest: reset-multiple-signaled
[170.611196] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[170.742583] [021/132] (847s left) drm_mm (drm_mm)
[170.779600] Starting subtest: drm_mm
[170.825777] Starting dynamic subtest: drm_test_mm_init
[170.980710] Dynamic subtest drm_test_mm_init: SUCCESS (0.152s)
[170.988166] Starting dynamic subtest: drm_test_mm_debug
[171.094464] Dynamic subtest drm_test_mm_debug: SUCCESS (0.114s)
[171.102914] Starting dynamic subtest: drm_test_mm_align32
[171.226516] Dynamic subtest drm_test_mm_align32: SUCCESS (0.131s)
[171.235789] Starting dynamic subtest: drm_test_mm_align64
[171.365111] Dynamic subtest drm_test_mm_align64: SUCCESS (0.139s)
[171.372426] Starting dynamic subtest: drm_test_mm_lowest
[171.475389] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.109s)
[171.483133] Starting dynamic subtest: drm_test_mm_highest
[171.591791] Dynamic subtest drm_test_mm_highest: SUCCESS (0.114s)
[171.594091] Subtest drm_mm: SUCCESS (0.810s)
[171.776205] [022/132] (846s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[176.845036] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[176.852178] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.006s)
[177.711865] [023/132] (840s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[177.809197] Starting subtest: plane-ctm3x4-lut1d
[177.822435] Starting dynamic subtest: pipe-A-eDP-1
[178.545948] Dynamic subtest pipe-A-eDP-1: FAIL (0.336s)
[179.253548] Starting dynamic subtest: pipe-B-eDP-1
[180.086963] Dynamic subtest pipe-B-eDP-1: FAIL (0.314s)
[180.685139] Starting dynamic subtest: pipe-C-eDP-1
[181.512481] Dynamic subtest pipe-C-eDP-1: FAIL (0.236s)
[182.019462] Starting dynamic subtest: pipe-D-eDP-1
[182.874314] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[182.876336] Subtest plane-ctm3x4-lut1d: FAIL (4.785s)
[183.430384] [024/132] (835s left) kms_psr (fbc-psr-primary-mmap-gtt)
[183.536823] Starting subtest: fbc-psr-primary-mmap-gtt
[183.539166] Starting dynamic subtest: eDP-1
[183.543483] Dynamic subtest eDP-1: SKIP (0.000s)
[183.547671] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[183.632126] [025/132] (834s left) gem_exec_schedule (u-semaphore-user)
[183.711593] Starting subtest: u-semaphore-user
[183.773030] Subtest u-semaphore-user: SUCCESS (0.067s)
[184.926666] [026/132] (833s left) gem_userptr_blits (unsync-unmap-cycles)
[184.990734] Subtest unsync-unmap-cycles: SKIP (0.000s)
[185.148187] [027/132] (833s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[185.237694] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[185.240131] Starting dynamic subtest: pipe-A-default-mode
[185.528655] Dynamic subtest pipe-A-default-mode: SKIP (0.290s)
[185.533371] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.291s)
[186.357050] [028/132] (832s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[186.466735] Starting subtest: fbc-rgb565-draw-pwrite
[190.985611] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.524s)
[192.154084] [029/132] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[192.265023] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[199.858459] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.596s)
[200.027767] [030/132] (818s left) kms_cursor_edge_walk (256x256-right-edge)
[200.126174] Starting subtest: 256x256-right-edge
[200.128417] Starting dynamic subtest: pipe-A-eDP-1
[203.623106] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.493s)
[203.632114] Starting dynamic subtest: pipe-D-eDP-1
[206.751370] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s)
[206.753697] Subtest 256x256-right-edge: SUCCESS (6.627s)
[207.577841] [031/132] (811s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[207.693867] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[212.046939] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.358s)
[213.270193] [032/132] (805s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[213.364456] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[213.368498] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[213.506623] [033/132] (805s left) i915_pm_rc6_residency (media-rc6-accuracy)
[213.569743] Starting subtest: media-rc6-accuracy
[213.573736] Subtest media-rc6-accuracy: SKIP (0.000s)
[213.685922] [034/132] (805s left) kms_async_flips (async-flip-hang)
[213.784717] Starting subtest: async-flip-hang
[214.163207] Starting dynamic subtest: pipe-A-eDP-1
[229.433138] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.267s)
[229.823018] Starting dynamic subtest: pipe-B-eDP-1
[245.430325] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.611s)
[245.825207] Starting dynamic subtest: pipe-C-eDP-1
[261.363961] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.544s)
[261.736849] Starting dynamic subtest: pipe-D-eDP-1
[277.368714] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.634s)
[277.371330] Subtest async-flip-hang: SUCCESS (63.584s)
[278.628125] [035/132] (740s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[278.733378] Starting subtest: fbc-rgb101010-draw-render
[283.323400] Subtest fbc-rgb101010-draw-render: SUCCESS (4.592s)
[284.523144] [036/132] (734s left) kms_pm_dc (dc5-psr)
[285.636886] Starting subtest: dc5-psr
[288.506071] Subtest dc5-psr: SUCCESS (2.867s)
[289.357269] [037/132] (729s left) kms_getfb (getfb-handle-valid)
[289.450836] Starting subtest: getfb-handle-valid
[289.453082] Subtest getfb-handle-valid: SUCCESS (0.000s)
[289.586037] [038/132] (729s left) perf (oa-formats)
[289.688901] Starting subtest: oa-formats
[289.691392] Starting dynamic subtest: 0-rcs0
[289.701115] Dynamic subtest 0-rcs0: SUCCESS (0.012s)
[289.710280] Starting dynamic subtest: 1-vcs1
[289.723071] Dynamic subtest 1-vcs1: SUCCESS (0.021s)
[289.725180] Subtest oa-formats: SUCCESS (0.035s)
[289.770315] [039/132] (729s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[289.841581] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[289.846119] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[290.050156] [040/132] (729s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[290.150636] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[290.154990] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[290.306888] [041/132] (728s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[290.408035] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[290.410185] Starting dynamic subtest: pipe-A-eDP-1
[306.750938] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.109s)
[306.760736] Starting dynamic subtest: pipe-B-eDP-1
[323.721555] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.474s)
[323.730597] Starting dynamic subtest: pipe-C-eDP-1
[340.697257] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.436s)
[340.707415] Starting dynamic subtest: pipe-D-eDP-1
[357.656002] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.348s)
[357.658388] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (12.373s)
[358.860442] [042/132] (660s left) gem_exec_fence (keep-in-fence)
[359.059320] Starting subtest: keep-in-fence
[359.061323] Starting dynamic subtest: rcs0
[359.134867] Dynamic subtest rcs0: SUCCESS (0.078s)
[359.146468] Starting dynamic subtest: bcs0
[359.188289] Dynamic subtest bcs0: SUCCESS (0.052s)
[359.199012] Starting dynamic subtest: vcs0
[360.811575] Dynamic subtest vcs0: SUCCESS (1.619s)
[360.823481] Starting dynamic subtest: vcs1
[362.794704] Dynamic subtest vcs1: SUCCESS (1.978s)
[362.806750] Starting dynamic subtest: vecs0
[364.780659] Dynamic subtest vecs0: SUCCESS (1.980s)
[364.793227] Starting dynamic subtest: ccs0
[365.017410] Dynamic subtest ccs0: SUCCESS (0.235s)
[365.020413] Subtest keep-in-fence: SUCCESS (5.955s)
[366.858377] [043/132] (652s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[366.965858] Starting subtest: psr-rgb565-draw-pwrite
[373.930916] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.961s)
[374.053488] [044/132] (645s left) perf (per-context-mode-unprivileged)
[374.150264] Starting subtest: per-context-mode-unprivileged
[374.154753] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[374.200459] [045/132] (645s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[374.313475] Starting subtest: x-tiled-8bpp-rotate-0
[375.549304] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.235s)
[375.748899] [046/132] (643s left) gem_lmem_swapping (parallel-random-verify-ccs)
[375.799226] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[375.843330] [047/132] (643s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[375.949262] Starting subtest: 4-tiled-64bpp-rotate-180
[377.596202] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.645s)
[377.786705] [048/132] (641s left) gem_exec_schedule (preempt)
[377.868032] Starting subtest: preempt
[377.869872] Starting dynamic subtest: rcs0
[377.890219] Dynamic subtest rcs0: SUCCESS (0.026s)
[377.899298] Starting dynamic subtest: bcs0
[377.909990] Dynamic subtest bcs0: SUCCESS (0.019s)
[377.918881] Starting dynamic subtest: vcs0
[377.931270] Dynamic subtest vcs0: SUCCESS (0.021s)
[377.940097] Starting dynamic subtest: vcs1
[377.953596] Dynamic subtest vcs1: SUCCESS (0.021s)
[377.962609] Starting dynamic subtest: vecs0
[377.974712] Dynamic subtest vecs0: SUCCESS (0.020s)
[377.983559] Starting dynamic subtest: ccs0
[377.994479] Dynamic subtest ccs0: SUCCESS (0.019s)
[377.996352] Subtest preempt: SUCCESS (0.131s)
[379.891405] [049/132] (639s left) kms_flip (2x-wf_vblank-ts-check)
[379.995983] Starting subtest: 2x-wf_vblank-ts-check
[380.007345] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[380.150864] [050/132] (639s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[380.253910] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[380.256030] Starting dynamic subtest: pipe-A-eDP-1
[380.276206] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[380.288140] Starting dynamic subtest: pipe-B-eDP-1
[380.306994] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[380.316416] Starting dynamic subtest: pipe-C-eDP-1
[380.334975] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[380.344048] Starting dynamic subtest: pipe-D-eDP-1
[380.362720] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[380.364841] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.009s)
[382.554799] [051/132] (637s left) sw_sync (alloc_timeline)
[382.601149] Starting subtest: alloc_timeline
[382.603544] Subtest alloc_timeline: SUCCESS (0.000s)
[382.697523] [052/132] (636s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[382.751102] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[382.753448] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[382.884103] [053/132] (636s left) kms_atomic_transition (modeset-transition-nonblocking)
[382.975872] Starting subtest: modeset-transition-nonblocking
[382.978071] Starting dynamic subtest: 1x-outputs
[393.796696] Dynamic subtest 1x-outputs: SUCCESS (10.816s)
[393.799269] Subtest modeset-transition-nonblocking: SUCCESS (10.818s)
[394.690709] [054/132] (625s left) kms_pm_rpm (cursor)
[396.918195] Starting subtest: cursor
[396.923190] Subtest cursor: SKIP (0.000s)
[397.297713] [055/132] (622s left) kms_selftest (drm_damage_helper)
[397.336544] Starting subtest: drm_damage_helper
[397.360893] Starting dynamic subtest: drm_test_damage_iter_no_damage
[397.463961] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.102s)
[397.473984] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[397.565097] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.100s)
[397.576087] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[397.693255] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.127s)
[397.702811] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[397.830484] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.136s)
[397.840675] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[397.980981] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.149s)
[397.991612] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[398.117425] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.136s)
[398.127517] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[398.245754] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.127s)
[398.255508] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[398.365694] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.120s)
[398.375025] Starting dynamic subtest: drm_test_damage_iter_single_damage
[398.509966] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.143s)
[398.518471] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[398.658646] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.147s)
[398.668993] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[398.753871] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.095s)
[398.764191] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[398.900701] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.145s)
[398.910900] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[399.025848] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.124s)
[399.035835] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[399.142629] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.116s)
[399.151747] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[399.255118] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.113s)
[399.263948] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[399.364808] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.108s)
[399.375090] Starting dynamic subtest: drm_test_damage_iter_damage
[399.503068] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.137s)
[399.511669] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[399.641746] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.137s)
[399.651062] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[399.785569] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.143s)
[399.794374] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[399.933901] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.146s)
[399.944092] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[400.059811] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.125s)
[400.062152] Subtest drm_damage_helper: SUCCESS (2.721s)
[400.262094] [056/132] (619s left) gem_userptr_blits (unsync-overlap)
[400.337406] Subtest unsync-overlap: SKIP (0.000s)
[400.493407] [057/132] (619s left) kms_psr (pr-sprite-mmap-gtt)
[400.594109] Starting subtest: pr-sprite-mmap-gtt
[400.598405] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[400.693515] [058/132] (619s left) kms_psr (psr-sprite-mmap-gtt)
[400.793812] Starting subtest: psr-sprite-mmap-gtt
[400.795922] Starting dynamic subtest: eDP-1
[402.208068] Dynamic subtest eDP-1: SKIP (1.411s)
[402.212997] Subtest psr-sprite-mmap-gtt: SKIP (1.413s)
[402.426346] [059/132] (617s left) gem_exec_parallel (basic)
[402.489853] Starting subtest: basic
[402.492120] Starting dynamic subtest: rcs0
[403.921678] Dynamic subtest rcs0: SUCCESS (1.433s)
[403.933691] Starting dynamic subtest: bcs0
[405.394544] Dynamic subtest bcs0: SUCCESS (1.471s)
[405.406863] Starting dynamic subtest: vcs0
[406.828992] Dynamic subtest vcs0: SUCCESS (1.433s)
[406.840913] Starting dynamic subtest: vcs1
[408.750685] Dynamic subtest vcs1: SUCCESS (1.915s)
[408.761013] Starting dynamic subtest: vecs0
[410.795934] Dynamic subtest vecs0: SUCCESS (2.042s)
[410.807866] Starting dynamic subtest: ccs0
[412.300371] Dynamic subtest ccs0: SUCCESS (1.503s)
[412.302862] Subtest basic: SUCCESS (9.810s)
[412.514655] [060/132] (607s left) kms_content_protection (content-type-change)
[412.665263] Starting subtest: content-type-change
[412.669903] Subtest content-type-change: SKIP (0.000s)
[412.833514] [061/132] (607s left) gem_mmap_wc (write-read-distinct)
[412.890594] Subtest write-read-distinct: SKIP (0.000s)
[412.967359] [062/132] (607s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[413.068047] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[413.881543] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.814s)
[414.089560] [063/132] (606s left) gem_ctx_persistence (engines-mixed-process)
[414.157164] Starting subtest: engines-mixed-process
[414.159571] Starting dynamic subtest: rcs0
[415.813616] Dynamic subtest rcs0: SUCCESS (1.655s)
[415.826054] Starting dynamic subtest: bcs0
[417.783629] Dynamic subtest bcs0: SUCCESS (1.968s)
[417.794372] Starting dynamic subtest: vcs0
[419.787107] Dynamic subtest vcs0: SUCCESS (2.000s)
[419.799594] Starting dynamic subtest: vcs1
[421.829847] Dynamic subtest vcs1: SUCCESS (2.040s)
[421.841474] Starting dynamic subtest: vecs0
[423.812732] Dynamic subtest vecs0: SUCCESS (1.980s)
[423.824883] Starting dynamic subtest: ccs0
[425.783868] Dynamic subtest ccs0: SUCCESS (1.968s)
[425.786264] Subtest engines-mixed-process: SUCCESS (11.621s)
[425.890438] [064/132] (594s left) kms_chamelium_edid (vga-edid-read)
[426.283905] Subtest vga-edid-read: SKIP (0.000s)
[427.132906] [065/132] (593s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[427.269628] Starting subtest: 2x-long-flip-vs-cursor-atomic
[427.277947] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.003s)
[427.448314] [066/132] (592s left) gem_exec_create (legacy)
[427.504534] Starting subtest: legacy
[427.506807] Starting dynamic subtest: smem
[430.765240] Dynamic subtest smem: SUCCESS (3.257s)
[430.768092] Subtest legacy: SUCCESS (3.259s)
[430.978701] [067/132] (589s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[431.092905] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[431.096866] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[431.224881] [068/132] (589s left) gem_softpin (zero)
[431.277601] Starting subtest: zero
[431.282364] Subtest zero: SUCCESS (0.004s)
[431.445694] [069/132] (588s left) gem_exec_flush (basic-wb-ro-before-default)
[431.514746] Starting subtest: basic-wb-ro-before-default
[436.973951] Subtest basic-wb-ro-before-default: SUCCESS (5.457s)
[437.195973] [070/132] (583s left) kms_big_fb (linear-64bpp-rotate-90)
[437.306983] Starting subtest: linear-64bpp-rotate-90
[437.325764] Subtest linear-64bpp-rotate-90: SKIP (0.018s)
[437.486835] [071/132] (583s left) gem_userptr_blits (set-cache-level)
[437.556471] Starting subtest: set-cache-level
[437.560562] Subtest set-cache-level: SKIP (0.000s)
[437.710904] [072/132] (582s left) perf_pmu (busy)
[437.760672] Starting subtest: busy
[437.763086] Starting dynamic subtest: rcs0
[438.380438] Dynamic subtest rcs0: SUCCESS (0.621s)
[438.392268] Starting dynamic subtest: bcs0
[438.941228] Dynamic subtest bcs0: SUCCESS (0.559s)
[438.953643] Starting dynamic subtest: vcs0
[440.748824] Dynamic subtest vcs0: SUCCESS (1.803s)
[440.760668] Starting dynamic subtest: vcs1
[442.797392] Dynamic subtest vcs1: SUCCESS (2.044s)
[442.808728] Starting dynamic subtest: vecs0
[444.781431] Dynamic subtest vecs0: SUCCESS (1.980s)
[444.793907] Starting dynamic subtest: ccs0
[445.349547] Dynamic subtest ccs0: SUCCESS (0.566s)
[445.352128] Subtest busy: SUCCESS (7.588s)
[445.491429] [073/132] (575s left) gem_basic (multigpu-create-close)
[445.544838] Starting subtest: multigpu-create-close
[445.549102] Subtest multigpu-create-close: SKIP (0.000s)
[445.662462] [074/132] (574s left) gen9_exec_parse (bb-secure)
[445.728378] Subtest bb-secure: SKIP (0.000s)
[445.806858] [075/132] (574s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[445.878297] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[445.880727] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[446.009997] [076/132] (574s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[450.955897] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[450.960525] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[451.816170] [077/132] (568s left) kms_lease (lease-invalid-crtc)
[451.917063] Starting subtest: lease-invalid-crtc
[451.919349] Starting dynamic subtest: pipe-A-eDP-1
[451.921616] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[451.931708] Starting dynamic subtest: pipe-B-eDP-1
[451.934058] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[451.943740] Starting dynamic subtest: pipe-C-eDP-1
[451.946039] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[451.955385] Starting dynamic subtest: pipe-D-eDP-1
[451.957305] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[451.959233] Subtest lease-invalid-crtc: SUCCESS (0.005s)
[452.015703] [078/132] (568s left) gem_pwrite_snooped
[452.183958] [079/132] (568s left) gem_exec_reloc (basic-write-wc-noreloc)
[452.243858] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[452.327377] [080/132] (568s left) kms_flip (wf_vblank-ts-check-interruptible)
[452.426028] Starting subtest: wf_vblank-ts-check-interruptible
[452.428395] Starting dynamic subtest: A-eDP1
[461.326960] Dynamic subtest A-eDP1: SUCCESS (8.908s)
[461.337839] Starting dynamic subtest: B-eDP1
[470.251721] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[470.264420] Starting dynamic subtest: C-eDP1
[479.163151] Dynamic subtest C-eDP1: SUCCESS (8.914s)
[479.173514] Starting dynamic subtest: D-eDP1
[488.005555] Dynamic subtest D-eDP1: SUCCESS (8.844s)
[488.011467] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.605s)
[489.244870] [081/132] (531s left) gem_ctx_persistence (legacy-engines-queued)
[489.304882] Starting subtest: legacy-engines-queued
[489.307214] Starting dynamic subtest: render
[489.430712] Dynamic subtest render: SUCCESS (0.125s)
[489.443257] Starting dynamic subtest: bsd1
[490.762395] Dynamic subtest bsd1: SUCCESS (1.327s)
[490.775533] Starting dynamic subtest: bsd2
[492.745459] Dynamic subtest bsd2: SUCCESS (1.980s)
[492.757313] Starting dynamic subtest: blt
[492.822381] Dynamic subtest blt: SUCCESS (0.075s)
[492.837677] Starting dynamic subtest: vebox
[494.730869] Dynamic subtest vebox: SUCCESS (1.904s)
[494.733627] Subtest legacy-engines-queued: SUCCESS (5.427s)
[494.835752] [082/132] (526s left) gem_exercise_blt (fast-copy)
[494.900195] Starting subtest: fast-copy
[494.902046] Starting dynamic subtest: linear-smem-smem
[494.924896] Dynamic subtest linear-smem-smem: SUCCESS (0.027s)
[494.936745] Starting dynamic subtest: xmajor-smem-smem
[494.944411] Dynamic subtest xmajor-smem-smem: SUCCESS (0.019s)
[494.954245] Starting dynamic subtest: tile4-smem-smem
[494.962974] Dynamic subtest tile4-smem-smem: SUCCESS (0.018s)
[494.972598] Starting dynamic subtest: tile64-smem-smem
[494.982833] Dynamic subtest tile64-smem-smem: SUCCESS (0.019s)
[494.985113] Subtest fast-copy: SUCCESS (0.086s)
[495.130228] [083/132] (525s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[495.225550] Starting subtest: bo-too-small-due-to-tiling
[495.229856] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[495.355290] [084/132] (525s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[495.407767] Starting subtest: multi-wait-all-for-submit-signaled
[495.410126] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[495.533887] [085/132] (525s left) kms_cursor_crc (cursor-random-512x512)
[495.815828] Starting subtest: cursor-random-512x512
[495.819729] Subtest cursor-random-512x512: SKIP (0.001s)
[496.019672] [086/132] (525s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[496.084628] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[496.145499] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.006s)
[496.186300] [087/132] (524s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[496.291396] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[496.293307] Starting dynamic subtest: pipe-A-default-mode
[496.581607] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[496.586390] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.288s)
[497.470983] [088/132] (523s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[497.577294] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[497.579450] Starting dynamic subtest: pipe-A-eDP-1
[497.601751] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[497.613266] Starting dynamic subtest: pipe-B-eDP-1
[497.635244] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[497.645427] Starting dynamic subtest: pipe-C-eDP-1
[497.666908] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[497.678254] Starting dynamic subtest: pipe-D-eDP-1
[497.701707] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[497.706080] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[499.836820] [089/132] (521s left) kms_plane_multiple (2x-tiling-none)
[499.951133] Starting subtest: 2x-tiling-none
[499.955469] Subtest 2x-tiling-none: SKIP (0.000s)
[500.050945] [090/132] (521s left) kms_psr (fbc-psr2-sprite-blt)
[500.148511] Starting subtest: fbc-psr2-sprite-blt
[500.150771] Starting dynamic subtest: eDP-1
[500.154996] Dynamic subtest eDP-1: SKIP (0.000s)
[500.159223] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[500.247533] [091/132] (521s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[500.351902] Starting subtest: y-tiled-16bpp-rotate-270
[500.356307] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[500.475808] [092/132] (520s left) i915_query (engine-info-invalid)
[500.537636] Starting subtest: engine-info-invalid
[500.539646] Subtest engine-info-invalid: SUCCESS (0.000s)
[500.668968] [093/132] (520s left) gem_ctx_param (invalid-size-set)
[500.732734] Starting subtest: invalid-size-set
[500.735016] Subtest invalid-size-set: SUCCESS (0.001s)
[500.876217] [094/132] (520s left) kms_fbcon_fbt (psr-suspend)
[500.938422] Starting subtest: psr-suspend
[550.512846] Subtest psr-suspend: SUCCESS (19.641s)
[550.593180] [095/132] (470s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[550.662050] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[550.767410] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[550.870587] [096/132] (470s left) kms_pm_rpm (universal-planes)
[552.889793] Starting subtest: universal-planes
[552.892695] Starting dynamic subtest: plane-33
[561.576255] Dynamic subtest plane-33: SUCCESS (8.678s)
[561.589425] Starting dynamic subtest: plane-63
[569.970022] Dynamic subtest plane-63: SUCCESS (8.386s)
[569.983558] Starting dynamic subtest: plane-93
[578.263626] Dynamic subtest plane-93: SUCCESS (8.287s)
[578.276730] Starting dynamic subtest: plane-123
[586.573860] Dynamic subtest plane-123: SUCCESS (8.308s)
[586.586882] Starting dynamic subtest: plane-133
[594.880459] Dynamic subtest plane-133: SUCCESS (8.303s)
[594.892959] Starting dynamic subtest: plane-143
[603.491069] Dynamic subtest plane-143: SUCCESS (8.605s)
[603.493830] Subtest universal-planes: SUCCESS (50.583s)
[603.886034] [097/132] (417s left) gem_exec_reloc (basic-write-read)
[603.960930] Subtest basic-write-read: SKIP (0.000s)
[604.040747] [098/132] (417s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[604.136140] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[604.140389] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[604.263202] [099/132] (417s left) kms_cursor_crc (cursor-onscreen-32x32)
[604.415490] Starting subtest: cursor-onscreen-32x32
[604.462261] Subtest cursor-onscreen-32x32: SKIP (0.044s)
[604.773841] [100/132] (416s left) syncobj_wait (invalid-wait-bad-flags)
[604.835655] Starting subtest: invalid-wait-bad-flags
[604.837572] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[604.956662] [101/132] (416s left) gem_exec_whisper (basic-contexts-forked-all)
[605.030984] Starting subtest: basic-contexts-forked-all
[625.642289] Subtest basic-contexts-forked-all: SUCCESS (20.598s)
[625.852146] [102/132] (395s left) gem_fenced_exec_thrash (2-spare-fences)
[625.923679] Subtest 2-spare-fences: SKIP (0.000s)
[626.008065] [103/132] (395s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[626.120949] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[626.124919] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[626.253132] [104/132] (395s left) api_intel_bb (add-remove-objects)
[626.320854] Starting subtest: add-remove-objects
[626.325385] Subtest add-remove-objects: SUCCESS (0.007s)
[626.466182] [105/132] (395s left) kms_vblank (invalid)
[626.554671] Starting subtest: invalid
[626.556825] Starting dynamic subtest: pipe-A-eDP-1
[626.953789] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.400s)
[626.956056] Subtest invalid: SUCCESS (0.401s)
[627.806902] [106/132] (394s left) gem_mmap_wc (bad-object)
[627.864355] Subtest bad-object: SKIP (0.000s)
[627.932839] [107/132] (394s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[628.036777] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[628.041164] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[628.163462] [108/132] (393s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[628.229496] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[628.318979] [109/132] (393s left) gem_tiled_fence_blits (normal)
[628.381298] Subtest normal: SKIP (0.000s)
[628.477226] [110/132] (393s left) gem_exec_alignment (pi)
[628.534427] Starting subtest: pi
[680.697137] Subtest pi: SUCCESS (52.159s)
[681.767297] [111/132] (340s left) gem_mmap (bad-object)
[681.872939] Subtest bad-object: SKIP (0.000s)
[681.945373] [112/132] (340s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[682.058365] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[682.060758] Starting dynamic subtest: pipe-A
[682.298850] Dynamic subtest pipe-A: SUCCESS (0.243s)
[682.310249] Starting dynamic subtest: pipe-B
[683.612837] Dynamic subtest pipe-B: SUCCESS (1.313s)
[683.625763] Starting dynamic subtest: pipe-C
[684.899394] Dynamic subtest pipe-C: SUCCESS (1.285s)
[684.912396] Starting dynamic subtest: pipe-D
[686.227674] Dynamic subtest pipe-D: SUCCESS (1.328s)
[686.230031] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.173s)
[687.423060] [113/132] (334s left) i915_hangman (engine-error-state-capture)
[687.495787] Starting subtest: engine-error-state-capture
[687.498159] Starting dynamic subtest: rcs0
[690.658252] Dynamic subtest rcs0: SUCCESS (3.160s)
[690.672370] Starting dynamic subtest: bcs0
[696.673458] Dynamic subtest bcs0: SUCCESS (6.011s)
[696.687688] Starting dynamic subtest: vcs0
[701.665634] Dynamic subtest vcs0: SUCCESS (4.989s)
[701.679655] Starting dynamic subtest: vcs1
[707.616904] Dynamic subtest vcs1: SUCCESS (5.947s)
[707.635347] Starting dynamic subtest: vecs0
[713.634398] Dynamic subtest vecs0: SUCCESS (6.012s)
[713.651826] Starting dynamic subtest: ccs0
[719.649653] Dynamic subtest ccs0: SUCCESS (6.011s)
[719.652587] Subtest engine-error-state-capture: SUCCESS (32.148s)
[719.758475] [114/132] (302s left) perf (low-oa-exponent-permissions)
[719.894071] Starting subtest: low-oa-exponent-permissions
[719.930885] Subtest low-oa-exponent-permissions: SUCCESS (0.037s)
[719.979319] [115/132] (302s left) gem_softpin (noreloc)
[720.035537] Starting subtest: noreloc
[725.439702] Subtest noreloc: SUCCESS (5.401s)
[725.606401] [116/132] (296s left) perf (enable-disable)
[725.710349] Starting subtest: enable-disable
[725.712558] Starting dynamic subtest: 0-rcs0
[727.998019] Dynamic subtest 0-rcs0: SUCCESS (2.285s)
[728.012740] Starting dynamic subtest: 1-vcs1
[732.479419] Dynamic subtest 1-vcs1: SUCCESS (4.480s)
[732.482022] Subtest enable-disable: SUCCESS (6.767s)
[732.532517] [117/132] (289s left) kms_pipe_crc_basic (nonblocking-crc)
[732.624803] Starting subtest: nonblocking-crc
[732.627179] Starting dynamic subtest: pipe-A-eDP-1
[733.373375] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.749s)
[733.385532] Starting dynamic subtest: pipe-B-eDP-1
[734.881334] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.505s)
[734.893225] Starting dynamic subtest: pipe-C-eDP-1
[736.221923] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.340s)
[736.233393] Starting dynamic subtest: pipe-D-eDP-1
[737.547300] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[737.549757] Subtest nonblocking-crc: SUCCESS (4.922s)
[738.387455] [118/132] (284s left) sysfs_preempt_timeout (timeout)
[738.459655] Starting subtest: timeout
[738.461613] Starting dynamic subtest: vecs0
[747.733482] Dynamic subtest vecs0: SUCCESS (9.270s)
[747.746900] Starting dynamic subtest: ccs0
[749.138092] Dynamic subtest ccs0: SUCCESS (1.402s)
[749.152141] Starting dynamic subtest: vcs0
[758.659180] Dynamic subtest vcs0: SUCCESS (9.517s)
[758.672846] Starting dynamic subtest: bcs0
[759.950955] Dynamic subtest bcs0: SUCCESS (1.290s)
[759.964175] Starting dynamic subtest: rcs0
[761.249661] Dynamic subtest rcs0: SUCCESS (1.298s)
[761.263759] Starting dynamic subtest: vcs1
[770.702869] Dynamic subtest vcs1: SUCCESS (9.451s)
[770.706050] Subtest timeout: SUCCESS (32.238s)
[770.807920] [119/132] (251s left) gem_mmap_gtt (hang-busy)
[770.875187] Subtest hang-busy: SKIP (0.000s)
[770.938697] [120/132] (251s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[771.056276] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[771.060666] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[771.177981] [121/132] (251s left) i915_drm_fdinfo (all-busy-idle-check-all)
[771.215116] Starting subtest: all-busy-idle-check-all
[771.222902] Subtest all-busy-idle-check-all: SKIP (0.004s)
[771.307943] [122/132] (251s left) perf_pmu (init-sema)
[771.350625] Starting subtest: init-sema
[771.352915] Starting dynamic subtest: rcs0
[771.355116] Dynamic subtest rcs0: SUCCESS (0.001s)
[771.366457] Starting dynamic subtest: bcs0
[771.368622] Dynamic subtest bcs0: SUCCESS (0.000s)
[771.379057] Starting dynamic subtest: vcs0
[771.381212] Dynamic subtest vcs0: SUCCESS (0.000s)
[771.391118] Starting dynamic subtest: vcs1
[771.393352] Dynamic subtest vcs1: SUCCESS (0.000s)
[771.403569] Starting dynamic subtest: vecs0
[771.405769] Dynamic subtest vecs0: SUCCESS (0.000s)
[771.415775] Starting dynamic subtest: ccs0
[771.417890] Dynamic subtest ccs0: SUCCESS (0.000s)
[771.420107] Subtest init-sema: SUCCESS (0.006s)
[771.511533] [123/132] (251s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[771.615223] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[778.613640] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.003s)
[779.014529] [124/132] (243s left) gem_exec_schedule (fairslice)
[779.145078] Starting subtest: fairslice
[779.147070] Starting dynamic subtest: rcs0
[781.173773] Dynamic subtest rcs0: SUCCESS (2.026s)
[781.186990] Starting dynamic subtest: bcs0
[783.198800] Dynamic subtest bcs0: SUCCESS (2.024s)
[783.211192] Starting dynamic subtest: vcs0
[785.222943] Dynamic subtest vcs0: SUCCESS (2.023s)
[785.233855] Starting dynamic subtest: vcs1
[787.242620] Dynamic subtest vcs1: SUCCESS (2.019s)
[787.255377] Starting dynamic subtest: vecs0
[789.258949] Dynamic subtest vecs0: SUCCESS (2.016s)
[789.270910] Starting dynamic subtest: ccs0
[791.277835] Dynamic subtest ccs0: SUCCESS (2.020s)
[791.279785] Subtest fairslice: SUCCESS (12.134s)
[791.605130] [125/132] (231s left) gem_exec_reloc (basic-wc-cpu)
[791.676373] Subtest basic-wc-cpu: SKIP (0.000s)
[791.764950] [126/132] (231s left) syncobj_timeline (wait-all-for-submit-complex)
[791.834388] Starting subtest: wait-all-for-submit-complex
[792.047433] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[792.150878] [127/132] (230s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[792.285161] Starting subtest: cursorB-vs-flipB-legacy
[792.289547] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[792.453950] [128/132] (230s left) gem_exec_flush (basic-wb-set-default)
[792.512966] Starting subtest: basic-wb-set-default
[797.975752] Subtest basic-wb-set-default: SUCCESS (5.465s)
[798.192036] [129/132] (224s left) gem_pxp (reject-modify-context-protection-off-2)
[798.316392] Starting subtest: reject-modify-context-protection-off-2
[798.319316] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[798.467232] [130/132] (224s left) api_intel_bb (misplaced-blitter)
[798.522188] Starting subtest: misplaced-blitter
[798.530701] Subtest misplaced-blitter: SUCCESS (0.011s)
[798.702047] [131/132] (224s left) kms_psr (fbc-pr-dpms)
[798.808420] Starting subtest: fbc-pr-dpms
[798.812757] Subtest fbc-pr-dpms: SKIP (0.000s)
[798.905697] [132/132] (224s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[799.009408] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[799.013747] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[799.144357] 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.