[65.271725] Initializing watchdogs
[65.271772]   /dev/watchdog0
[65.286393] [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]
[65.290847] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[65.292878] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[65.294572] [001/130] (960s left) gem_workarounds (basic-read)
[65.371567] Starting subtest: basic-read
[65.392076] Subtest basic-read: SUCCESS (0.021s)
[65.528277] [002/130] (960s left) gem_ppgtt (blt-vs-render-ctx0)
[65.587340] Starting subtest: blt-vs-render-ctx0
[98.552694] Subtest blt-vs-render-ctx0: SUCCESS (32.962s)
[98.674037] [003/130] (927s left) api_intel_bb (object-noreloc-keep-cache-simple)
[98.758062] Starting subtest: object-noreloc-keep-cache-simple
[98.768204] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.010s)
[98.906306] [004/130] (926s left) kms_universal_plane (cursor-fb-leak)
[99.009207] Starting subtest: cursor-fb-leak
[99.010085] Starting dynamic subtest: pipe-A-eDP-1
[99.335505] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.326s)
[99.342863] Starting dynamic subtest: pipe-B-eDP-1
[100.639148] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.303s)
[100.647413] Starting dynamic subtest: pipe-C-eDP-1
[101.965959] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.326s)
[101.972077] Starting dynamic subtest: pipe-D-eDP-1
[103.284250] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.316s)
[103.285170] Subtest cursor-fb-leak: SUCCESS (4.275s)
[104.460621] [005/130] (921s left) syncobj_basic (bad-fd-to-handle)
[104.513573] Starting subtest: bad-fd-to-handle
[104.514623] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[104.626372] [006/130] (921s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[104.720029] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[105.730748] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.011s)
[105.878923] [007/130] (920s left) kms_lease (possible-crtcs-filtering)
[105.972759] Starting subtest: possible-crtcs-filtering
[105.978594] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[106.079897] [008/130] (919s left) kms_cursor_crc (cursor-offscreen-64x64)
[106.239953] Starting subtest: cursor-offscreen-64x64
[106.242570] Starting dynamic subtest: pipe-A-eDP-1
[108.165043] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s)
[108.190554] Starting dynamic subtest: pipe-D-eDP-1
[110.764735] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.579s)
[110.765678] Subtest cursor-offscreen-64x64: SUCCESS (4.525s)
[111.647773] [009/130] (914s left) prime_vgem (wait)
[111.786404] Starting subtest: wait
[111.787388] Starting dynamic subtest: rcs0
[111.818933] Dynamic subtest rcs0: SUCCESS (0.032s)
[111.825166] Starting dynamic subtest: bcs0
[111.890062] Dynamic subtest bcs0: SUCCESS (0.071s)
[111.896274] Starting dynamic subtest: vcs0
[111.934841] Dynamic subtest vcs0: SUCCESS (0.044s)
[111.940738] Starting dynamic subtest: vcs1
[111.979941] Dynamic subtest vcs1: SUCCESS (0.045s)
[111.986181] Starting dynamic subtest: vecs0
[112.027322] Dynamic subtest vecs0: SUCCESS (0.047s)
[112.033343] Starting dynamic subtest: ccs0
[112.072217] Dynamic subtest ccs0: SUCCESS (0.045s)
[112.073041] Subtest wait: SUCCESS (0.286s)
[112.205916] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[112.207804] [010/130] (913s left) kms_flip (flip-vs-rmfb-interruptible)
[112.303451] Starting subtest: flip-vs-rmfb-interruptible
[112.305563] Starting dynamic subtest: A-eDP1
[120.923104] Dynamic subtest A-eDP1: SUCCESS (8.617s)
[120.931663] Starting dynamic subtest: B-eDP1
[129.547401] Dynamic subtest B-eDP1: SUCCESS (8.623s)
[129.555750] Starting dynamic subtest: C-eDP1
[138.174970] Dynamic subtest C-eDP1: SUCCESS (8.626s)
[138.184329] Starting dynamic subtest: D-eDP1
[146.800396] Dynamic subtest D-eDP1: SUCCESS (8.624s)
[146.803802] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.500s)
[147.988913] [011/130] (878s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[148.075241] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[148.076099] Starting dynamic subtest: pipe-A-eDP-1
[148.085724] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[148.092437] Starting dynamic subtest: pipe-B-eDP-1
[148.101810] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[148.107735] Starting dynamic subtest: pipe-C-eDP-1
[148.116582] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[148.122810] Starting dynamic subtest: pipe-D-eDP-1
[148.131829] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[148.133599] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[150.373704] [012/130] (875s left) kms_cursor_crc (cursor-size-change)
[150.452558] Starting subtest: cursor-size-change
[150.453544] Starting dynamic subtest: pipe-A-eDP-1
[151.248182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s)
[151.256162] Starting dynamic subtest: pipe-D-eDP-1
[152.692849] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.442s)
[152.693976] Subtest cursor-size-change: SUCCESS (2.241s)
[153.510927] [013/130] (872s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[153.605647] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[153.607455] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[153.735266] [014/130] (872s left) vgem_basic (dmabuf-fence)
[153.770178] Starting subtest: dmabuf-fence
[153.771192] Subtest dmabuf-fence: SUCCESS (0.001s)
[153.831217] [015/130] (872s left) template (a)
[153.883367] Starting subtest: A
[153.884180] Subtest A: SUCCESS (0.000s)
[154.005790] [016/130] (872s left) core_setmaster (master-drop-set-root)
[154.080471] Starting subtest: master-drop-set-root
[154.093547] Subtest master-drop-set-root: SUCCESS (0.013s)
[154.133838] [017/130] (872s left) gem_exec_capture (userptr)
[154.191400] Starting subtest: userptr
[155.039633] Subtest userptr: SUCCESS (0.847s)
[155.202221] [018/130] (871s left) gen9_exec_parse (cmd-crossing-page)
[155.253739] Subtest cmd-crossing-page: SKIP (0.000s)
[155.327513] [019/130] (871s left) kms_frontbuffer_tracking (psr-slowdraw)
[155.426708] Starting subtest: psr-slowdraw
[180.447705] Subtest psr-slowdraw: SUCCESS (25.020s)
[180.588937] [020/130] (845s left) kms_pm_dc (dc5-retention-flops)
[181.687475] Starting subtest: dc5-retention-flops
[181.689282] Subtest dc5-retention-flops: SKIP (0.000s)
[181.781214] [021/130] (844s left) i915_suspend (sysfs-reader)
[181.840363] Starting subtest: sysfs-reader
[200.710596] Subtest sysfs-reader: SUCCESS (3.642s)
[205.768573] [022/130] (825s left) gem_eio (in-flight-internal-10ms)
[206.018456] Starting subtest: in-flight-internal-10ms
[207.208380] Subtest in-flight-internal-10ms: SUCCESS (1.189s)
[207.373580] [023/130] (824s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[207.465355] Starting subtest: fbc-rgb565-draw-blt
[211.711408] Subtest fbc-rgb565-draw-blt: SUCCESS (4.243s)
[212.883990] [024/130] (818s left) i915_pm_rc6_residency (rc6-idle)
[212.937048] Starting subtest: rc6-idle
[212.980055] Starting dynamic subtest: gt0-rcs0
[237.427103] Dynamic subtest gt0-rcs0: SUCCESS (24.441s)
[237.437052] Starting dynamic subtest: gt0-bcs0
[261.799443] Dynamic subtest gt0-bcs0: SUCCESS (24.371s)
[261.809583] Starting dynamic subtest: gt0-ccs0
[286.155856] Dynamic subtest gt0-ccs0: SUCCESS (24.353s)
[286.165044] Starting dynamic subtest: gt1-vcs0
[292.329493] Dynamic subtest gt1-vcs0: SUCCESS (6.168s)
[292.339686] Starting dynamic subtest: gt1-vecs0
[298.501609] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[298.503366] Subtest rc6-idle: SUCCESS (85.560s)
[298.587743] [025/130] (732s left) i915_drm_fdinfo (most-busy-check-all)
[298.622094] Starting subtest: most-busy-check-all
[298.623132] Starting dynamic subtest: rcs0
[298.627727] Dynamic subtest rcs0: SKIP (0.005s)
[298.638052] Starting dynamic subtest: bcs0
[298.639761] Dynamic subtest bcs0: SKIP (0.000s)
[298.647832] Starting dynamic subtest: vcs0
[298.649483] Dynamic subtest vcs0: SKIP (0.000s)
[298.657305] Starting dynamic subtest: vcs1
[298.658935] Dynamic subtest vcs1: SKIP (0.000s)
[298.666520] Starting dynamic subtest: vecs0
[298.668259] Dynamic subtest vecs0: SKIP (0.000s)
[298.675930] Starting dynamic subtest: ccs0
[298.677703] Dynamic subtest ccs0: SKIP (0.000s)
[298.679465] Subtest most-busy-check-all: SKIP (0.010s)
[298.716757] [026/130] (732s left) gem_caching (read-writes)
[298.828610] Subtest read-writes: SKIP (0.000s)
[298.883875] [027/130] (732s left) gem_ctx_param (invalid-get-no-zeromap)
[298.939133] Starting subtest: invalid-get-no-zeromap
[298.939960] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[299.065706] [028/130] (732s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[299.160644] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[299.281435] [029/130] (732s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[299.361734] Starting subtest: psr-farfromfence-mmap-gtt
[299.363490] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[299.475793] [030/130] (732s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[299.565164] Starting subtest: plane-use-after-nonblocking-unbind
[299.566024] Starting dynamic subtest: pipe-A-eDP-1
[299.626884] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[299.919351] Starting dynamic subtest: pipe-B-eDP-1
[300.751449] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.839s)
[301.052708] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.487s)
[301.932908] [031/130] (729s left) gem_exec_schedule (semaphore-resolve)
[302.008389] Starting subtest: semaphore-resolve
[302.051700] Subtest semaphore-resolve: SUCCESS (0.043s)
[302.416120] [032/130] (729s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[302.509185] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[306.533298] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.025s)
[307.735547] [033/130] (724s left) kms_atomic_interruptible (legacy-pageflip)
[307.823050] Starting subtest: legacy-pageflip
[307.823919] Starting dynamic subtest: pipe-A-eDP-1
[315.223559] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.399s)
[315.224601] Subtest legacy-pageflip: SUCCESS (7.401s)
[316.075585] [034/130] (715s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[316.161690] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[323.630885] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.469s)
[323.795018] [035/130] (708s left) kms_psr (fbc-pr-sprite-render)
[323.878918] Starting subtest: fbc-pr-sprite-render
[323.880768] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[323.958030] [036/130] (707s left) gem_exec_balancer (bonded-sync)
[324.018444] Starting subtest: bonded-sync
[324.020455] Subtest bonded-sync: SKIP (0.000s)
[324.336113] [037/130] (707s left) gem_eio (create-ext)
[324.567824] Starting subtest: create-ext
[324.911404] Subtest create-ext: SUCCESS (0.344s)
[325.074272] [038/130] (706s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[325.164206] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[326.131118] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.967s)
[326.366043] [039/130] (705s left) gem_pxp (hw-rejects-pxp-context)
[326.489734] Starting subtest: hw-rejects-pxp-context
[326.492400] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[326.649973] [040/130] (705s left) kms_plane_multiple (tiling-none)
[326.734982] Starting subtest: tiling-none
[326.735910] Starting dynamic subtest: pipe-A-eDP-1
[330.419208] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.683s)
[330.428061] Starting dynamic subtest: pipe-B-eDP-1
[334.792587] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.373s)
[334.800979] Starting dynamic subtest: pipe-C-eDP-1
[339.142617] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.348s)
[339.151396] Starting dynamic subtest: pipe-D-eDP-1
[343.470597] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.325s)
[343.471515] Subtest tiling-none: SUCCESS (16.734s)
[344.296062] [041/130] (687s left) gem_pxp (create-valid-protected-context)
[344.346747] Starting subtest: create-valid-protected-context
[344.347694] Subtest create-valid-protected-context: SUCCESS (0.001s)
[344.489774] [042/130] (687s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[349.461562] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[349.464025] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s)
[350.301447] [043/130] (681s left) perf (non-sampling-read-error)
[350.431602] Starting subtest: non-sampling-read-error
[350.442151] Subtest non-sampling-read-error: SUCCESS (0.011s)
[350.473943] [044/130] (681s left) i915_pm_freq_api (freq-reset-multiple)
[350.530127] Starting subtest: freq-reset-multiple
[350.531152] Starting dynamic subtest: gt0
[360.511798] Dynamic subtest gt0: SUCCESS (9.979s)
[360.522113] Starting dynamic subtest: gt1
[368.961123] Dynamic subtest gt1: SUCCESS (8.448s)
[368.962704] Subtest freq-reset-multiple: SUCCESS (18.430s)
[369.057341] [045/130] (663s left) perf_pmu (init-wait)
[369.095330] Starting subtest: init-wait
[369.096252] Starting dynamic subtest: rcs0
[369.098273] Dynamic subtest rcs0: SUCCESS (0.002s)
[369.108473] Starting dynamic subtest: bcs0
[369.109444] Dynamic subtest bcs0: SUCCESS (0.001s)
[369.117684] Starting dynamic subtest: vcs0
[369.118818] Dynamic subtest vcs0: SUCCESS (0.001s)
[369.126860] Starting dynamic subtest: vcs1
[369.127861] Dynamic subtest vcs1: SUCCESS (0.001s)
[369.135921] Starting dynamic subtest: vecs0
[369.136907] Dynamic subtest vecs0: SUCCESS (0.001s)
[369.145303] Starting dynamic subtest: ccs0
[369.146543] Dynamic subtest ccs0: SUCCESS (0.000s)
[369.147587] Subtest init-wait: SUCCESS (0.008s)
[369.221364] [046/130] (663s left) kms_atomic_interruptible (universal-setplane-cursor)
[369.310683] Starting subtest: universal-setplane-cursor
[369.311562] Starting dynamic subtest: pipe-A-eDP-1
[376.710553] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.397s)
[376.711465] Subtest universal-setplane-cursor: SUCCESS (7.399s)
[377.564289] [047/130] (654s left) kms_content_protection (dp-mst-suspend-resume)
[377.696142] Starting subtest: dp-mst-suspend-resume
[377.698165] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[377.833733] [048/130] (654s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[377.914568] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[377.916469] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[378.031558] [049/130] (654s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[378.123109] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[378.124897] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[378.231303] [050/130] (654s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[378.277456] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[378.334403] [051/130] (654s left) gem_mmap_gtt (pf-nonblock)
[378.383862] Subtest pf-nonblock: SKIP (0.000s)
[378.440238] [052/130] (654s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[378.487587] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[378.489601] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[378.677441] [053/130] (653s left) gem_softpin (evict-prime-sanity-check)
[378.739947] Starting subtest: evict-prime-sanity-check
[378.741041] Starting dynamic subtest: rcs0
[379.903581] Dynamic subtest rcs0: SUCCESS (1.165s)
[379.915911] Starting dynamic subtest: bcs0
[381.037611] Dynamic subtest bcs0: SUCCESS (1.131s)
[381.051099] Starting dynamic subtest: vcs0
[382.192706] Dynamic subtest vcs0: SUCCESS (1.153s)
[382.206037] Starting dynamic subtest: vcs1
[384.170693] Dynamic subtest vcs1: SUCCESS (1.974s)
[384.181667] Starting dynamic subtest: vecs0
[386.114163] Dynamic subtest vecs0: SUCCESS (1.940s)
[386.124858] Starting dynamic subtest: ccs0
[387.277578] Dynamic subtest ccs0: SUCCESS (1.163s)
[387.289254] Starting dynamic subtest: all
[388.411596] Dynamic subtest all: SUCCESS (1.131s)
[388.413014] Subtest evict-prime-sanity-check: SUCCESS (9.674s)
[388.500571] [054/130] (644s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[388.593183] Starting subtest: dsc-fractional-bpp-with-bpc
[388.604437] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[388.677090] [055/130] (643s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[388.763780] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[388.764661] Starting dynamic subtest: pipe-A-default-mode
[389.074602] Dynamic subtest pipe-A-default-mode: SKIP (0.309s)
[389.077085] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.311s)
[389.898437] [056/130] (642s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[390.002934] Starting subtest: x-tiled-16bpp-rotate-270
[390.023186] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[390.146960] [057/130] (642s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[390.226995] Starting subtest: plane-primary-overlay-mutable-zpos
[393.628517] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.400s)
[394.822605] [058/130] (637s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[394.917690] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[394.919281] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[395.062114] [059/130] (637s left) sysfs_timeslice_duration (idempotent)
[395.134170] Starting subtest: idempotent
[395.135252] Starting dynamic subtest: vecs0
[395.136253] Dynamic subtest vecs0: SUCCESS (0.000s)
[395.146058] Starting dynamic subtest: ccs0
[395.147012] Dynamic subtest ccs0: SUCCESS (0.000s)
[395.155218] Starting dynamic subtest: vcs0
[395.156207] Dynamic subtest vcs0: SUCCESS (0.000s)
[395.164383] Starting dynamic subtest: bcs0
[395.165361] Dynamic subtest bcs0: SUCCESS (0.000s)
[395.173689] Starting dynamic subtest: rcs0
[395.174634] Dynamic subtest rcs0: SUCCESS (0.000s)
[395.182760] Starting dynamic subtest: vcs1
[395.183711] Dynamic subtest vcs1: SUCCESS (0.000s)
[395.184770] Subtest idempotent: SUCCESS (0.006s)
[395.265761] [060/130] (637s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[395.356878] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[395.357890] Starting dynamic subtest: pipe-A-eDP-1
[395.505681] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.145s)
[395.515016] Starting dynamic subtest: pipe-B-eDP-1
[395.523176] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[395.531491] Starting dynamic subtest: pipe-C-eDP-1
[395.539117] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[395.547189] Starting dynamic subtest: pipe-D-eDP-1
[395.555474] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[395.556399] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.180s)
[397.793553] [061/130] (635s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[397.881009] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[397.881802] Starting dynamic subtest: pipe-A
[397.897559] Dynamic subtest pipe-A: SKIP (0.015s)
[397.907123] Starting dynamic subtest: pipe-B
[397.925413] Dynamic subtest pipe-B: SKIP (0.027s)
[397.934113] Starting dynamic subtest: pipe-C
[397.952843] Dynamic subtest pipe-C: SKIP (0.027s)
[397.960920] Starting dynamic subtest: pipe-D
[397.980721] Dynamic subtest pipe-D: SKIP (0.027s)
[397.982416] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.099s)
[398.103680] [062/130] (634s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[398.194834] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[405.448727] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.255s)
[405.604374] [063/130] (627s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[405.696667] Starting subtest: fbc-psr-cursor-mmap-cpu
[405.697643] Starting dynamic subtest: eDP-1
[405.699406] Dynamic subtest eDP-1: SKIP (0.000s)
[405.701221] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[405.775531] [064/130] (627s left) kms_vblank (ts-continuation-dpms-rpm)
[405.869502] Starting subtest: ts-continuation-dpms-rpm
[405.870459] Starting dynamic subtest: pipe-A-eDP-1
[408.410461] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.540s)
[408.420483] Starting dynamic subtest: pipe-D-eDP-1
[410.655312] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.241s)
[410.656464] Subtest ts-continuation-dpms-rpm: SUCCESS (4.786s)
[411.446944] [065/130] (621s left) gem_busy (busy)
[411.514121] Starting subtest: busy
[411.515124] Starting dynamic subtest: all-engines
[411.579560] Dynamic subtest all-engines: SUCCESS (0.065s)
[411.590415] Starting dynamic subtest: rcs0
[413.146652] Dynamic subtest rcs0: SUCCESS (1.564s)
[413.157566] Starting dynamic subtest: bcs0
[413.203449] Dynamic subtest bcs0: SUCCESS (0.056s)
[413.213337] Starting dynamic subtest: vcs0
[413.242065] Dynamic subtest vcs0: SUCCESS (0.038s)
[413.251142] Starting dynamic subtest: vcs1
[415.128056] Dynamic subtest vcs1: SUCCESS (1.881s)
[415.138008] Starting dynamic subtest: vecs0
[417.176262] Dynamic subtest vecs0: SUCCESS (2.048s)
[417.186453] Starting dynamic subtest: ccs0
[419.160196] Dynamic subtest ccs0: SUCCESS (1.983s)
[419.161462] Subtest busy: SUCCESS (7.645s)
[419.539706] [066/130] (613s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[419.646863] Starting subtest: 4-tiled-8bpp-rotate-0
[420.758702] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.112s)
[420.912070] [067/130] (612s left) gem_exec_gttfill (multigpu-basic)
[421.083404] Starting subtest: multigpu-basic
[436.229238] Subtest multigpu-basic: SUCCESS (15.145s)
[436.338446] [068/130] (596s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[441.431352] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[441.434931] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[442.286929] [069/130] (590s left) kms_psr (pr-sprite-blt)
[442.380740] Starting subtest: pr-sprite-blt
[442.382404] Subtest pr-sprite-blt: SKIP (0.000s)
[442.472546] [070/130] (590s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[442.567110] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[442.568985] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[442.678238] [071/130] (590s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[442.742000] Starting subtest: legacy-engines-hostile-preempt
[442.742937] Starting dynamic subtest: render
[442.823450] Dynamic subtest render: SUCCESS (0.080s)
[442.837011] Starting dynamic subtest: bsd1
[444.137566] Dynamic subtest bsd1: SUCCESS (1.309s)
[444.149160] Starting dynamic subtest: bsd2
[446.184448] Dynamic subtest bsd2: SUCCESS (2.044s)
[446.195204] Starting dynamic subtest: blt
[446.267913] Dynamic subtest blt: SUCCESS (0.083s)
[446.279134] Starting dynamic subtest: vebox
[448.166548] Dynamic subtest vebox: SUCCESS (1.893s)
[448.168128] Subtest legacy-engines-hostile-preempt: SUCCESS (5.423s)
[448.258618] [072/130] (585s left) kms_psr (psr2-sprite-plane-move)
[448.355072] Starting subtest: psr2-sprite-plane-move
[448.356018] Starting dynamic subtest: eDP-1
[450.094013] Dynamic subtest eDP-1: SUCCESS (1.737s)
[450.094982] Subtest psr2-sprite-plane-move: SUCCESS (1.738s)
[450.951773] [073/130] (582s left) kms_invalid_mode (overflow-vrefresh)
[451.048023] Starting subtest: overflow-vrefresh
[451.048967] Starting dynamic subtest: pipe-A-eDP-1
[451.338252] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[451.346960] Starting dynamic subtest: pipe-B-eDP-1
[451.347870] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[451.356125] Starting dynamic subtest: pipe-C-eDP-1
[451.357064] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[451.366124] Starting dynamic subtest: pipe-D-eDP-1
[451.367692] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[451.369211] Subtest overflow-vrefresh: SUCCESS (0.296s)
[452.173324] [074/130] (581s left) gem_userptr_blits (input-checking)
[452.227430] Starting subtest: input-checking
[452.228644] Subtest input-checking: SUCCESS (0.000s)
[452.394625] [075/130] (580s left) gem_eio (wait-wedge-10ms)
[452.624359] Starting subtest: wait-wedge-10ms
[454.234054] Subtest wait-wedge-10ms: SUCCESS (1.609s)
[454.379023] [076/130] (579s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[454.473108] Starting subtest: plane-upscale-20x20-with-pixel-format
[454.474043] Starting dynamic subtest: pipe-A
[455.521675] Dynamic subtest pipe-A: SUCCESS (1.050s)
[455.530452] Starting dynamic subtest: pipe-B
[457.682992] Dynamic subtest pipe-B: SUCCESS (2.160s)
[457.691374] Starting dynamic subtest: pipe-C
[458.886924] Dynamic subtest pipe-C: SUCCESS (1.203s)
[458.896991] Starting dynamic subtest: pipe-D
[460.019340] Dynamic subtest pipe-D: SUCCESS (1.131s)
[460.020412] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.548s)
[461.207197] [077/130] (572s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[461.312668] Starting subtest: y-tiled-16bpp-rotate-90
[461.314405] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[461.434611] [078/130] (572s left) syncobj_wait (reset-unsignaled)
[461.493366] Starting subtest: reset-unsignaled
[461.494374] Subtest reset-unsignaled: SUCCESS (0.000s)
[461.623810] [079/130] (571s left) perf_pmu (all-busy-idle-check-all)
[461.657761] Starting subtest: all-busy-idle-check-all
[463.125128] Subtest all-busy-idle-check-all: SUCCESS (1.464s)
[463.255625] [080/130] (570s left) syncobj_wait (invalid-wait-illegal-handle)
[463.308645] Starting subtest: invalid-wait-illegal-handle
[463.309690] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[463.434151] [081/130] (570s left) gem_mmap_gtt (coherency)
[463.487807] Subtest coherency: SKIP (0.000s)
[463.550848] [082/130] (570s left) kms_chamelium_frames (dp-frame-dump)
[463.928681] Subtest dp-frame-dump: SKIP (0.000s)
[464.706960] [083/130] (568s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[464.761655] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[464.871835] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[464.955058] [084/130] (568s left) syncobj_wait (wait-delayed-signal)
[465.016723] Starting subtest: wait-delayed-signal
[465.121676] Subtest wait-delayed-signal: SUCCESS (0.102s)
[465.213014] [085/130] (568s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[465.306219] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[465.308886] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[465.427514] [086/130] (568s left) kms_cdclk (mode-transition)
[465.512786] Starting subtest: mode-transition
[465.513743] Starting dynamic subtest: pipe-A-eDP-1
[465.809908] Dynamic subtest pipe-A-eDP-1: SKIP (0.295s)
[465.818326] Starting dynamic subtest: pipe-B-eDP-1
[465.820144] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[465.828451] Starting dynamic subtest: pipe-C-eDP-1
[465.830308] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[465.838534] Starting dynamic subtest: pipe-D-eDP-1
[465.840321] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[465.842125] Subtest mode-transition: SKIP (0.303s)
[466.679372] [087/130] (567s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[466.778991] Starting subtest: crc-primary-suspend-y-tiled-ccs
[466.779876] Starting dynamic subtest: pipe-A-eDP-1
[466.783493] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.792095] Starting dynamic subtest: pipe-B-eDP-1
[466.795690] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.804144] Starting dynamic subtest: pipe-C-eDP-1
[466.807920] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.816171] Starting dynamic subtest: pipe-D-eDP-1
[466.819721] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.821470] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[469.039654] [088/130] (564s left) kms_pm_rpm (modeset-non-lpsp-stress)
[471.272840] Starting subtest: modeset-non-lpsp-stress
[472.440247] Subtest modeset-non-lpsp-stress: SKIP (1.166s)
[472.845940] [089/130] (560s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[472.933237] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[479.608088] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.675s)
[479.754804] [090/130] (554s left) kms_psr2_su (frontbuffer-xrgb8888)
[479.816540] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[479.877787] [091/130] (554s left) kms_color (ctm-blue-to-red)
[479.969584] Starting subtest: ctm-blue-to-red
[479.970478] Starting dynamic subtest: pipe-A-eDP-1
[480.648096] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.678s)
[480.656674] Starting dynamic subtest: pipe-B-eDP-1
[482.022518] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.375s)
[482.031143] Starting dynamic subtest: pipe-C-eDP-1
[483.308755] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.285s)
[483.317234] Starting dynamic subtest: pipe-D-eDP-1
[484.628775] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.320s)
[484.629692] Subtest ctm-blue-to-red: SUCCESS (4.661s)
[485.488604] [092/130] (548s left) gem_tiled_blits (basic)
[485.534371] Subtest basic: SKIP (0.000s)
[485.625548] [093/130] (548s left) kms_fbcon_fbt (fbc)
[485.679522] Starting subtest: fbc
[492.016270] Subtest fbc: SUCCESS (6.333s)
[493.095326] [094/130] (540s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[493.204177] Starting subtest: 2x-flip-vs-cursor-legacy
[493.207041] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[493.367815] [095/130] (540s left) syncobj_timeline (invalid-transfer-non-existent-point)
[493.420392] Starting subtest: invalid-transfer-non-existent-point
[493.421341] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[493.543014] [096/130] (540s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[493.641958] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[493.643687] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[493.756078] [097/130] (540s left) gem_exec_fence (parallel)
[493.924766] Starting subtest: parallel
[493.925861] Starting dynamic subtest: rcs0
[496.077082] Dynamic subtest rcs0: SUCCESS (2.153s)
[496.087010] Starting dynamic subtest: bcs0
[498.226785] Dynamic subtest bcs0: SUCCESS (2.149s)
[498.236809] Starting dynamic subtest: vcs0
[500.383956] Dynamic subtest vcs0: SUCCESS (2.156s)
[500.394004] Starting dynamic subtest: vcs1
[502.533289] Dynamic subtest vcs1: SUCCESS (2.148s)
[502.550287] Starting dynamic subtest: vecs0
[504.686005] Dynamic subtest vecs0: SUCCESS (2.151s)
[504.695781] Starting dynamic subtest: ccs0
[506.840604] Dynamic subtest ccs0: SUCCESS (2.154s)
[506.841768] Subtest parallel: SUCCESS (12.917s)
[507.464349] [098/130] (526s left) gem_create (create-massive)
[507.511157] Starting subtest: create-massive
[507.512098] Subtest create-massive: SUCCESS (0.000s)
[507.712879] [099/130] (526s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[507.811009] Starting subtest: psr-indfb-scaledprimary
[513.632679] Subtest psr-indfb-scaledprimary: SUCCESS (5.822s)
[513.752800] [100/130] (520s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[513.848896] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[513.849696] Starting dynamic subtest: pipe-A-eDP-1
[514.075867] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.215s)
[514.084987] Starting dynamic subtest: pipe-B-eDP-1
[515.285854] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.209s)
[515.294944] Starting dynamic subtest: pipe-C-eDP-1
[516.502614] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.215s)
[516.512280] Starting dynamic subtest: pipe-D-eDP-1
[517.730269] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[517.731099] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.872s)
[518.889288] [101/130] (515s left) gem_exec_schedule (u-submit-golden-slice)
[518.948351] Starting subtest: u-submit-golden-slice
[518.949394] Starting dynamic subtest: rcs0
[518.986781] Dynamic subtest rcs0: SUCCESS (0.038s)
[518.996439] Starting dynamic subtest: bcs0
[519.024009] Dynamic subtest bcs0: SUCCESS (0.037s)
[519.033601] Starting dynamic subtest: vcs0
[519.055855] Dynamic subtest vcs0: SUCCESS (0.031s)
[519.067373] Starting dynamic subtest: vcs1
[519.088588] Dynamic subtest vcs1: SUCCESS (0.032s)
[519.098609] Starting dynamic subtest: vecs0
[519.117136] Dynamic subtest vecs0: SUCCESS (0.028s)
[519.125808] Starting dynamic subtest: ccs0
[519.150152] Dynamic subtest ccs0: SUCCESS (0.032s)
[519.151137] Subtest u-submit-golden-slice: SUCCESS (0.202s)
[520.258999] [102/130] (514s left) syncobj_eventfd (invalid-illegal-eventfd)
[520.310064] Starting subtest: invalid-illegal-eventfd
[520.311023] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[520.414080] [103/130] (513s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[520.509079] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[520.510725] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[520.616296] [104/130] (513s left) kms_force_connector_basic (force-load-detect)
[520.689814] Starting subtest: force-load-detect
[520.692255] Subtest force-load-detect: SKIP (0.000s)
[520.816784] [105/130] (513s left) api_intel_allocator (reopen)
[520.875761] Starting subtest: reopen
[520.879588] Subtest reopen: SUCCESS (0.005s)
[521.020015] [106/130] (513s left) kms_frontbuffer_tracking (psr-2p-rte)
[521.103034] Starting subtest: psr-2p-rte
[521.104788] Subtest psr-2p-rte: SKIP (0.000s)
[521.228185] [107/130] (513s left) perf_pmu (busy-double-start)
[521.259561] Starting subtest: busy-double-start
[521.260451] Starting dynamic subtest: rcs0
[524.861280] Dynamic subtest rcs0: FAIL (3.581s)
[524.870431] Starting dynamic subtest: bcs0
[528.462256] Dynamic subtest bcs0: SUCCESS (3.620s)
[528.475578] Starting dynamic subtest: vcs0
[532.684402] Dynamic subtest vcs0: SUCCESS (4.219s)
[532.697747] Starting dynamic subtest: vcs1
[536.700296] Dynamic subtest vcs1: SUCCESS (4.013s)
[536.712140] Starting dynamic subtest: vecs0
[540.668955] Dynamic subtest vecs0: SUCCESS (3.967s)
[540.681709] Starting dynamic subtest: ccs0
[544.268507] Dynamic subtest ccs0: FAIL (3.567s)
[544.269401] Subtest busy-double-start: FAIL (22.978s)
[544.372643] [108/130] (490s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[544.584021] Starting subtest: cursor-rapid-movement-128x42
[544.623028] Subtest cursor-rapid-movement-128x42: SKIP (0.038s)
[544.856194] [109/130] (489s left) kms_3d (basic)
[544.903841] Starting subtest: basic
[546.854417] Subtest basic: SUCCESS (1.952s)
[547.391119] [110/130] (487s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[547.494686] Starting subtest: primary-4-tiled-reflect-x-180
[554.429198] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.935s)
[555.622710] [111/130] (478s left) kms_cursor_edge_walk (64x64-top-edge)
[555.709344] Starting subtest: 64x64-top-edge
[555.710305] Starting dynamic subtest: pipe-A-eDP-1
[559.147590] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.438s)
[559.158066] Starting dynamic subtest: pipe-D-eDP-1
[562.329730] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.180s)
[562.330612] Subtest 64x64-top-edge: SUCCESS (6.621s)
[563.139144] [112/130] (471s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[563.224177] Starting subtest: fbcpsr-modesetfrombusy
[569.309436] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.086s)
[569.487247] [113/130] (465s left) gem_exec_schedule (preempt-engines)
[569.557410] Starting subtest: preempt-engines
[569.558429] Starting dynamic subtest: rcs0
[571.869998] Dynamic subtest rcs0: SUCCESS (2.309s)
[571.881058] Starting dynamic subtest: bcs0
[574.093082] Dynamic subtest bcs0: SUCCESS (2.221s)
[574.104058] Starting dynamic subtest: vcs0
[576.359269] Dynamic subtest vcs0: SUCCESS (2.265s)
[576.370467] Starting dynamic subtest: vcs1
[578.696366] Dynamic subtest vcs1: SUCCESS (2.336s)
[578.711296] Starting dynamic subtest: vecs0
[580.949920] Dynamic subtest vecs0: SUCCESS (2.252s)
[580.961857] Starting dynamic subtest: ccs0
[583.263112] Dynamic subtest ccs0: SUCCESS (2.311s)
[583.264323] Subtest preempt-engines: SUCCESS (13.706s)
[583.587677] [114/130] (451s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[583.689519] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[583.690364] Starting dynamic subtest: pipe-A-default-mode
[583.977231] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[583.978995] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.288s)
[584.815760] [115/130] (449s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[584.899838] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[588.859983] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (3.961s)
[590.035010] [116/130] (444s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[590.126728] Starting subtest: flip-vs-dpms-off-vs-modeset
[590.128810] Starting dynamic subtest: A-eDP1
[593.752458] Dynamic subtest A-eDP1: SUCCESS (3.623s)
[593.761155] Starting dynamic subtest: B-eDP1
[597.038302] Dynamic subtest B-eDP1: SUCCESS (3.285s)
[597.047246] Starting dynamic subtest: C-eDP1
[600.334947] Dynamic subtest C-eDP1: SUCCESS (3.296s)
[600.345943] Starting dynamic subtest: D-eDP1
[603.635504] Dynamic subtest D-eDP1: SUCCESS (3.300s)
[603.638138] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.511s)
[604.528209] [117/130] (430s left) gem_mmap_gtt (big-bo-tiledy)
[604.583785] Subtest big-bo-tiledY: SKIP (0.000s)
[604.655016] [118/130] (430s left) kms_flip (plain-flip-fb-recreate-interruptible)
[604.749542] Starting subtest: plain-flip-fb-recreate-interruptible
[604.751836] Starting dynamic subtest: A-eDP1
[613.579888] Dynamic subtest A-eDP1: SUCCESS (8.827s)
[613.589577] Starting dynamic subtest: B-eDP1
[622.374541] Dynamic subtest B-eDP1: SUCCESS (8.793s)
[622.384904] Starting dynamic subtest: C-eDP1
[631.167404] Dynamic subtest C-eDP1: SUCCESS (8.791s)
[631.177853] Starting dynamic subtest: D-eDP1
[639.963236] Dynamic subtest D-eDP1: SUCCESS (8.794s)
[639.965416] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.214s)
[641.115099] [119/130] (393s left) gem_exec_balancer (sequential)
[641.176519] Starting subtest: sequential
[644.245000] Subtest sequential: SUCCESS (3.068s)
[644.576803] [120/130] (390s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[644.680497] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[644.682213] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[644.803849] [121/130] (390s left) gem_lmem_swapping (heavy-verify-random)
[644.841166] Subtest heavy-verify-random: SKIP (0.000s)
[644.881176] [122/130] (390s left) gem_userptr_blits (sd-probe)
[644.931377] Starting subtest: sd-probe
[644.932306] Subtest sd-probe: SUCCESS (0.000s)
[645.071333] [123/130] (389s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[645.121241] Starting subtest: framebuffer-vs-set-tiling
[645.123067] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[645.290476] [124/130] (389s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[645.345355] Starting subtest: multi-wait-for-submit-available-signaled
[645.346470] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[645.465764] [125/130] (389s left) gem_eio (in-flight-contexts-10ms)
[645.693806] Starting subtest: in-flight-contexts-10ms
[659.168598] Subtest in-flight-contexts-10ms: SUCCESS (13.474s)
[659.366005] [126/130] (375s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[659.452748] Starting subtest: compare-crc-sanitycheck-xr24
[659.453659] Starting dynamic subtest: pipe-A-eDP-1
[660.065984] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.612s)
[660.075095] Starting dynamic subtest: pipe-B-eDP-1
[661.323522] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.255s)
[661.332686] Starting dynamic subtest: pipe-C-eDP-1
[662.491421] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.168s)
[662.502051] Starting dynamic subtest: pipe-D-eDP-1
[663.707790] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[663.708726] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.255s)
[664.516409] [127/130] (370s left) kms_cursor_edge_walk (128x128-top-bottom)
[664.599612] Starting subtest: 128x128-top-bottom
[664.600671] Starting dynamic subtest: pipe-A-eDP-1
[668.028010] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.427s)
[668.036483] Starting dynamic subtest: pipe-D-eDP-1
[671.232670] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.203s)
[671.233721] Subtest 128x128-top-bottom: SUCCESS (6.633s)
[672.116655] [128/130] (363s left) gem_eio (suspend)
[672.339343] Starting subtest: suspend
[684.271090] Subtest suspend: SUCCESS (11.931s)
[690.453816] [129/130] (350s left) syncobj_timeline (wait-all-for-submit-complex)
[690.507436] Starting subtest: wait-all-for-submit-complex
[690.723017] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[690.813312] [130/130] (350s left) gem_workarounds (basic-read-context)
[690.885217] Starting subtest: basic-read-context
[690.901368] Subtest basic-read-context: SUCCESS (0.016s)
[691.068505] Closing watchdogs
results: parsing output: 0/ for test: gem_workarounds
results: parsing output: 1/ for test: gem_ppgtt
results: parsing output: 2/ for test: api_intel_bb
results: parsing output: 3/ for test: kms_universal_plane
results: parsing output: 4/ for test: syncobj_basic
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_lease
results: parsing output: 7/ for test: kms_cursor_crc
results: parsing output: 8/ for test: prime_vgem
results: parsing output: 9/ for test: kms_flip
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: kms_cursor_crc
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: vgem_basic
results: parsing output: 14/ for test: template
results: parsing output: 15/ for test: core_setmaster
results: parsing output: 16/ for test: gem_exec_capture
results: parsing output: 17/ for test: gen9_exec_parse
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_pm_dc
results: parsing output: 20/ for test: i915_suspend
results: parsing output: 21/ for test: gem_eio
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: i915_pm_rc6_residency
results: parsing output: 24/ for test: i915_drm_fdinfo
results: parsing output: 25/ for test: gem_caching
results: parsing output: 26/ for test: gem_ctx_param
results: parsing output: 27/ for test: kms_addfb_basic
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_atomic_transition
results: parsing output: 30/ for test: gem_exec_schedule
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_atomic_interruptible
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: gem_exec_balancer
results: parsing output: 36/ for test: gem_eio
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: gem_pxp
results: parsing output: 39/ for test: kms_plane_multiple
results: parsing output: 40/ for test: gem_pxp
results: parsing output: 41/ for test: kms_psr2_sf
results: parsing output: 42/ for test: perf
results: parsing output: 43/ for test: i915_pm_freq_api
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_atomic_interruptible
results: parsing output: 46/ for test: kms_content_protection
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: gem_mmap_gtt
results: parsing output: 51/ for test: gem_render_copy
results: parsing output: 52/ for test: gem_softpin
results: parsing output: 53/ for test: kms_dsc
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_atomic
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: sysfs_timeslice_duration
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_plane_scaling
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: kms_vblank
results: parsing output: 64/ for test: gem_busy
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_exec_gttfill
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_invalid_mode
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: gem_eio
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: perf_pmu
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: gem_mmap_gtt
results: parsing output: 81/ for test: kms_chamelium_frames
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_cdclk
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: kms_pm_rpm
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_psr2_su
results: parsing output: 90/ for test: kms_color
results: parsing output: 91/ for test: gem_tiled_blits
results: parsing output: 92/ for test: kms_fbcon_fbt
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_fence
results: parsing output: 97/ for test: gem_create
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_ccs
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: syncobj_eventfd
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_force_connector_basic
results: parsing output: 104/ for test: api_intel_allocator
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: kms_3d
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: kms_cursor_edge_walk
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_exec_schedule
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: gem_mmap_gtt
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: gem_exec_balancer
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_lmem_swapping
results: parsing output: 121/ for test: gem_userptr_blits
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: gem_eio
results: parsing output: 125/ for test: kms_pipe_crc_basic
results: parsing output: 126/ for test: kms_cursor_edge_walk
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: gem_workarounds
Done.