[60.954875] Initializing watchdogs
[60.954923]   /dev/watchdog0
[60.969785] [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]
[60.974220] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.976508] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.979821] [001/132] (960s left) gem_exec_balancer (noheartbeat)
[61.270065] Starting subtest: noheartbeat
[61.274977] Subtest noheartbeat: SKIP (0.000s)
[61.429599] [002/132] (960s left) prime_vgem (basic-gtt)
[61.549064] Starting subtest: basic-gtt
[61.553879] Subtest basic-gtt: SKIP (0.000s)
[61.692652] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[61.695738] [003/132] (959s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[61.810829] Starting subtest: fbc-1p-pri-indfb-multidraw
[75.321718] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.515s)
[76.752162] [004/132] (944s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[76.862472] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[86.052320] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.193s)
[86.220090] [005/132] (935s left) kms_rmfb (rmfb-ioctl)
[86.321151] Starting subtest: rmfb-ioctl
[86.323409] Starting dynamic subtest: pipe-A-eDP-1
[86.424202] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[86.436276] Starting dynamic subtest: pipe-B-eDP-1
[87.643371] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.216s)
[87.654784] Starting dynamic subtest: pipe-C-eDP-1
[88.859212] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.214s)
[88.868481] Starting dynamic subtest: pipe-D-eDP-1
[89.998693] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.138s)
[90.001071] Subtest rmfb-ioctl: SUCCESS (3.681s)
[91.232386] [006/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[91.334542] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[97.936062] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.603s)
[98.085140] [007/132] (923s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[98.476661] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[99.247357] [008/132] (922s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[99.350119] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[99.352483] Starting dynamic subtest: pipe-A-default-mode
[100.522062] Dynamic subtest pipe-A-default-mode: SKIP (1.169s)
[100.526421] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.170s)
[100.715565] [009/132] (921s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[100.848264] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[100.852202] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[100.985504] [010/132] (920s left) gem_exec_reloc (basic-write-read-noreloc)
[101.059611] Subtest basic-write-read-noreloc: SKIP (0.000s)
[101.153855] [011/132] (920s left) kms_draw_crc (draw-method-blt)
[101.244839] Starting subtest: draw-method-blt
[101.247129] Starting dynamic subtest: xrgb8888-untiled
[101.498879] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[101.506794] Starting dynamic subtest: rgb565-untiled
[101.710140] Dynamic subtest rgb565-untiled: SUCCESS (0.211s)
[101.718309] Starting dynamic subtest: xrgb2101010-untiled
[101.910528] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.200s)
[101.922336] Starting dynamic subtest: xbgr16161616f-untiled
[102.132330] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[102.140283] Starting dynamic subtest: xrgb8888-xtiled
[102.243084] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.111s)
[102.251238] Starting dynamic subtest: rgb565-xtiled
[102.365638] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[102.374118] Starting dynamic subtest: xrgb2101010-xtiled
[102.476694] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[102.485698] Starting dynamic subtest: xbgr16161616f-xtiled
[102.587757] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[102.595901] Starting dynamic subtest: xrgb8888-4tiled
[102.709933] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[102.717447] Starting dynamic subtest: rgb565-4tiled
[102.832082] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s)
[102.840712] Starting dynamic subtest: xrgb2101010-4tiled
[102.943283] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[102.951267] Starting dynamic subtest: xbgr16161616f-4tiled
[103.054918] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[103.057266] Subtest draw-method-blt: SUCCESS (1.812s)
[103.205459] [012/132] (918s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[103.310072] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[103.311994] Starting dynamic subtest: pipe-A-eDP-1
[103.330988] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[103.340152] Starting dynamic subtest: pipe-B-eDP-1
[103.357826] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[103.364890] Starting dynamic subtest: pipe-C-eDP-1
[103.382502] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[103.389422] Starting dynamic subtest: pipe-D-eDP-1
[103.408440] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[103.411927] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[105.578571] [013/132] (916s left) perf (gen12-group-exclusive-stream-sample-oa)
[105.727101] Starting subtest: gen12-group-exclusive-stream-sample-oa
[105.747449] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.020s)
[105.786457] [014/132] (916s left) gem_create (hog-create)
[105.842641] Starting subtest: hog-create
[105.845023] Starting dynamic subtest: smem0
[139.964347] Dynamic subtest smem0: SUCCESS (34.119s)
[139.966926] Subtest hog-create: SUCCESS (34.120s)
[140.176820] [015/132] (881s left) kms_cdclk (plane-scaling)
[140.285511] Starting subtest: plane-scaling
[140.287915] Starting dynamic subtest: pipe-A-eDP-1
[140.574896] Dynamic subtest pipe-A-eDP-1: SKIP (0.288s)
[140.583581] Starting dynamic subtest: pipe-B-eDP-1
[140.587910] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[140.595204] Starting dynamic subtest: pipe-C-eDP-1
[140.599513] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[140.607040] Starting dynamic subtest: pipe-D-eDP-1
[140.611650] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[140.615998] Subtest plane-scaling: SKIP (0.294s)
[141.401776] [016/132] (880s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[141.508848] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[141.513586] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[141.627034] [017/132] (880s left) gem_softpin (softpin)
[141.690213] Starting subtest: softpin
[174.063296] Subtest softpin: SUCCESS (32.366s)
[174.221944] [018/132] (847s left) kms_joiner (basic-force-ultra-joiner)
[174.334684] Starting subtest: basic-force-ultra-joiner
[174.338876] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[174.413071] [019/132] (847s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[174.520355] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[174.532388] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[174.666896] [020/132] (847s left) syncobj_timeline (reset-multiple-signaled)
[174.721046] Starting subtest: reset-multiple-signaled
[174.723397] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[174.845065] [021/132] (847s left) drm_mm (drm_mm)
[174.893291] Starting subtest: drm_mm
[174.940047] Starting dynamic subtest: drm_test_mm_init
[175.083792] Dynamic subtest drm_test_mm_init: SUCCESS (0.143s)
[175.095617] Starting dynamic subtest: drm_test_mm_debug
[175.239795] Dynamic subtest drm_test_mm_debug: SUCCESS (0.155s)
[175.251864] Starting dynamic subtest: drm_test_mm_align32
[175.400756] Dynamic subtest drm_test_mm_align32: SUCCESS (0.161s)
[175.410334] Starting dynamic subtest: drm_test_mm_align64
[175.533559] Dynamic subtest drm_test_mm_align64: SUCCESS (0.133s)
[175.542925] Starting dynamic subtest: drm_test_mm_lowest
[175.667648] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.133s)
[175.676400] Starting dynamic subtest: drm_test_mm_highest
[175.790958] Dynamic subtest drm_test_mm_highest: SUCCESS (0.122s)
[175.793395] Subtest drm_mm: SUCCESS (0.897s)
[176.009887] [022/132] (846s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[180.938757] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[180.943356] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.003s)
[181.834717] [023/132] (840s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[181.933269] Starting subtest: plane-ctm3x4-lut1d
[181.948366] Starting dynamic subtest: pipe-A-eDP-1
[182.658274] Dynamic subtest pipe-A-eDP-1: FAIL (0.324s)
[183.382300] Starting dynamic subtest: pipe-B-eDP-1
[184.244815] Dynamic subtest pipe-B-eDP-1: FAIL (0.347s)
[184.806252] Starting dynamic subtest: pipe-C-eDP-1
[185.664414] Dynamic subtest pipe-C-eDP-1: FAIL (0.280s)
[186.140688] Starting dynamic subtest: pipe-D-eDP-1
[187.002620] Dynamic subtest pipe-D-eDP-1: FAIL (0.279s)
[187.004587] Subtest plane-ctm3x4-lut1d: FAIL (4.769s)
[187.545310] [024/132] (834s left) kms_psr (fbc-psr-primary-mmap-gtt)
[187.650365] Starting subtest: fbc-psr-primary-mmap-gtt
[187.652726] Starting dynamic subtest: eDP-1
[187.657164] Dynamic subtest eDP-1: SKIP (0.000s)
[187.661606] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[187.754292] [025/132] (834s left) gem_exec_schedule (u-semaphore-user)
[187.819709] Starting subtest: u-semaphore-user
[187.873161] Subtest u-semaphore-user: SUCCESS (0.061s)
[189.903990] [026/132] (832s left) gem_userptr_blits (unsync-unmap-cycles)
[189.984909] Subtest unsync-unmap-cycles: SKIP (0.000s)
[190.153960] [027/132] (832s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[190.250177] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[190.252542] Starting dynamic subtest: pipe-A-default-mode
[190.549382] Dynamic subtest pipe-A-default-mode: SKIP (0.297s)
[190.554161] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.298s)
[191.395549] [028/132] (831s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[191.489015] Starting subtest: fbc-rgb565-draw-pwrite
[196.068730] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.585s)
[197.288418] [029/132] (825s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[197.389114] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[204.976259] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.589s)
[205.145391] [030/132] (817s left) kms_cursor_edge_walk (256x256-right-edge)
[205.238168] Starting subtest: 256x256-right-edge
[205.240434] Starting dynamic subtest: pipe-A-eDP-1
[208.655963] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.417s)
[208.664404] Starting dynamic subtest: pipe-D-eDP-1
[211.780328] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.122s)
[211.782701] Subtest 256x256-right-edge: SUCCESS (6.542s)
[212.606325] [031/132] (810s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[212.707710] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[217.242856] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.538s)
[218.418247] [032/132] (804s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[218.522159] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[218.526348] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[218.628435] [033/132] (804s left) i915_pm_rc6_residency (media-rc6-accuracy)
[218.693487] Starting subtest: media-rc6-accuracy
[218.697819] Subtest media-rc6-accuracy: SKIP (0.000s)
[218.812918] [034/132] (804s left) kms_async_flips (async-flip-hang)
[218.907846] Starting subtest: async-flip-hang
[219.277654] Starting dynamic subtest: pipe-A-eDP-1
[234.354038] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.074s)
[234.738530] Starting dynamic subtest: pipe-B-eDP-1
[250.351146] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.616s)
[250.733856] Starting dynamic subtest: pipe-C-eDP-1
[266.350959] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.620s)
[266.726025] Starting dynamic subtest: pipe-D-eDP-1
[282.349227] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.627s)
[282.351691] Subtest async-flip-hang: SUCCESS (63.439s)
[283.525715] [035/132] (739s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[283.633444] Starting subtest: fbc-rgb101010-draw-render
[288.240144] Subtest fbc-rgb101010-draw-render: SUCCESS (4.610s)
[289.434049] [036/132] (733s left) kms_pm_dc (dc5-psr)
[290.534511] Starting subtest: dc5-psr
[293.430773] Subtest dc5-psr: SUCCESS (2.897s)
[294.255741] [037/132] (728s left) kms_getfb (getfb-handle-valid)
[294.360226] Starting subtest: getfb-handle-valid
[294.362380] Subtest getfb-handle-valid: SUCCESS (0.000s)
[294.494687] [038/132] (728s left) perf (oa-formats)
[294.602671] Starting subtest: oa-formats
[294.605101] Starting dynamic subtest: 0-rcs0
[294.615486] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[294.624688] Starting dynamic subtest: 1-vecs0
[294.636856] Dynamic subtest 1-vecs0: SUCCESS (0.021s)
[294.639109] Subtest oa-formats: SUCCESS (0.035s)
[294.674363] [039/132] (728s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[294.749716] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[294.754212] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[294.959487] [040/132] (728s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[295.070035] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[295.074541] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[295.215999] [041/132] (727s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[295.309047] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[295.311204] Starting dynamic subtest: pipe-A-eDP-1
[311.906904] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.126s)
[311.916762] Starting dynamic subtest: pipe-B-eDP-1
[328.885372] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.446s)
[328.895328] Starting dynamic subtest: pipe-C-eDP-1
[345.861254] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.327s)
[345.870497] Starting dynamic subtest: pipe-D-eDP-1
[362.878153] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.414s)
[362.880792] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (12.318s)
[364.134804] [042/132] (659s left) gem_exec_fence (keep-in-fence)
[364.324053] Starting subtest: keep-in-fence
[364.326682] Starting dynamic subtest: rcs0
[364.390151] Dynamic subtest rcs0: SUCCESS (0.066s)
[364.401651] Starting dynamic subtest: bcs0
[364.445493] Dynamic subtest bcs0: SUCCESS (0.053s)
[364.457511] Starting dynamic subtest: vcs0
[366.177134] Dynamic subtest vcs0: SUCCESS (1.727s)
[366.189712] Starting dynamic subtest: vcs1
[368.161869] Dynamic subtest vcs1: SUCCESS (1.981s)
[368.173667] Starting dynamic subtest: vecs0
[370.145206] Dynamic subtest vecs0: SUCCESS (1.979s)
[370.156769] Starting dynamic subtest: ccs0
[370.385818] Dynamic subtest ccs0: SUCCESS (0.239s)
[370.388826] Subtest keep-in-fence: SUCCESS (6.058s)
[372.263661] [043/132] (650s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[372.368806] Starting subtest: psr-rgb565-draw-pwrite
[379.443843] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.073s)
[379.592093] [044/132] (643s left) perf (per-context-mode-unprivileged)
[379.700787] Starting subtest: per-context-mode-unprivileged
[379.705814] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[379.747262] [045/132] (643s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[379.848058] Starting subtest: x-tiled-8bpp-rotate-0
[381.082570] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.236s)
[381.244878] [046/132] (642s left) gem_lmem_swapping (parallel-random-verify-ccs)
[381.286991] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[381.325167] [047/132] (642s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[381.434499] Starting subtest: 4-tiled-64bpp-rotate-180
[382.937691] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.501s)
[383.342322] [048/132] (640s left) gem_exec_schedule (preempt)
[383.420675] Starting subtest: preempt
[383.423560] Starting dynamic subtest: rcs0
[383.438424] Dynamic subtest rcs0: SUCCESS (0.029s)
[383.450567] Starting dynamic subtest: bcs0
[383.462838] Dynamic subtest bcs0: SUCCESS (0.024s)
[383.472354] Starting dynamic subtest: vcs0
[383.484554] Dynamic subtest vcs0: SUCCESS (0.021s)
[383.493957] Starting dynamic subtest: vcs1
[383.507479] Dynamic subtest vcs1: SUCCESS (0.022s)
[383.516765] Starting dynamic subtest: vecs0
[383.525595] Dynamic subtest vecs0: SUCCESS (0.017s)
[383.535432] Starting dynamic subtest: ccs0
[383.545798] Dynamic subtest ccs0: SUCCESS (0.020s)
[383.548782] Subtest preempt: SUCCESS (0.139s)
[385.240854] [049/132] (638s left) kms_flip (2x-wf_vblank-ts-check)
[385.343607] Starting subtest: 2x-wf_vblank-ts-check
[385.351608] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[385.498432] [050/132] (638s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[385.596044] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[385.598399] Starting dynamic subtest: pipe-A-eDP-1
[385.619651] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[385.633943] Starting dynamic subtest: pipe-B-eDP-1
[385.658483] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[385.667367] Starting dynamic subtest: pipe-C-eDP-1
[385.691566] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[385.702268] Starting dynamic subtest: pipe-D-eDP-1
[385.724481] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[385.726766] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[387.850163] [051/132] (635s left) sw_sync (alloc_timeline)
[387.892174] Starting subtest: alloc_timeline
[387.894556] Subtest alloc_timeline: SUCCESS (0.000s)
[387.976132] [052/132] (635s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[388.035259] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[388.038065] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[388.165347] [053/132] (635s left) kms_atomic_transition (modeset-transition-nonblocking)
[388.257630] Starting subtest: modeset-transition-nonblocking
[388.260153] Starting dynamic subtest: 1x-outputs
[399.320774] Dynamic subtest 1x-outputs: SUCCESS (11.058s)
[399.323805] Subtest modeset-transition-nonblocking: SUCCESS (11.060s)
[400.202653] [054/132] (623s left) kms_pm_rpm (cursor)
[402.335554] Starting subtest: cursor
[402.340985] Subtest cursor: SKIP (0.000s)
[402.723700] [055/132] (620s left) kms_selftest (drm_damage_helper)
[402.765953] Starting subtest: drm_damage_helper
[402.788135] Starting dynamic subtest: drm_test_damage_iter_no_damage
[402.936605] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.146s)
[402.954090] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[403.035218] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.100s)
[403.044753] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[403.171620] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.134s)
[403.181624] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[403.321803] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.147s)
[403.332374] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[403.401642] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.079s)
[403.410707] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[403.562144] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.158s)
[403.572992] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[403.665654] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.103s)
[403.674430] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[403.790122] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.125s)
[403.800777] Starting dynamic subtest: drm_test_damage_iter_single_damage
[403.920849] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.128s)
[403.931647] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[404.012850] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.090s)
[404.021710] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[404.152532] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.140s)
[404.162801] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[404.296397] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.142s)
[404.307695] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[404.400811] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.103s)
[404.409435] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[404.557655] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.157s)
[404.567815] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[404.698566] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.140s)
[404.708580] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[404.782668] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.082s)
[404.791610] Starting dynamic subtest: drm_test_damage_iter_damage
[404.909683] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.126s)
[404.919712] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[405.044086] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.134s)
[405.053634] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[405.140766] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.093s)
[405.149399] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[405.271728] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.132s)
[405.282787] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[405.391795] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.119s)
[405.394679] Subtest drm_damage_helper: SUCCESS (2.625s)
[405.634295] [056/132] (618s left) gem_userptr_blits (unsync-overlap)
[405.710194] Subtest unsync-overlap: SKIP (0.000s)
[405.893709] [057/132] (617s left) kms_psr (pr-sprite-mmap-gtt)
[406.001484] Starting subtest: pr-sprite-mmap-gtt
[406.006293] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[406.098157] [058/132] (617s left) kms_psr (psr-sprite-mmap-gtt)
[406.195791] Starting subtest: psr-sprite-mmap-gtt
[406.198087] Starting dynamic subtest: eDP-1
[407.631763] Dynamic subtest eDP-1: SKIP (1.431s)
[407.637767] Subtest psr-sprite-mmap-gtt: SKIP (1.433s)
[407.828767] [059/132] (616s left) gem_exec_parallel (basic)
[407.893075] Starting subtest: basic
[407.895610] Starting dynamic subtest: rcs0
[409.577819] Dynamic subtest rcs0: SUCCESS (1.684s)
[409.588687] Starting dynamic subtest: bcs0
[411.273187] Dynamic subtest bcs0: SUCCESS (1.692s)
[411.282782] Starting dynamic subtest: vcs0
[413.152320] Dynamic subtest vcs0: SUCCESS (1.875s)
[413.167732] Starting dynamic subtest: vcs1
[415.136547] Dynamic subtest vcs1: SUCCESS (1.980s)
[415.147269] Starting dynamic subtest: vecs0
[417.133970] Dynamic subtest vecs0: SUCCESS (1.994s)
[417.146605] Starting dynamic subtest: ccs0
[418.955031] Dynamic subtest ccs0: SUCCESS (1.819s)
[418.957650] Subtest basic: SUCCESS (11.058s)
[419.164302] [060/132] (604s left) kms_content_protection (content-type-change)
[419.320325] Starting subtest: content-type-change
[419.325646] Subtest content-type-change: SKIP (0.000s)
[419.484046] [061/132] (604s left) gem_mmap_wc (write-read-distinct)
[419.555509] Subtest write-read-distinct: SKIP (0.000s)
[419.624043] [062/132] (604s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[419.738294] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[420.540403] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.804s)
[420.883055] [063/132] (603s left) gem_ctx_persistence (engines-mixed-process)
[420.945808] Starting subtest: engines-mixed-process
[420.948788] Starting dynamic subtest: rcs0
[422.201593] Dynamic subtest rcs0: SUCCESS (1.254s)
[422.213822] Starting dynamic subtest: bcs0
[424.179095] Dynamic subtest bcs0: SUCCESS (1.976s)
[424.190810] Starting dynamic subtest: vcs0
[426.157216] Dynamic subtest vcs0: SUCCESS (1.975s)
[426.169310] Starting dynamic subtest: vcs1
[428.132386] Dynamic subtest vcs1: SUCCESS (1.972s)
[428.143829] Starting dynamic subtest: vecs0
[430.209264] Dynamic subtest vecs0: SUCCESS (2.075s)
[430.221428] Starting dynamic subtest: ccs0
[432.083668] Dynamic subtest ccs0: SUCCESS (1.868s)
[432.086704] Subtest engines-mixed-process: SUCCESS (11.130s)
[432.217458] [064/132] (591s left) kms_chamelium_edid (vga-edid-read)
[432.616335] Subtest vga-edid-read: SKIP (0.000s)
[433.454823] [065/132] (590s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[433.584298] Starting subtest: 2x-long-flip-vs-cursor-atomic
[433.589292] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[433.754251] [066/132] (590s left) gem_exec_create (legacy)
[433.829522] Starting subtest: legacy
[433.832405] Starting dynamic subtest: smem
[435.915314] Dynamic subtest smem: SUCCESS (2.087s)
[435.918323] Subtest legacy: SUCCESS (2.089s)
[436.124854] [067/132] (588s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[436.231680] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[436.237690] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[436.346313] [068/132] (587s left) gem_softpin (zero)
[436.422198] Starting subtest: zero
[436.427360] Subtest zero: SUCCESS (0.005s)
[436.596026] [069/132] (587s left) gem_exec_flush (basic-wb-ro-before-default)
[436.660657] Starting subtest: basic-wb-ro-before-default
[442.118378] Subtest basic-wb-ro-before-default: SUCCESS (5.457s)
[442.335184] [070/132] (582s left) kms_big_fb (linear-64bpp-rotate-90)
[442.449564] Starting subtest: linear-64bpp-rotate-90
[442.470832] Subtest linear-64bpp-rotate-90: SKIP (0.021s)
[442.609956] [071/132] (581s left) gem_userptr_blits (set-cache-level)
[442.684120] Starting subtest: set-cache-level
[442.688616] Subtest set-cache-level: SKIP (0.000s)
[442.836649] [072/132] (581s left) perf_pmu (busy)
[442.878366] Starting subtest: busy
[442.880775] Starting dynamic subtest: rcs0
[443.479221] Dynamic subtest rcs0: SUCCESS (0.600s)
[443.492727] Starting dynamic subtest: bcs0
[444.052768] Dynamic subtest bcs0: SUCCESS (0.571s)
[444.065497] Starting dynamic subtest: vcs0
[445.151323] Dynamic subtest vcs0: SUCCESS (1.095s)
[445.163486] Starting dynamic subtest: vcs1
[447.137008] Dynamic subtest vcs1: SUCCESS (1.981s)
[447.150562] Starting dynamic subtest: vecs0
[449.185594] Dynamic subtest vecs0: SUCCESS (2.045s)
[449.199693] Starting dynamic subtest: ccs0
[449.751667] Dynamic subtest ccs0: SUCCESS (0.564s)
[449.754532] Subtest busy: SUCCESS (6.871s)
[449.872094] [073/132] (574s left) gem_basic (multigpu-create-close)
[449.928800] Starting subtest: multigpu-create-close
[449.934306] Subtest multigpu-create-close: SKIP (0.000s)
[450.067267] [074/132] (574s left) gen9_exec_parse (bb-secure)
[450.133768] Subtest bb-secure: SKIP (0.000s)
[450.221078] [075/132] (574s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[450.281797] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[450.284778] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[450.419048] [076/132] (574s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[455.541135] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[455.546775] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[456.420477] [077/132] (568s left) kms_lease (lease-invalid-crtc)
[456.516576] Starting subtest: lease-invalid-crtc
[456.519456] Starting dynamic subtest: pipe-A-eDP-1
[456.521920] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[456.531947] Starting dynamic subtest: pipe-B-eDP-1
[456.534786] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[456.544300] Starting dynamic subtest: pipe-C-eDP-1
[456.547048] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[456.556783] Starting dynamic subtest: pipe-D-eDP-1
[456.559688] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[456.562571] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[456.633160] [078/132] (568s left) gem_pwrite_snooped
[456.771965] [079/132] (567s left) gem_exec_reloc (basic-write-wc-noreloc)
[456.818488] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[456.899712] [080/132] (567s left) kms_flip (wf_vblank-ts-check-interruptible)
[457.001442] Starting subtest: wf_vblank-ts-check-interruptible
[457.004009] Starting dynamic subtest: A-eDP1
[465.942255] Dynamic subtest A-eDP1: SUCCESS (8.947s)
[465.952778] Starting dynamic subtest: B-eDP1
[474.870408] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[474.882554] Starting dynamic subtest: C-eDP1
[483.793081] Dynamic subtest C-eDP1: SUCCESS (8.925s)
[483.804117] Starting dynamic subtest: D-eDP1
[492.717576] Dynamic subtest D-eDP1: SUCCESS (8.925s)
[492.724099] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.739s)
[493.962623] [081/132] (530s left) gem_ctx_persistence (legacy-engines-queued)
[494.028374] Starting subtest: legacy-engines-queued
[494.031377] Starting dynamic subtest: render
[494.118529] Dynamic subtest render: SUCCESS (0.090s)
[494.131783] Starting dynamic subtest: bsd1
[496.127439] Dynamic subtest bsd1: SUCCESS (2.005s)
[496.140850] Starting dynamic subtest: bsd2
[498.111637] Dynamic subtest bsd2: SUCCESS (1.981s)
[498.125087] Starting dynamic subtest: blt
[498.158796] Dynamic subtest blt: SUCCESS (0.045s)
[498.169680] Starting dynamic subtest: vebox
[500.095136] Dynamic subtest vebox: SUCCESS (1.934s)
[500.098325] Subtest legacy-engines-queued: SUCCESS (6.068s)
[500.200874] [082/132] (524s left) gem_exercise_blt (fast-copy)
[500.265059] Starting subtest: fast-copy
[500.267783] Starting dynamic subtest: linear-smem-smem
[500.296774] Dynamic subtest linear-smem-smem: SUCCESS (0.031s)
[500.309533] Starting dynamic subtest: xmajor-smem-smem
[500.315234] Dynamic subtest xmajor-smem-smem: SUCCESS (0.018s)
[500.325086] Starting dynamic subtest: tile4-smem-smem
[500.332770] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[500.342627] Starting dynamic subtest: tile64-smem-smem
[500.351606] Dynamic subtest tile64-smem-smem: SUCCESS (0.018s)
[500.354394] Subtest fast-copy: SUCCESS (0.087s)
[500.507086] [083/132] (524s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[500.608579] Starting subtest: bo-too-small-due-to-tiling
[500.613983] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[500.760087] [084/132] (524s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[500.821705] Starting subtest: multi-wait-all-for-submit-signaled
[500.824645] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[500.952593] [085/132] (524s left) kms_cursor_crc (cursor-random-512x512)
[501.266208] Starting subtest: cursor-random-512x512
[501.270128] Subtest cursor-random-512x512: SKIP (0.001s)
[501.467092] [086/132] (523s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[501.533351] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[501.604583] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.005s)
[501.676816] [087/132] (523s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[501.783033] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[501.785118] Starting dynamic subtest: pipe-A-default-mode
[502.081455] Dynamic subtest pipe-A-default-mode: SKIP (0.295s)
[502.086521] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.299s)
[502.893489] [088/132] (522s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[503.000214] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[503.002564] Starting dynamic subtest: pipe-A-eDP-1
[503.027153] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[503.038100] Starting dynamic subtest: pipe-B-eDP-1
[503.062812] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[503.073035] Starting dynamic subtest: pipe-C-eDP-1
[503.098731] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[503.107817] Starting dynamic subtest: pipe-D-eDP-1
[503.133357] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[503.138380] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[505.256185] [089/132] (519s left) kms_plane_multiple (2x-tiling-none)
[505.357545] Starting subtest: 2x-tiling-none
[505.362513] Subtest 2x-tiling-none: SKIP (0.000s)
[505.454945] [090/132] (519s left) kms_psr (fbc-psr2-sprite-blt)
[505.555542] Starting subtest: fbc-psr2-sprite-blt
[505.558132] Starting dynamic subtest: eDP-1
[505.563430] Dynamic subtest eDP-1: SKIP (0.000s)
[505.568567] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[505.654022] [091/132] (519s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[505.756248] Starting subtest: y-tiled-16bpp-rotate-270
[505.761601] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[505.903182] [092/132] (519s left) i915_query (engine-info-invalid)
[505.968792] Starting subtest: engine-info-invalid
[505.971721] Subtest engine-info-invalid: SUCCESS (0.000s)
[506.103906] [093/132] (519s left) gem_ctx_param (invalid-size-set)
[506.163307] Starting subtest: invalid-size-set
[506.165626] Subtest invalid-size-set: SUCCESS (0.002s)
[506.293550] [094/132] (519s left) kms_fbcon_fbt (psr-suspend)
[506.346573] Starting subtest: psr-suspend
[555.698799] Subtest psr-suspend: SUCCESS (19.668s)
[555.783197] [095/132] (469s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[555.848187] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[555.957499] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[556.057472] [096/132] (469s left) kms_pm_rpm (universal-planes)
[557.999277] Starting subtest: universal-planes
[558.002017] Starting dynamic subtest: plane-33
[566.405201] Dynamic subtest plane-33: SUCCESS (8.405s)
[566.417305] Starting dynamic subtest: plane-63
[575.012071] Dynamic subtest plane-63: SUCCESS (8.600s)
[575.025506] Starting dynamic subtest: plane-93
[583.331212] Dynamic subtest plane-93: SUCCESS (8.313s)
[583.344573] Starting dynamic subtest: plane-123
[591.909013] Dynamic subtest plane-123: SUCCESS (8.577s)
[591.925228] Starting dynamic subtest: plane-133
[600.195289] Dynamic subtest plane-133: SUCCESS (8.284s)
[600.207339] Starting dynamic subtest: plane-143
[608.821561] Dynamic subtest plane-143: SUCCESS (8.623s)
[608.824167] Subtest universal-planes: SUCCESS (50.818s)
[609.227958] [097/132] (416s left) gem_exec_reloc (basic-write-read)
[609.284850] Subtest basic-write-read: SKIP (0.000s)
[609.357445] [098/132] (416s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[609.455913] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[609.460015] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[609.579649] [099/132] (416s left) kms_cursor_crc (cursor-onscreen-32x32)
[609.719792] Starting subtest: cursor-onscreen-32x32
[609.767210] Subtest cursor-onscreen-32x32: SKIP (0.043s)
[610.069556] [100/132] (415s left) syncobj_wait (invalid-wait-bad-flags)
[610.130680] Starting subtest: invalid-wait-bad-flags
[610.132660] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[610.258595] [101/132] (415s left) gem_exec_whisper (basic-contexts-forked-all)
[610.318873] Starting subtest: basic-contexts-forked-all
[630.798876] Subtest basic-contexts-forked-all: SUCCESS (20.473s)
[631.007748] [102/132] (394s left) gem_fenced_exec_thrash (2-spare-fences)
[631.075578] Subtest 2-spare-fences: SKIP (0.000s)
[631.150415] [103/132] (394s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[631.249705] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[631.253809] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[631.381865] [104/132] (394s left) api_intel_bb (add-remove-objects)
[631.435126] Starting subtest: add-remove-objects
[631.442632] Subtest add-remove-objects: SUCCESS (0.008s)
[631.585093] [105/132] (394s left) kms_vblank (invalid)
[631.684262] Starting subtest: invalid
[631.686176] Starting dynamic subtest: pipe-A-eDP-1
[632.082294] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.397s)
[632.084487] Subtest invalid: SUCCESS (0.398s)
[632.952660] [106/132] (392s left) gem_mmap_wc (bad-object)
[633.019428] Subtest bad-object: SKIP (0.000s)
[633.076179] [107/132] (392s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[633.173480] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[633.177295] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[633.300086] [108/132] (392s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[633.360765] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[633.447146] [109/132] (392s left) gem_tiled_fence_blits (normal)
[633.504303] Subtest normal: SKIP (0.000s)
[633.590657] [110/132] (392s left) gem_exec_alignment (pi)
[633.655987] Starting subtest: pi
[685.485216] Subtest pi: SUCCESS (51.823s)
[686.644116] [111/132] (339s left) gem_mmap (bad-object)
[686.751610] Subtest bad-object: SKIP (0.000s)
[686.837607] [112/132] (339s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[686.941072] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[686.943399] Starting dynamic subtest: pipe-A
[687.216396] Dynamic subtest pipe-A: SUCCESS (0.277s)
[687.227528] Starting dynamic subtest: pipe-B
[688.514940] Dynamic subtest pipe-B: SUCCESS (1.296s)
[688.525437] Starting dynamic subtest: pipe-C
[689.847348] Dynamic subtest pipe-C: SUCCESS (1.330s)
[689.858523] Starting dynamic subtest: pipe-D
[691.203330] Dynamic subtest pipe-D: SUCCESS (1.355s)
[691.205441] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.264s)
[692.402279] [113/132] (333s left) i915_hangman (engine-error-state-capture)
[692.479789] Starting subtest: engine-error-state-capture
[692.481666] Starting dynamic subtest: rcs0
[695.783466] Dynamic subtest rcs0: SUCCESS (3.303s)
[695.797081] Starting dynamic subtest: bcs0
[701.799970] Dynamic subtest bcs0: SUCCESS (6.013s)
[701.813801] Starting dynamic subtest: vcs0
[707.750627] Dynamic subtest vcs0: SUCCESS (5.947s)
[707.763384] Starting dynamic subtest: vcs1
[712.743202] Dynamic subtest vcs1: SUCCESS (4.989s)
[712.757581] Starting dynamic subtest: vecs0
[717.799520] Dynamic subtest vecs0: SUCCESS (5.053s)
[717.813130] Starting dynamic subtest: ccs0
[723.814965] Dynamic subtest ccs0: SUCCESS (6.011s)
[723.817955] Subtest engine-error-state-capture: SUCCESS (31.332s)
[723.923129] [114/132] (302s left) perf (low-oa-exponent-permissions)
[724.060863] Starting subtest: low-oa-exponent-permissions
[724.096346] Subtest low-oa-exponent-permissions: SUCCESS (0.036s)
[724.146067] [115/132] (302s left) gem_softpin (noreloc)
[724.206983] Starting subtest: noreloc
[729.626049] Subtest noreloc: SUCCESS (5.416s)
[729.768170] [116/132] (296s left) perf (enable-disable)
[729.868183] Starting subtest: enable-disable
[729.870459] Starting dynamic subtest: 0-rcs0
[732.155672] Dynamic subtest 0-rcs0: SUCCESS (2.285s)
[732.165955] Starting dynamic subtest: 1-vcs1
[736.628883] Dynamic subtest 1-vcs1: SUCCESS (4.471s)
[736.631756] Subtest enable-disable: SUCCESS (6.758s)
[736.678319] [117/132] (289s left) kms_pipe_crc_basic (nonblocking-crc)
[736.777733] Starting subtest: nonblocking-crc
[736.780073] Starting dynamic subtest: pipe-A-eDP-1
[737.547426] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.773s)
[737.564063] Starting dynamic subtest: pipe-B-eDP-1
[738.985792] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.435s)
[738.997136] Starting dynamic subtest: pipe-C-eDP-1
[740.365034] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.379s)
[740.377011] Starting dynamic subtest: pipe-D-eDP-1
[741.689601] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.320s)
[741.691946] Subtest nonblocking-crc: SUCCESS (4.913s)
[742.509733] [118/132] (283s left) sysfs_preempt_timeout (timeout)
[742.572591] Starting subtest: timeout
[742.574895] Starting dynamic subtest: vecs0
[751.810711] Dynamic subtest vecs0: SUCCESS (9.232s)
[751.823014] Starting dynamic subtest: ccs0
[753.190738] Dynamic subtest ccs0: SUCCESS (1.380s)
[753.203710] Starting dynamic subtest: vcs0
[762.846225] Dynamic subtest vcs0: SUCCESS (9.650s)
[762.859738] Starting dynamic subtest: bcs0
[764.230477] Dynamic subtest bcs0: SUCCESS (1.382s)
[764.244043] Starting dynamic subtest: rcs0
[765.582276] Dynamic subtest rcs0: SUCCESS (1.350s)
[765.595325] Starting dynamic subtest: vcs1
[774.828343] Dynamic subtest vcs1: SUCCESS (9.242s)
[774.830906] Subtest timeout: SUCCESS (32.247s)
[774.927207] [119/132] (251s left) gem_mmap_gtt (hang-busy)
[774.988998] Subtest hang-busy: SKIP (0.000s)
[775.059221] [120/132] (251s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[775.157434] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[775.161714] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[775.306552] [121/132] (251s left) i915_drm_fdinfo (all-busy-idle-check-all)
[775.351824] Starting subtest: all-busy-idle-check-all
[775.356210] Subtest all-busy-idle-check-all: SKIP (0.004s)
[775.450559] [122/132] (251s left) perf_pmu (init-sema)
[775.493051] Starting subtest: init-sema
[775.495292] Starting dynamic subtest: rcs0
[775.497473] Dynamic subtest rcs0: SUCCESS (0.001s)
[775.508316] Starting dynamic subtest: bcs0
[775.510378] Dynamic subtest bcs0: SUCCESS (0.000s)
[775.520428] Starting dynamic subtest: vcs0
[775.522500] Dynamic subtest vcs0: SUCCESS (0.000s)
[775.532522] Starting dynamic subtest: vcs1
[775.534323] Dynamic subtest vcs1: SUCCESS (0.000s)
[775.544024] Starting dynamic subtest: vecs0
[775.545866] Dynamic subtest vecs0: SUCCESS (0.000s)
[775.555672] Starting dynamic subtest: ccs0
[775.557514] Dynamic subtest ccs0: SUCCESS (0.000s)
[775.559313] Subtest init-sema: SUCCESS (0.007s)
[775.661851] [123/132] (250s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[775.763023] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[782.843663] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.083s)
[782.997040] [124/132] (243s left) gem_exec_schedule (fairslice)
[783.073111] Starting subtest: fairslice
[783.075494] Starting dynamic subtest: rcs0
[785.083344] Dynamic subtest rcs0: SUCCESS (2.018s)
[785.095304] Starting dynamic subtest: bcs0
[787.101629] Dynamic subtest bcs0: SUCCESS (2.017s)
[787.114800] Starting dynamic subtest: vcs0
[789.119420] Dynamic subtest vcs0: SUCCESS (2.018s)
[789.130910] Starting dynamic subtest: vcs1
[791.136205] Dynamic subtest vcs1: SUCCESS (2.016s)
[791.148468] Starting dynamic subtest: vecs0
[793.157878] Dynamic subtest vecs0: SUCCESS (2.017s)
[793.169144] Starting dynamic subtest: ccs0
[795.177059] Dynamic subtest ccs0: SUCCESS (2.022s)
[795.179052] Subtest fairslice: SUCCESS (12.114s)
[795.499451] [125/132] (231s left) gem_exec_reloc (basic-wc-cpu)
[795.564781] Subtest basic-wc-cpu: SKIP (0.000s)
[795.631525] [126/132] (231s left) syncobj_timeline (wait-all-for-submit-complex)
[795.687587] Starting subtest: wait-all-for-submit-complex
[795.900785] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[796.007405] [127/132] (230s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[796.137318] Starting subtest: cursorB-vs-flipB-legacy
[796.141967] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[796.302569] [128/132] (230s left) gem_exec_flush (basic-wb-set-default)
[796.358468] Starting subtest: basic-wb-set-default
[801.811190] Subtest basic-wb-set-default: SUCCESS (5.454s)
[802.027298] [129/132] (224s left) gem_pxp (reject-modify-context-protection-off-2)
[802.157652] Starting subtest: reject-modify-context-protection-off-2
[802.160283] Subtest reject-modify-context-protection-off-2: SUCCESS (0.001s)
[802.333108] [130/132] (224s left) api_intel_bb (misplaced-blitter)
[802.398932] Starting subtest: misplaced-blitter
[802.407747] Subtest misplaced-blitter: SUCCESS (0.011s)
[802.574161] [131/132] (224s left) kms_psr (fbc-pr-dpms)
[802.677021] Starting subtest: fbc-pr-dpms
[802.681308] Subtest fbc-pr-dpms: SKIP (0.000s)
[802.781020] [132/132] (224s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[802.884095] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[802.888337] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[803.016531] 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.