[150.837832] Initializing watchdogs
[150.837882]   /dev/watchdog0
[150.853682] [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]
[150.858326] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[150.861227] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[150.863865] [001/133] (960s left) dumb_buffer (create-clear)
[150.913669] Starting subtest: create-clear
[184.770274] Subtest create-clear: SUCCESS (33.806s)
[184.817789] [002/133] (926s left) kms_color_pipeline (plane-lut1d)
[184.922175] Starting subtest: plane-lut1d
[185.114229] Starting dynamic subtest: pipe-A-plane-0
[185.292224] Dynamic subtest pipe-A-plane-0: SUCCESS (0.178s)
[185.301637] Starting dynamic subtest: pipe-A-plane-1
[185.481566] Dynamic subtest pipe-A-plane-1: SUCCESS (0.189s)
[185.488266] Starting dynamic subtest: pipe-A-plane-2
[185.647928] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[186.825807] Starting dynamic subtest: pipe-B-plane-0
[186.997541] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s)
[187.006461] Starting dynamic subtest: pipe-B-plane-1
[187.163447] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[187.170092] Starting dynamic subtest: pipe-B-plane-2
[187.352627] Dynamic subtest pipe-B-plane-2: SUCCESS (0.189s)
[188.535909] Starting dynamic subtest: pipe-C-plane-0
[188.684081] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[188.692182] Starting dynamic subtest: pipe-C-plane-1
[188.851287] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[188.859029] Starting dynamic subtest: pipe-C-plane-2
[189.006166] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[190.200313] Starting dynamic subtest: pipe-D-plane-0
[190.360866] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[190.368515] Starting dynamic subtest: pipe-D-plane-1
[190.516769] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[190.523549] Starting dynamic subtest: pipe-D-plane-2
[190.672076] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[190.959446] Subtest plane-lut1d: SUCCESS (6.037s)
[191.744665] [003/133] (919s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[191.839744] Starting subtest: 2x-absolute-wf_vblank-interruptible
[191.851626] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[191.917529] [004/133] (919s left) gem_ctx_shared (q-smoketest-all)
[191.982473] Starting subtest: Q-smoketest-all
[224.345643] Subtest Q-smoketest-all: SUCCESS (32.334s)
[225.282384] [005/133] (886s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[225.331208] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[225.332150] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[225.425956] [006/133] (886s left) gen9_exec_parse (batch-without-end)
[225.475215] Subtest batch-without-end: SKIP (0.000s)
[225.548028] [007/133] (886s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[225.638842] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[225.640550] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[225.718241] [008/133] (885s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[225.809141] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[225.820775] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[225.917129] [009/133] (885s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[226.002629] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[232.723210] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.721s)
[232.816166] [010/133] (878s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[232.904248] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[232.905974] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[232.996650] [011/133] (878s left) perf (blocking)
[233.124919] Starting subtest: blocking
[233.125897] Starting dynamic subtest: 0-rcs0
[243.170507] Dynamic subtest 0-rcs0: SUCCESS (10.035s)
[243.179631] Starting dynamic subtest: 1-vcs1
[253.227605] Dynamic subtest 1-vcs1: SUCCESS (10.019s)
[253.229083] Subtest blocking: SUCCESS (20.056s)
[253.259550] [012/133] (858s left) gem_exec_schedule (preemptive-hang)
[253.445991] Starting subtest: preemptive-hang
[253.447568] Starting dynamic subtest: rcs0
[262.470704] Dynamic subtest rcs0: SUCCESS (9.025s)
[262.480784] Starting dynamic subtest: bcs0
[271.496081] Dynamic subtest bcs0: SUCCESS (9.024s)
[271.508060] Starting dynamic subtest: vcs0
[280.523586] Dynamic subtest vcs0: SUCCESS (9.026s)
[280.533913] Starting dynamic subtest: vcs1
[289.477699] Dynamic subtest vcs1: SUCCESS (8.953s)
[289.486264] Starting dynamic subtest: vecs0
[298.502339] Dynamic subtest vecs0: SUCCESS (9.024s)
[298.510706] Starting dynamic subtest: ccs0
[307.462088] Dynamic subtest ccs0: SUCCESS (8.958s)
[307.463541] Subtest preemptive-hang: SUCCESS (54.014s)
[307.697226] [013/133] (804s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[307.797070] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[307.799011] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[307.865228] [014/133] (803s left) prime_mmap (test_correct)
[307.910768] Starting subtest: test_correct
[307.911766] Starting dynamic subtest: test_correct-smem
[307.944681] Dynamic subtest test_correct-smem: SUCCESS (0.034s)
[307.946089] Subtest test_correct: SUCCESS (0.034s)
[308.033334] [015/133] (803s left) gem_mmap_offset (ptrace)
[308.078292] Starting subtest: ptrace
[308.081040] Starting dynamic subtest: smem0-wb
[308.100556] Dynamic subtest smem0-wb: SUCCESS (0.020s)
[308.109783] Starting dynamic subtest: smem0-wc
[308.118496] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[308.125823] Starting dynamic subtest: smem0-uc
[308.138609] Dynamic subtest smem0-uc: SUCCESS (0.020s)
[308.139907] Subtest ptrace: SUCCESS (0.061s)
[308.238027] [016/133] (803s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[308.327878] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[308.328843] Starting dynamic subtest: pipe-A-eDP-1
[319.028262] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.699s)
[319.037299] Starting dynamic subtest: pipe-B-eDP-1
[330.484201] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.456s)
[330.485045] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.157s)
[331.343935] [017/133] (780s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[331.457053] Starting subtest: cursor-vs-flip-atomic-transitions
[361.962045] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.504s)
[362.769037] [018/133] (749s left) i915_pm_freq_api (freq-suspend)
[362.830363] Starting subtest: freq-suspend
[362.831435] Starting dynamic subtest: gt0
[378.761654] Dynamic subtest gt0: SUCCESS (1.708s)
[378.772890] Starting dynamic subtest: gt1
[395.757832] Dynamic subtest gt1: SUCCESS (1.612s)
[395.758772] Subtest freq-suspend: SUCCESS (3.322s)
[401.855094] [019/133] (716s left) kms_lease (lease-invalid-connector)
[401.960579] Starting subtest: lease-invalid-connector
[401.961474] Starting dynamic subtest: pipe-A-eDP-1
[401.962351] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[401.971228] Starting dynamic subtest: pipe-B-eDP-1
[401.972176] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[401.980361] Starting dynamic subtest: pipe-C-eDP-1
[401.981269] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[401.989584] Starting dynamic subtest: pipe-D-eDP-1
[401.990497] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[401.991359] Subtest lease-invalid-connector: SUCCESS (0.003s)
[402.029159] [020/133] (715s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[402.124947] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[402.127941] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[402.190420] [021/133] (715s left) kms_invalid_mode (zero-vdisplay)
[402.286515] Starting subtest: zero-vdisplay
[402.287473] Starting dynamic subtest: pipe-A-eDP-1
[402.579683] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[402.587981] Starting dynamic subtest: pipe-B-eDP-1
[402.588931] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[402.596548] Starting dynamic subtest: pipe-C-eDP-1
[402.597423] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[402.605097] Starting dynamic subtest: pipe-D-eDP-1
[402.606050] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[402.606976] Subtest zero-vdisplay: SUCCESS (0.287s)
[403.409430] [022/133] (714s left) gem_ctx_exec (basic)
[403.471158] Starting subtest: basic
[403.480541] Subtest basic: SUCCESS (0.010s)
[403.592038] [023/133] (714s left) gem_create (busy-create)
[403.646672] Starting subtest: busy-create
[403.647881] Starting dynamic subtest: smem0
[436.868269] Dynamic subtest smem0: SUCCESS (32.965s)
[436.869737] Subtest busy-create: SUCCESS (32.966s)
[437.036950] [024/133] (681s left) gem_exec_nop (basic-series)
[437.098057] Starting subtest: basic-series
[452.936966] Subtest basic-series: SUCCESS (15.821s)
[453.110310] [025/133] (665s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[453.204982] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[453.207470] Starting dynamic subtest: A-eDP1
[456.810326] Dynamic subtest A-eDP1: SUCCESS (3.601s)
[456.821054] Starting dynamic subtest: B-eDP1
[460.020762] Dynamic subtest B-eDP1: SUCCESS (3.208s)
[460.031600] Starting dynamic subtest: C-eDP1
[463.152870] Dynamic subtest C-eDP1: SUCCESS (3.129s)
[463.161079] Starting dynamic subtest: D-eDP1
[466.247238] Dynamic subtest D-eDP1: SUCCESS (3.093s)
[466.250866] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.040s)
[467.037102] [026/133] (651s left) gem_render_copy (y-tiled-to-vebox-linear)
[467.101376] Starting subtest: y-tiled-to-vebox-linear
[467.103320] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[467.290597] [027/133] (650s left) kms_frontbuffer_tracking (psr-slowdraw)
[467.389118] Starting subtest: psr-slowdraw
[492.690856] Subtest psr-slowdraw: SUCCESS (25.290s)
[492.799057] [028/133] (625s left) perf (polling)
[492.895756] Starting subtest: polling
[492.896689] Starting dynamic subtest: 0-rcs0
[502.913458] Dynamic subtest 0-rcs0: SUCCESS (10.012s)
[502.923612] Starting dynamic subtest: 1-vcs1
[512.931667] Dynamic subtest 1-vcs1: SUCCESS (10.019s)
[512.932664] Subtest polling: SUCCESS (20.034s)
[512.968666] [029/133] (605s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[513.070223] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[513.071109] Starting dynamic subtest: pipe-A-eDP-1
[513.079637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[513.087760] Starting dynamic subtest: pipe-B-eDP-1
[513.095604] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[513.103521] Starting dynamic subtest: pipe-C-eDP-1
[513.111189] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[513.119058] Starting dynamic subtest: pipe-D-eDP-1
[513.126600] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[513.127458] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.006s)
[515.332916] [030/133] (602s left) perf_pmu (busy-accuracy-50)
[515.368430] Starting subtest: busy-accuracy-50
[515.369526] Starting dynamic subtest: rcs0
[516.906597] Dynamic subtest rcs0: SUCCESS (1.532s)
[516.919897] Starting dynamic subtest: bcs0
[518.448809] Dynamic subtest bcs0: SUCCESS (1.540s)
[518.460528] Starting dynamic subtest: vcs0
[519.990097] Dynamic subtest vcs0: SUCCESS (1.539s)
[519.999548] Starting dynamic subtest: vcs1
[521.529203] Dynamic subtest vcs1: SUCCESS (1.539s)
[521.540226] Starting dynamic subtest: vecs0
[523.074154] Dynamic subtest vecs0: SUCCESS (1.541s)
[523.085303] Starting dynamic subtest: ccs0
[524.622316] Dynamic subtest ccs0: SUCCESS (1.546s)
[524.623508] Subtest busy-accuracy-50: SUCCESS (9.250s)
[524.730743] [031/133] (593s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[524.828616] Starting subtest: fbcpsr-rgb101010-draw-blt
[531.831281] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (7.002s)
[531.937609] [032/133] (586s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[532.028046] Starting subtest: fbc-psr2-primary-mmap-gtt
[532.029140] Starting dynamic subtest: eDP-1
[532.030918] Dynamic subtest eDP-1: SKIP (0.000s)
[532.032516] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[532.107382] [033/133] (586s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[532.708581] Starting subtest: syncobj-timeline-invalid-wait
[532.709794] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[534.039539] [FACT gem_exec_fence (syncobj-timeline-invalid-wait)] new: kernel.kmod_is_loaded.vgem: true
[534.041145] [034/133] (584s left) kms_flip (plain-flip-interruptible)
[534.131193] Starting subtest: plain-flip-interruptible
[534.133497] Starting dynamic subtest: A-eDP1
[535.792039] Dynamic subtest A-eDP1: SUCCESS (1.658s)
[535.803744] Starting dynamic subtest: B-eDP1
[537.405911] Dynamic subtest B-eDP1: SUCCESS (1.611s)
[537.418243] Starting dynamic subtest: C-eDP1
[539.022910] Dynamic subtest C-eDP1: SUCCESS (1.614s)
[539.032906] Starting dynamic subtest: D-eDP1
[540.643458] Dynamic subtest D-eDP1: SUCCESS (1.619s)
[540.646797] Subtest plain-flip-interruptible: SUCCESS (6.512s)
[541.806944] [035/133] (576s left) api_intel_allocator (fork-simple-once)
[541.857117] Starting subtest: fork-simple-once
[541.871361] Subtest fork-simple-once: SUCCESS (0.015s)
[541.993595] [036/133] (576s left) kms_flip (flip-vs-blocking-wf-vblank)
[542.094799] Starting subtest: flip-vs-blocking-wf-vblank
[542.096949] Starting dynamic subtest: A-eDP1
[551.058220] Dynamic subtest A-eDP1: SUCCESS (8.960s)
[551.073355] Starting dynamic subtest: B-eDP1
[559.990805] Dynamic subtest B-eDP1: SUCCESS (8.929s)
[560.001858] Starting dynamic subtest: C-eDP1
[568.942815] Dynamic subtest C-eDP1: SUCCESS (8.910s)
[568.953636] Starting dynamic subtest: D-eDP1
[577.882102] Dynamic subtest D-eDP1: SUCCESS (8.934s)
[577.882934] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.743s)
[579.056717] [037/133] (539s left) kms_addfb_basic (addfb25-modifier-no-flag)
[579.142147] Starting subtest: addfb25-modifier-no-flag
[579.142909] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[579.250416] [038/133] (539s left) syncobj_basic (illegal-fd-to-handle)
[579.300129] Starting subtest: illegal-fd-to-handle
[579.301390] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[579.390746] [039/133] (539s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[579.492802] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[579.494545] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[579.591673] [040/133] (539s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[579.696188] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[579.698160] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[579.792121] [041/133] (538s left) syncobj_timeline (wait-any-snapshot)
[579.844619] Starting subtest: wait-any-snapshot
[579.926103] Subtest wait-any-snapshot: SUCCESS (0.081s)
[580.009095] [042/133] (538s left) syncobj_wait (invalid-reset-illegal-handle)
[580.059882] Starting subtest: invalid-reset-illegal-handle
[580.061039] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[580.189062] [043/133] (538s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[580.246688] Starting subtest: y-tiled-ccs-to-y-tiled
[580.249100] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[580.441937] [044/133] (538s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[580.523620] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[580.524503] Starting dynamic subtest: pipe-A-eDP-1
[580.533748] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[580.544752] Starting dynamic subtest: pipe-B-eDP-1
[580.553648] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[580.563017] Starting dynamic subtest: pipe-C-eDP-1
[580.571193] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[580.579769] Starting dynamic subtest: pipe-D-eDP-1
[580.588302] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[580.589916] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[582.833479] [045/133] (536s left) gem_exec_reloc (basic-cpu-gtt)
[582.890390] Subtest basic-cpu-gtt: SKIP (0.000s)
[582.947524] [046/133] (535s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[582.999424] Starting subtest: multi-wait-all-for-submit-submitted
[583.105332] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[583.178846] [047/133] (535s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[583.268008] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[583.269652] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[583.377512] [048/133] (535s left) gem_exec_balancer (bonded-pair)
[583.430816] Starting subtest: bonded-pair
[583.432605] Subtest bonded-pair: SKIP (0.000s)
[583.734940] [049/133] (535s left) perf (sysctl-defaults)
[583.798413] Starting subtest: sysctl-defaults
[583.799419] Subtest sysctl-defaults: SUCCESS (0.000s)
[583.866089] [050/133] (535s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[583.920510] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[584.024744] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[584.107773] [051/133] (534s left) kms_prime (d3hot)
[584.183910] Subtest D3hot: SKIP (0.000s)
[584.285485] [052/133] (534s left) gem_exec_reloc (basic-softpin)
[584.334813] Subtest basic-softpin: SKIP (0.000s)
[584.404921] [053/133] (534s left) kms_frontbuffer_tracking (fbc-suspend)
[584.492932] Starting subtest: fbc-suspend
[622.223272] Subtest fbc-suspend: SUCCESS (8.157s)
[628.479269] [054/133] (495s left) kms_dp_link_training (uhbr-mst)
[628.583658] Starting subtest: uhbr-mst
[628.587650] Subtest uhbr-mst: SKIP (0.000s)
[628.656147] [055/133] (495s left) gem_exec_schedule (preempt-user)
[628.717412] Starting subtest: preempt-user
[628.718365] Starting dynamic subtest: rcs0
[628.745267] Dynamic subtest rcs0: SUCCESS (0.028s)
[628.754344] Starting dynamic subtest: bcs0
[628.765723] Dynamic subtest bcs0: SUCCESS (0.020s)
[628.774494] Starting dynamic subtest: vcs0
[628.786902] Dynamic subtest vcs0: SUCCESS (0.020s)
[628.797543] Starting dynamic subtest: vcs1
[628.809253] Dynamic subtest vcs1: SUCCESS (0.022s)
[628.818869] Starting dynamic subtest: vecs0
[628.829651] Dynamic subtest vecs0: SUCCESS (0.019s)
[628.839217] Starting dynamic subtest: ccs0
[628.852420] Dynamic subtest ccs0: SUCCESS (0.021s)
[628.853479] Subtest preempt-user: SUCCESS (0.134s)
[630.596752] [056/133] (493s left) gem_render_copy (linear-to-vebox-y-tiled)
[630.655778] Starting subtest: linear-to-vebox-y-tiled
[630.657913] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[630.838652] [057/133] (493s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[630.919279] Starting subtest: plane-scaler-unity-scaling-with-rotation
[630.920234] Starting dynamic subtest: pipe-A
[631.153016] Dynamic subtest pipe-A: SUCCESS (0.228s)
[631.162810] Starting dynamic subtest: pipe-B
[632.507834] Dynamic subtest pipe-B: SUCCESS (1.317s)
[632.517798] Starting dynamic subtest: pipe-C
[633.877576] Dynamic subtest pipe-C: SUCCESS (1.337s)
[633.891272] Starting dynamic subtest: pipe-D
[635.220743] Dynamic subtest pipe-D: SUCCESS (1.317s)
[635.221659] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.201s)
[636.420828] [058/133] (487s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[636.508701] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[636.510422] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[636.602507] [059/133] (487s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[636.705981] Starting subtest: 4-tiled-addfb-size-offset-overflow
[636.707123] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[636.798295] [060/133] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[636.883165] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[636.884804] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[636.977937] [061/133] (487s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[637.123944] Starting subtest: dp-mst-type-1-suspend-resume
[637.125607] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[637.233062] [062/133] (487s left) kms_feature_discovery (display)
[637.335964] Starting subtest: display
[637.336892] Subtest display: SUCCESS (0.000s)
[637.407163] [063/133] (487s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[637.456569] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[637.565436] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.106s)
[637.651132] [064/133] (486s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[637.749887] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[637.751821] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[637.848910] [065/133] (486s left) gem_busy (semaphore)
[638.037879] Subtest semaphore: SKIP (0.000s)
[638.278236] [066/133] (486s left) i915_selftest (perf)
[642.959705] Starting subtest: perf
[642.960836] Starting dynamic subtest: engine_cs
[647.339253] Dynamic subtest engine_cs: SUCCESS (4.358s)
[647.347973] Starting dynamic subtest: request
[654.697511] Dynamic subtest request: SUCCESS (7.336s)
[654.707500] Starting dynamic subtest: migrate
[659.125336] Dynamic subtest migrate: SUCCESS (4.420s)
[659.135384] Starting dynamic subtest: region
[664.954667] Dynamic subtest region: SUCCESS (5.823s)
[664.955795] Subtest perf: SUCCESS (21.945s)
[664.978131] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[664.978866] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[664.980704] [067/133] (459s left) kms_plane_lowres (tiling-y)
[666.543219] Starting subtest: tiling-y
[666.545057] Subtest tiling-y: SKIP (0.000s)
[666.643644] [FACT kms_plane_lowres (tiling-y)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[666.644938] [FACT kms_plane_lowres (tiling-y)] new: kernel.kmod_is_loaded.i915: true
[666.648445] [068/133] (458s left) kms_psr (psr-primary-page-flip)
[666.753251] Starting subtest: psr-primary-page-flip
[666.754228] Starting dynamic subtest: eDP-1
[668.508356] Dynamic subtest eDP-1: SUCCESS (1.754s)
[668.509177] Subtest psr-primary-page-flip: SUCCESS (1.756s)
[669.374193] [069/133] (455s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[669.472307] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[669.474299] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[669.556447] [070/133] (455s left) gem_ctx_shared (detached-shared-gtt)
[669.610099] Starting subtest: detached-shared-gtt
[671.772257] Subtest detached-shared-gtt: SUCCESS (2.152s)
[672.134053] [071/133] (452s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[672.237642] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[672.238659] Starting dynamic subtest: pipe-A-eDP-1
[672.263016] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[672.272113] Starting dynamic subtest: pipe-B-eDP-1
[672.294008] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[672.302293] Starting dynamic subtest: pipe-C-eDP-1
[672.323236] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[672.331105] Starting dynamic subtest: pipe-D-eDP-1
[672.351932] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[672.353612] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[674.549241] [072/133] (450s left) kms_selftest (drm_plane_helper)
[674.582808] Starting subtest: drm_plane_helper
[674.595402] Starting dynamic subtest: drm_test_check_plane_state
[674.686324] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.091s)
[674.696517] Starting dynamic subtest: drm_test_check_invalid_plane_state
[674.768673] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.081s)
[674.769741] Subtest drm_plane_helper: SUCCESS (0.188s)
[674.928215] [073/133] (450s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[675.022299] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[675.024143] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[675.127712] [074/133] (449s left) gem_ctx_persistence (engines-cleanup)
[675.173144] Starting subtest: engines-cleanup
[675.174180] Starting dynamic subtest: rcs0
[675.247717] Dynamic subtest rcs0: SUCCESS (0.074s)
[675.263158] Starting dynamic subtest: bcs0
[675.301896] Dynamic subtest bcs0: SUCCESS (0.051s)
[675.314106] Starting dynamic subtest: vcs0
[675.340814] Dynamic subtest vcs0: SUCCESS (0.037s)
[675.352540] Starting dynamic subtest: vcs1
[675.381870] Dynamic subtest vcs1: SUCCESS (0.039s)
[675.392984] Starting dynamic subtest: vecs0
[675.432312] Dynamic subtest vecs0: SUCCESS (0.048s)
[675.443377] Starting dynamic subtest: ccs0
[675.481756] Dynamic subtest ccs0: SUCCESS (0.048s)
[675.482745] Subtest engines-cleanup: SUCCESS (0.309s)
[675.557603] [075/133] (449s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[675.644790] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[675.645684] Starting dynamic subtest: pipe-A-eDP-1
[677.922681] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.277s)
[677.932705] Starting dynamic subtest: pipe-B-eDP-1
[680.448310] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.523s)
[680.458190] Starting dynamic subtest: pipe-C-eDP-1
[682.728046] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.278s)
[682.738040] Starting dynamic subtest: pipe-D-eDP-1
[684.915497] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.185s)
[684.916513] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (9.268s)
[686.092090] [076/133] (438s left) gem_exec_reloc (basic-wc-gtt)
[686.156584] Subtest basic-wc-gtt: SKIP (0.000s)
[686.218138] [077/133] (438s left) gem_mmap_gtt (medium-copy)
[686.273676] Subtest medium-copy: SKIP (0.000s)
[686.336557] [078/133] (438s left) prime_vgem (fence-wait)
[686.397285] Starting subtest: fence-wait
[686.398304] Starting dynamic subtest: rcs0
[687.436293] Dynamic subtest rcs0: SUCCESS (1.029s)
[687.446847] Starting dynamic subtest: bcs0
[688.487417] Dynamic subtest bcs0: SUCCESS (1.049s)
[688.498134] Starting dynamic subtest: vcs0
[689.528718] Dynamic subtest vcs0: SUCCESS (1.039s)
[689.539052] Starting dynamic subtest: vcs1
[690.569864] Dynamic subtest vcs1: SUCCESS (1.038s)
[690.580743] Starting dynamic subtest: vecs0
[691.618815] Dynamic subtest vecs0: SUCCESS (1.046s)
[691.629488] Starting dynamic subtest: ccs0
[692.660073] Dynamic subtest ccs0: SUCCESS (1.039s)
[692.661262] Subtest fence-wait: SUCCESS (6.252s)
[692.797910] [079/133] (432s left) gem_reg_read (timestamp-monotonic)
[692.838394] Starting subtest: timestamp-monotonic
[697.858067] Subtest timestamp-monotonic: SUCCESS (4.973s)
[697.892187] [080/133] (427s left) kms_pm_lpsp (screens-disabled)
[698.982439] Starting subtest: screens-disabled
[698.984555] Subtest screens-disabled: SKIP (0.000s)
[699.071196] [081/133] (426s left) gem_exec_whisper (basic-queues-priority-all)
[699.125845] Starting subtest: basic-queues-priority-all
[722.643722] Subtest basic-queues-priority-all: SUCCESS (23.498s)
[722.830283] [082/133] (402s left) core_auth (many-magics)
[722.886335] Starting subtest: many-magics
[723.856778] Subtest many-magics: SUCCESS (0.971s)
[724.016279] [083/133] (401s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[724.061057] Starting subtest: multi-wait-all-for-submit-available-signaled
[724.061968] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[724.153303] [084/133] (401s left) gem_create (create-invalid-size)
[724.197678] Starting subtest: create-invalid-size
[724.198797] Subtest create-invalid-size: SUCCESS (0.000s)
[724.404212] [085/133] (400s left) gem_exec_reloc (basic-active)
[724.456720] Subtest basic-active: SKIP (0.000s)
[724.528726] [086/133] (400s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[724.913499] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[725.722270] [087/133] (399s left) i915_pm_rps (engine-order)
[725.781574] Starting subtest: engine-order
[732.025559] Subtest engine-order: SUCCESS (6.241s)
[732.130628] [088/133] (393s left) gem_exec_capture (capture-invisible)
[732.193141] Starting subtest: capture-invisible
[732.194138] Starting dynamic subtest: smem0
[732.195994] Dynamic subtest smem0: SKIP (0.000s)
[732.197789] Subtest capture-invisible: SKIP (0.001s)
[732.291369] [089/133] (393s left) gem_close (basic)
[732.351180] Starting subtest: basic
[732.352284] Subtest basic: SUCCESS (0.000s)
[732.469181] [090/133] (393s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[732.570466] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[733.219757] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.649s)
[733.398194] [091/133] (392s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[733.490445] Starting subtest: x-tiled-64bpp-rotate-0
[735.191173] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.701s)
[735.365831] [092/133] (390s left) kms_async_flips (invalid-async-flip-atomic)
[735.468925] Starting subtest: invalid-async-flip-atomic
[735.844371] Starting dynamic subtest: pipe-A-eDP-1
[736.671979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.828s)
[737.032875] Starting dynamic subtest: pipe-B-eDP-1
[737.820809] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.796s)
[738.179006] Starting dynamic subtest: pipe-C-eDP-1
[738.941580] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.771s)
[739.295785] Starting dynamic subtest: pipe-D-eDP-1
[740.073723] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.786s)
[740.074930] Subtest invalid-async-flip-atomic: SUCCESS (4.607s)
[741.211377] [093/133] (384s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[741.295873] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[741.297666] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[741.365345] [094/133] (384s left) gem_spin_batch (spin-all-new)
[741.425564] Starting subtest: spin-all-new
[741.445003] Subtest spin-all-new: SUCCESS (0.020s)
[743.534480] [095/133] (382s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[743.627211] Starting subtest: fbc-psr2-sprite-mmap-gtt
[743.628393] Starting dynamic subtest: eDP-1
[743.630435] Dynamic subtest eDP-1: SKIP (0.000s)
[743.632237] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[743.697646] [096/133] (382s left) kms_cursor_crc (cursor-offscreen-128x42)
[743.913560] Starting subtest: cursor-offscreen-128x42
[743.959626] Subtest cursor-offscreen-128x42: SKIP (0.045s)
[744.156201] [097/133] (381s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[744.547873] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[745.348410] [098/133] (380s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[745.450861] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[745.452828] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[745.547126] [099/133] (380s left) kms_psr (psr2-cursor-mmap-gtt)
[745.636990] Starting subtest: psr2-cursor-mmap-gtt
[745.637958] Starting dynamic subtest: eDP-1
[747.338718] Dynamic subtest eDP-1: SUCCESS (1.700s)
[747.339804] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.701s)
[748.137711] [100/133] (377s left) kms_psr (fbc-pr-no-drrs)
[748.217097] Starting subtest: fbc-pr-no-drrs
[748.219192] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[748.286783] [101/133] (377s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[748.377598] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[748.378453] Starting dynamic subtest: pipe-A-eDP-1
[748.388241] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[748.397863] Starting dynamic subtest: pipe-B-eDP-1
[748.408389] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[748.417861] Starting dynamic subtest: pipe-C-eDP-1
[748.427253] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[748.436067] Starting dynamic subtest: pipe-D-eDP-1
[748.445925] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[748.447618] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[750.715703] [102/133] (375s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[750.804935] Starting subtest: yf-tiled-8bpp-rotate-180
[750.806739] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[750.889193] [103/133] (375s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[750.977967] Starting subtest: compare-crc-sanitycheck-nv12
[750.979102] Starting dynamic subtest: pipe-A-eDP-1
[751.679618] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.701s)
[751.691436] Starting dynamic subtest: pipe-B-eDP-1
[753.018284] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.337s)
[753.029134] Starting dynamic subtest: pipe-C-eDP-1
[754.288008] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.266s)
[754.300309] Starting dynamic subtest: pipe-D-eDP-1
[755.527025] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[755.528090] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.550s)
[756.347000] [104/133] (369s left) gem_exec_params (cliprects-invalid)
[756.401014] Starting subtest: cliprects-invalid
[756.402195] Subtest cliprects-invalid: SUCCESS (0.000s)
[756.516079] [105/133] (369s left) gem_mmap_offset (clear)
[756.574522] Starting subtest: clear
[756.575706] Starting dynamic subtest: smem0
[785.106170] Dynamic subtest smem0: SUCCESS (28.518s)
[785.107326] Subtest clear: SUCCESS (28.520s)
[785.228378] [106/133] (340s left) kms_hdr (brightness-with-hdr)
[785.367490] Starting subtest: brightness-with-hdr
[785.368364] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[785.370030] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[785.379363] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[785.381247] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[785.382988] Subtest brightness-with-hdr: SKIP (0.003s)
[785.447559] [107/133] (340s left) kms_atomic_interruptible (legacy-dpms)
[785.548218] Starting subtest: legacy-dpms
[785.549180] Starting dynamic subtest: pipe-A-eDP-1
[792.990845] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.440s)
[792.991695] Subtest legacy-dpms: SUCCESS (7.441s)
[793.809609] [108/133] (332s left) i915_drm_fdinfo (virtual-busy-hang)
[793.847115] Starting subtest: virtual-busy-hang
[793.849043] Subtest virtual-busy-hang: SKIP (0.000s)
[793.905895] [109/133] (332s left) kms_big_fb (linear-32bpp-rotate-180)
[793.998479] Starting subtest: linear-32bpp-rotate-180
[795.624064] Subtest linear-32bpp-rotate-180: SUCCESS (1.625s)
[795.755600] [110/133] (330s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[795.846505] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[795.847488] Starting dynamic subtest: pipe-A
[795.910798] Dynamic subtest pipe-A: SUCCESS (0.065s)
[795.922681] Starting dynamic subtest: pipe-B
[797.034733] Dynamic subtest pipe-B: SUCCESS (1.123s)
[797.048109] Starting dynamic subtest: pipe-C
[798.139716] Dynamic subtest pipe-C: SUCCESS (1.103s)
[798.149557] Starting dynamic subtest: pipe-D
[799.262521] Dynamic subtest pipe-D: SUCCESS (1.122s)
[799.263532] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.417s)
[800.437553] [111/133] (325s left) kms_cursor_crc (cursor-random-max-size)
[800.731904] Starting subtest: cursor-random-max-size
[800.734582] Subtest cursor-random-max-size: SKIP (0.000s)
[800.864887] [112/133] (325s left) core_auth (getclient-master-drop)
[800.897342] Starting subtest: getclient-master-drop
[800.911975] Subtest getclient-master-drop: SUCCESS (0.015s)
[801.020168] [113/133] (325s left) perf (per-context-mode-unprivileged)
[801.154616] Starting subtest: per-context-mode-unprivileged
[801.156237] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[801.193982] [114/133] (325s left) gem_vm_create (invalid-create)
[801.242498] Starting subtest: invalid-create
[801.243383] Subtest invalid-create: SUCCESS (0.002s)
[801.340638] [115/133] (325s left) gem_lmem_swapping (random)
[801.372351] Subtest random: SKIP (0.000s)
[801.408190] [116/133] (325s left) kms_cursor_crc (cursor-suspend)
[801.525230] Starting subtest: cursor-suspend
[801.526202] Starting dynamic subtest: pipe-A-eDP-1
[836.250504] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.988s)
[836.261242] Starting dynamic subtest: pipe-D-eDP-1
[871.245709] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.544s)
[871.246720] Subtest cursor-suspend: SUCCESS (8.534s)
[878.075997] [117/133] (254s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[878.190601] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[885.253756] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.076s)
[885.386619] [118/133] (247s left) perf_pmu (frequency)
[885.424615] Starting subtest: frequency
[885.428306] Starting dynamic subtest: gt0
[887.542700] Dynamic subtest gt0: SUCCESS (2.114s)
[887.554475] Starting dynamic subtest: idle-gt0
[888.087171] Dynamic subtest idle-gt0: SUCCESS (0.534s)
[888.100677] Starting dynamic subtest: gt1
[892.685885] Dynamic subtest gt1: SUCCESS (4.597s)
[892.696559] Starting dynamic subtest: idle-gt1
[893.191818] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[893.192750] Subtest frequency: SUCCESS (7.767s)
[893.319662] [119/133] (239s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[893.413685] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[897.134846] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.722s)
[898.295725] [120/133] (234s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[898.389327] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[898.391001] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[898.492809] [121/133] (234s left) syncobj_timeline (signal-point-0)
[898.533317] Starting subtest: signal-point-0
[898.534245] Subtest signal-point-0: SUCCESS (0.000s)
[898.635860] [122/133] (234s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[898.728168] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[898.730118] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[898.830001] [123/133] (233s left) perf_pmu (most-busy-check-all)
[898.870832] Starting subtest: most-busy-check-all
[898.871793] Starting dynamic subtest: rcs0
[900.681536] Dynamic subtest rcs0: SUCCESS (1.800s)
[900.694972] Starting dynamic subtest: bcs0
[901.415782] Dynamic subtest bcs0: FAIL (0.673s)
[901.428061] Starting dynamic subtest: vcs0
[902.664806] Dynamic subtest vcs0: SUCCESS (1.307s)
[902.677473] Starting dynamic subtest: vcs1
[904.713676] Dynamic subtest vcs1: SUCCESS (2.046s)
[904.726163] Starting dynamic subtest: vecs0
[906.696725] Dynamic subtest vecs0: SUCCESS (1.981s)
[906.708710] Starting dynamic subtest: ccs0
[908.680866] Dynamic subtest ccs0: SUCCESS (1.982s)
[908.682046] Subtest most-busy-check-all: FAIL (9.803s)
[908.774375] [124/133] (224s left) gem_madvise (dontneed-before-exec)
[908.807819] Starting subtest: dontneed-before-exec
[908.830921] Subtest dontneed-before-exec: SKIP (0.023s)
[908.886112] [125/133] (223s left) kms_vblank (query-forked-busy-hang)
[908.995714] Starting subtest: query-forked-busy-hang
[908.996615] Starting dynamic subtest: pipe-A-eDP-1
[935.506536] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.513s)
[935.518960] Starting dynamic subtest: pipe-D-eDP-1
[960.451044] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.941s)
[960.452581] Subtest query-forked-busy-hang: SUCCESS (51.458s)
[961.244085] [126/133] (171s left) gem_exec_suspend (basic-s0)
[961.298637] Starting subtest: basic-S0
[961.299590] Starting dynamic subtest: smem
[981.324488] Dynamic subtest smem: SUCCESS (6.380s)
[981.325784] Subtest basic-S0: SUCCESS (6.383s)
[987.523394] [127/133] (151s left) kms_async_flips (crc)
[987.615564] Starting subtest: crc
[987.988595] Starting dynamic subtest: pipe-A-eDP-1
[990.948253] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.944s)
[991.331218] Starting dynamic subtest: pipe-B-eDP-1
[994.306173] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.975s)
[994.691240] Starting dynamic subtest: pipe-C-eDP-1
[997.615703] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.927s)
[997.993287] Starting dynamic subtest: pipe-D-eDP-1
[1000.869761] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.881s)
[1000.870748] Subtest crc: SUCCESS (13.214s)
[1002.029193] [128/133] (136s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[1002.128482] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[1002.130227] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[1002.232349] [129/133] (136s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[1002.319956] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[1002.321762] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[1002.438697] [130/133] (136s left) gem_linear_blits (interruptible)
[1002.643288] Starting subtest: interruptible
[1006.626614] Subtest interruptible: SUCCESS (3.971s)
[1006.747531] [131/133] (132s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[1006.890469] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[1013.978390] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.083s)
[1014.127888] [132/133] (125s left) kms_psr (pr-no-drrs)
[1014.218238] Starting subtest: pr-no-drrs
[1014.219993] Subtest pr-no-drrs: SKIP (0.000s)
[1014.288311] [133/133] (124s left) perf_pmu (all-busy-idle-check-all)
[1014.326345] Starting subtest: all-busy-idle-check-all
[1014.994166] Subtest all-busy-idle-check-all: FAIL (0.646s)
[1016.343696] Closing watchdogs
results: parsing output: 0/ for test: dumb_buffer
results: parsing output: 1/ for test: kms_color_pipeline
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: gem_ctx_shared
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: gen9_exec_parse
results: parsing output: 6/ for test: kms_flip_scaled_crc
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: perf
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: prime_mmap
results: parsing output: 14/ for test: gem_mmap_offset
results: parsing output: 15/ for test: kms_psr2_sf
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: i915_pm_freq_api
results: parsing output: 18/ for test: kms_lease
results: parsing output: 19/ for test: kms_plane
results: parsing output: 20/ for test: kms_invalid_mode
results: parsing output: 21/ for test: gem_ctx_exec
results: parsing output: 22/ for test: gem_create
results: parsing output: 23/ for test: gem_exec_nop
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: gem_render_copy
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: perf
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: perf_pmu
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_exec_fence
results: parsing output: 33/ for test: kms_flip
results: parsing output: 34/ for test: api_intel_allocator
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: syncobj_basic
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: gem_exec_reloc
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_exec_balancer
results: parsing output: 48/ for test: perf
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: kms_prime
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_dp_link_training
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: gem_render_copy
results: parsing output: 56/ for test: kms_plane_scaling
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_content_protection
results: parsing output: 61/ for test: kms_feature_discovery
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_busy
results: parsing output: 65/ for test: i915_selftest
results: parsing output: 66/ for test: kms_plane_lowres
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_ctx_shared
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: kms_selftest
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_ctx_persistence
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: gem_mmap_gtt
results: parsing output: 77/ for test: prime_vgem
results: parsing output: 78/ for test: gem_reg_read
results: parsing output: 79/ for test: kms_pm_lpsp
results: parsing output: 80/ for test: gem_exec_whisper
results: parsing output: 81/ for test: core_auth
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_create
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: kms_chamelium_hpd
results: parsing output: 86/ for test: i915_pm_rps
results: parsing output: 87/ for test: gem_exec_capture
results: parsing output: 88/ for test: gem_close
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_flip_scaled_crc
results: parsing output: 93/ for test: gem_spin_batch
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: kms_chamelium_edid
results: parsing output: 97/ for test: kms_flip_scaled_crc
results: parsing output: 98/ for test: kms_psr
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_ccs
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: kms_pipe_crc_basic
results: parsing output: 103/ for test: gem_exec_params
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: kms_hdr
results: parsing output: 106/ for test: kms_atomic_interruptible
results: parsing output: 107/ for test: i915_drm_fdinfo
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: kms_plane_scaling
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: core_auth
results: parsing output: 112/ for test: perf
results: parsing output: 113/ for test: gem_vm_create
results: parsing output: 114/ for test: gem_lmem_swapping
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: perf_pmu
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: gem_madvise
results: parsing output: 124/ for test: kms_vblank
results: parsing output: 125/ for test: gem_exec_suspend
results: parsing output: 126/ for test: kms_async_flips
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_linear_blits
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: perf_pmu
Done.