[59.566157] Initializing watchdogs
[59.566201]   /dev/watchdog0
[59.581583] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[59.586047] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.588104] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.591473] [001/133] (960s left) kms_big_fb (linear-8bpp-rotate-90)
[59.717228] Starting subtest: linear-8bpp-rotate-90
[59.756050] Subtest linear-8bpp-rotate-90: SKIP (0.036s)
[59.882213] [002/133] (960s left) gem_ctx_param (non-root-set)
[59.933611] Starting subtest: non-root-set
[59.961370] Subtest non-root-set: SUCCESS (0.028s)
[60.064706] [003/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[60.150476] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[60.154779] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[60.237234] [004/133] (959s left) gem_pxp (create-protected-buffer)
[60.361161] Starting subtest: create-protected-buffer
[60.363176] Subtest create-protected-buffer: SUCCESS (0.000s)
[60.472532] [005/133] (959s left) kms_invalid_mode (bad-htotal)
[60.548034] Starting subtest: bad-htotal
[60.550105] Starting dynamic subtest: pipe-A-eDP-1
[60.834742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[60.844683] Starting dynamic subtest: pipe-B-eDP-1
[60.846839] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[60.853505] Starting dynamic subtest: pipe-C-eDP-1
[60.855686] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[60.863978] Starting dynamic subtest: pipe-D-eDP-1
[60.866607] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[60.869064] Subtest bad-htotal: SUCCESS (0.289s)
[61.687283] [006/133] (958s left) kms_vblank (invalid)
[61.756034] Starting subtest: invalid
[61.758033] Starting dynamic subtest: pipe-A-eDP-1
[62.155315] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.401s)
[62.157658] Subtest invalid: SUCCESS (0.402s)
[63.029394] [007/133] (957s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[63.119413] Starting subtest: fbc-2p-pri-indfb-multidraw
[63.123828] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[63.212823] [008/133] (957s left) gem_softpin (overlap)
[63.275176] Starting subtest: overlap
[64.161343] Subtest overlap: SUCCESS (0.886s)
[64.306665] [009/133] (956s left) kms_psr (fbc-psr-basic)
[64.383602] Starting subtest: fbc-psr-basic
[64.385851] Starting dynamic subtest: eDP-1
[64.390130] Dynamic subtest eDP-1: SKIP (0.000s)
[64.394397] Subtest fbc-psr-basic: SKIP (0.000s)
[64.452945] [010/133] (955s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[64.536119] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[64.538534] Starting dynamic subtest: pipe-A-eDP-1
[64.992197] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.427s)
[65.002543] Starting dynamic subtest: pipe-B-eDP-1
[66.429649] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.439s)
[66.438167] Starting dynamic subtest: pipe-C-eDP-1
[67.823110] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.393s)
[67.831840] Starting dynamic subtest: pipe-D-eDP-1
[69.159879] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[69.161775] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.595s)
[70.349449] [011/133] (950s left) gem_vm_create (invalid-destroy)
[70.402585] Starting subtest: invalid-destroy
[70.405018] Subtest invalid-destroy: SUCCESS (0.002s)
[70.498199] [012/133] (950s left) i915_pm_rpm (debugfs-forcewake-user)
[72.012901] Starting subtest: debugfs-forcewake-user
[83.403719] Subtest debugfs-forcewake-user: SUCCESS (11.395s)
[83.786173] [013/133] (936s left) core_setmaster_vs_auth
[83.943971] [014/133] (936s left) gem_softpin (noreloc)
[83.996771] Starting subtest: noreloc
[89.415868] Subtest noreloc: SUCCESS (5.421s)
[89.530028] [015/133] (931s left) kms_flip (flip-vs-expired-vblank-interruptible)
[89.608260] Starting subtest: flip-vs-expired-vblank-interruptible
[89.610639] Starting dynamic subtest: A-eDP1
[98.277769] Dynamic subtest A-eDP1: SUCCESS (8.669s)
[98.285839] Starting dynamic subtest: B-eDP1
[106.904418] Dynamic subtest B-eDP1: SUCCESS (8.627s)
[106.914475] Starting dynamic subtest: C-eDP1
[115.531785] Dynamic subtest C-eDP1: SUCCESS (8.625s)
[115.544172] Starting dynamic subtest: D-eDP1
[124.154411] Dynamic subtest D-eDP1: SUCCESS (8.622s)
[124.159063] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.552s)
[125.311707] [016/133] (895s left) gem_mmap_gtt (isolation)
[125.376160] Subtest isolation: SKIP (0.000s)
[125.438927] [017/133] (895s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[125.525706] Starting subtest: pr-cursor-plane-move-continuous-sf
[125.530281] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[125.593634] [018/133] (895s left) gem_exec_params (larger-than-life-batch)
[125.655575] Starting subtest: larger-than-life-batch
[127.853674] Subtest larger-than-life-batch: SUCCESS (2.198s)
[128.901550] [019/133] (891s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[129.027261] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[129.031354] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[129.117766] [020/133] (891s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[129.493898] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[130.321203] [021/133] (890s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[130.399060] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[130.403722] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[130.473248] [022/133] (890s left) kms_dsc (dsc-basic)
[130.540748] Starting subtest: dsc-basic
[130.554067] Subtest dsc-basic: SKIP (0.000s)
[130.613423] [023/133] (890s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[130.680569] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[130.789363] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.107s)
[130.862885] [024/133] (890s left) kms_atomic_transition (plane-all-transition-nonblocking)
[130.941181] Starting subtest: plane-all-transition-nonblocking
[130.943518] Starting dynamic subtest: pipe-A-eDP-1
[133.436020] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[133.747531] Starting dynamic subtest: pipe-B-eDP-1
[136.973567] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.236s)
[137.279221] Subtest plane-all-transition-nonblocking: SUCCESS (6.338s)
[138.137346] [025/133] (882s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[138.283804] Starting subtest: dp-mst-lic-type-0-hdcp14
[138.288417] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[138.397492] [026/133] (882s left) kms_color (ctm-max)
[138.473156] Starting subtest: ctm-max
[138.475471] Starting dynamic subtest: pipe-A-eDP-1
[139.220568] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.748s)
[139.228758] Starting dynamic subtest: pipe-B-eDP-1
[140.625105] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.402s)
[140.633994] Starting dynamic subtest: pipe-C-eDP-1
[142.004067] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.379s)
[142.014389] Starting dynamic subtest: pipe-D-eDP-1
[143.346972] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[143.349383] Subtest ctm-max: SUCCESS (4.875s)
[144.146825] [027/133] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[144.235269] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[144.239836] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[144.340503] [028/133] (876s left) kms_async_flips (crc)
[144.415899] Starting subtest: crc
[144.801255] Starting dynamic subtest: pipe-A-eDP-1
[147.683619] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.883s)
[148.063573] Starting dynamic subtest: pipe-B-eDP-1
[151.019208] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.964s)
[151.394245] Starting dynamic subtest: pipe-C-eDP-1
[154.319753] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.933s)
[154.692965] Starting dynamic subtest: pipe-D-eDP-1
[157.550213] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.866s)
[157.552947] Subtest crc: SUCCESS (13.133s)
[158.728807] [029/133] (862s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[158.809758] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[158.812205] Starting dynamic subtest: pipe-A-eDP-1
[158.821201] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[158.828924] Starting dynamic subtest: pipe-B-eDP-1
[158.837389] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[158.845187] Starting dynamic subtest: pipe-C-eDP-1
[158.853290] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[158.860246] Starting dynamic subtest: pipe-D-eDP-1
[158.868323] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[158.872289] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[161.075652] [030/133] (860s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[161.143173] Starting subtest: fbc-1p-pri-indfb-multidraw
[173.986149] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (12.845s)
[175.385711] [031/133] (845s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[175.484346] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[175.488363] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[175.554709] [032/133] (845s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[175.635546] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[175.639704] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[175.730300] [033/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[175.815086] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[175.819216] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[175.899908] [034/133] (845s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[175.978332] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[175.982824] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[176.063286] [035/133] (845s left) kms_dp_link_training (uhbr-mst)
[176.136497] Starting subtest: uhbr-mst
[176.145166] Subtest uhbr-mst: SKIP (0.000s)
[176.226127] [036/133] (845s left) kms_plane_multiple (2x-tiling-x)
[176.292819] Starting subtest: 2x-tiling-x
[176.297172] Subtest 2x-tiling-x: SKIP (0.000s)
[176.373814] [037/133] (845s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[176.447083] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[176.450872] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[176.538312] [038/133] (845s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[176.622069] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[176.625713] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[176.713956] [039/133] (844s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[176.787254] Starting subtest: 4-tiled-32bpp-rotate-0
[178.111442] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.324s)
[178.239460] [040/133] (843s left) gem_ctx_persistence (engines-mixed-process)
[178.293710] Starting subtest: engines-mixed-process
[178.295647] Starting dynamic subtest: rcs0
[179.811436] Dynamic subtest rcs0: SUCCESS (1.514s)
[179.821905] Starting dynamic subtest: bcs0
[181.848446] Dynamic subtest bcs0: SUCCESS (2.039s)
[181.858590] Starting dynamic subtest: vcs0
[183.834795] Dynamic subtest vcs0: SUCCESS (1.986s)
[183.845587] Starting dynamic subtest: vcs1
[185.824795] Dynamic subtest vcs1: SUCCESS (1.989s)
[185.833698] Starting dynamic subtest: vecs0
[187.873340] Dynamic subtest vecs0: SUCCESS (2.048s)
[187.883737] Starting dynamic subtest: ccs0
[189.831681] Dynamic subtest ccs0: SUCCESS (1.956s)
[189.833761] Subtest engines-mixed-process: SUCCESS (11.536s)
[189.906248] [041/133] (831s left) i915_drm_fdinfo (virtual-busy-hang-all)
[189.953482] Starting subtest: virtual-busy-hang-all
[189.957670] Subtest virtual-busy-hang-all: SKIP (0.000s)
[190.016670] [042/133] (831s left) kms_flip (2x-blocking-wf_vblank)
[190.104201] Starting subtest: 2x-blocking-wf_vblank
[190.110891] Subtest 2x-blocking-wf_vblank: SKIP (0.008s)
[190.187535] [043/133] (831s left) kms_chamelium_color (gamma)
[190.266505] Subtest gamma: SKIP (0.000s)
[190.308749] [044/133] (831s left) sw_sync (sync_multi_timeline_wait)
[190.350825] Starting subtest: sync_multi_timeline_wait
[190.353139] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[190.402543] [045/133] (831s left) prime_self_import (basic-with_fd_dup)
[190.433863] Starting subtest: basic-with_fd_dup
[190.453287] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[190.564567] [046/133] (831s left) i915_drm_fdinfo (virtual-busy)
[190.612523] Starting subtest: virtual-busy
[190.617218] Subtest virtual-busy: SKIP (0.000s)
[190.674984] [047/133] (831s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[190.747853] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[190.750233] Starting dynamic subtest: pipe-A
[190.768337] Dynamic subtest pipe-A: SKIP (0.016s)
[190.780382] Starting dynamic subtest: pipe-B
[190.797248] Dynamic subtest pipe-B: SKIP (0.029s)
[190.805159] Starting dynamic subtest: pipe-C
[190.828316] Dynamic subtest pipe-C: SKIP (0.030s)
[190.836965] Starting dynamic subtest: pipe-D
[190.865589] Dynamic subtest pipe-D: SKIP (0.038s)
[190.868688] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.116s)
[190.959221] [048/133] (830s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[191.041478] Starting subtest: fbc-rgb565-draw-pwrite
[195.591036] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.551s)
[196.795225] [049/133] (825s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[196.878424] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[196.882507] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[196.975696] [050/133] (825s left) kms_fbcon_fbt (psr)
[197.040678] Starting subtest: psr
[204.457189] Subtest psr: SUCCESS (7.419s)
[204.529789] [051/133] (817s left) kms_pm_backlight (fade-with-suspend)
[204.608823] Starting subtest: fade-with-suspend
[220.395089] Starting dynamic subtest: eDP-1
[222.859641] Dynamic subtest eDP-1: SUCCESS (2.465s)
[222.862168] Subtest fade-with-suspend: SUCCESS (4.609s)
[223.720345] [052/133] (798s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[223.806214] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[223.879058] [053/133] (798s left) prime_vgem (basic-blt)
[224.039918] Starting subtest: basic-blt
[224.067898] Subtest basic-blt: SUCCESS (0.028s)
[224.206126] [FACT prime_vgem (basic-blt)] new: kernel.kmod_is_loaded.vgem: true
[224.209341] [054/133] (797s left) gem_softpin (allocator-basic-reserve)
[224.264449] Starting subtest: allocator-basic-reserve
[224.344028] Subtest allocator-basic-reserve: SUCCESS (0.079s)
[224.467661] [055/133] (797s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[224.550768] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[231.200312] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.651s)
[231.314464] [056/133] (790s left) gem_exec_schedule (u-fairslice)
[231.383811] Starting subtest: u-fairslice
[231.385846] Starting dynamic subtest: rcs0
[233.408450] Dynamic subtest rcs0: SUCCESS (2.024s)
[233.418710] Starting dynamic subtest: bcs0
[235.428574] Dynamic subtest bcs0: SUCCESS (2.019s)
[235.439114] Starting dynamic subtest: vcs0
[237.446868] Dynamic subtest vcs0: SUCCESS (2.018s)
[237.457295] Starting dynamic subtest: vcs1
[239.468956] Dynamic subtest vcs1: SUCCESS (2.020s)
[239.478428] Starting dynamic subtest: vecs0
[241.489466] Dynamic subtest vecs0: SUCCESS (2.019s)
[241.498938] Starting dynamic subtest: ccs0
[243.509802] Dynamic subtest ccs0: SUCCESS (2.019s)
[243.512116] Subtest u-fairslice: SUCCESS (12.124s)
[243.811854] [057/133] (778s left) gem_lmem_evict (dontneed-evict-race)
[243.853632] Subtest dontneed-evict-race: SKIP (0.000s)
[243.898878] [058/133] (778s left) gem_exec_reloc (basic-gtt-cpu-active)
[243.962232] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[244.031012] [059/133] (778s left) kms_pipe_crc_basic (suspend-read-crc)
[244.108351] Starting subtest: suspend-read-crc
[244.110602] Starting dynamic subtest: pipe-A-eDP-1
[262.302102] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.532s)
[262.313187] Starting dynamic subtest: pipe-B-eDP-1
[280.307459] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.160s)
[280.317644] Starting dynamic subtest: pipe-C-eDP-1
[298.291445] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.141s)
[298.302306] Starting dynamic subtest: pipe-D-eDP-1
[316.263447] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.076s)
[316.266454] Subtest suspend-read-crc: SUCCESS (11.915s)
[317.117169] [060/133] (705s left) gem_mmap_gtt (basic)
[317.177277] Subtest basic: SKIP (0.000s)
[317.243245] [061/133] (705s left) syncobj_wait (invalid-signal-illegal-handle)
[317.296115] Starting subtest: invalid-signal-illegal-handle
[317.298449] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[317.394935] [062/133] (705s left) perf_pmu (busy-idle-check-all)
[317.443252] Starting subtest: busy-idle-check-all
[317.446127] Starting dynamic subtest: rcs0
[318.102795] Dynamic subtest rcs0: SUCCESS (0.659s)
[318.116146] Starting dynamic subtest: bcs0
[318.757853] Dynamic subtest bcs0: SUCCESS (0.652s)
[318.771279] Starting dynamic subtest: vcs0
[319.486225] Dynamic subtest vcs0: FAIL (0.641s)
[319.497013] Starting dynamic subtest: vcs1
[320.754163] Dynamic subtest vcs1: SUCCESS (1.349s)
[320.766860] Starting dynamic subtest: vecs0
[322.737867] Dynamic subtest vecs0: SUCCESS (1.980s)
[322.751060] Starting dynamic subtest: ccs0
[323.397020] Dynamic subtest ccs0: SUCCESS (0.657s)
[323.400124] Subtest busy-idle-check-all: FAIL (5.951s)
[323.496438] [063/133] (699s left) kms_hdr (bpc-switch-suspend)
[323.578339] Starting subtest: bpc-switch-suspend
[324.694598] Starting dynamic subtest: pipe-A-eDP-1
[344.532597] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.602s)
[344.534986] Subtest bpc-switch-suspend: SUCCESS (5.716s)
[344.649691] [064/133] (677s left) gem_ctx_persistence (legacy-engines-hang)
[344.715550] Starting subtest: legacy-engines-hang
[344.717910] Starting dynamic subtest: render
[344.836956] Dynamic subtest render: SUCCESS (0.121s)
[344.852642] Starting dynamic subtest: bsd1
[344.937135] Dynamic subtest bsd1: SUCCESS (0.097s)
[344.949543] Starting dynamic subtest: bsd2
[345.038994] Dynamic subtest bsd2: SUCCESS (0.099s)
[345.052812] Starting dynamic subtest: blt
[345.132890] Dynamic subtest blt: SUCCESS (0.091s)
[345.147790] Starting dynamic subtest: vebox
[345.229172] Dynamic subtest vebox: SUCCESS (0.093s)
[345.231691] Subtest legacy-engines-hang: SUCCESS (0.514s)
[345.316742] [065/133] (677s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[345.391689] Starting subtest: yf-tiled-32bpp-rotate-270
[345.396062] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[345.489284] [066/133] (677s left) kms_cursor_crc (cursor-offscreen-128x42)
[345.673866] Starting subtest: cursor-offscreen-128x42
[345.725778] Subtest cursor-offscreen-128x42: SKIP (0.045s)
[345.908463] [067/133] (676s left) gem_create (create-valid-nonaligned)
[345.969353] Starting subtest: create-valid-nonaligned
[345.971232] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[346.154929] [068/133] (676s left) kms_rotation_crc (primary-rotation-270)
[346.230386] Starting subtest: primary-rotation-270
[346.234887] Subtest primary-rotation-270: SKIP (0.001s)
[346.320812] [069/133] (676s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[346.396141] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[346.400395] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[346.474036] [070/133] (676s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[346.577891] Starting subtest: verify-pxp-stale-buf-optout-execution
[347.636092] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.058s)
[347.767138] [071/133] (675s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[347.872539] Starting subtest: basic-flip-before-cursor-legacy
[348.807370] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.933s)
[349.622163] [072/133] (673s left) kms_async_flips (test-cursor)
[349.693427] Starting subtest: test-cursor
[350.071418] Starting dynamic subtest: pipe-A-eDP-1
[350.819207] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.747s)
[351.195386] Starting dynamic subtest: pipe-B-eDP-1
[351.943279] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.755s)
[352.322775] Starting dynamic subtest: pipe-C-eDP-1
[353.072476] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.757s)
[353.441554] Starting dynamic subtest: pipe-D-eDP-1
[354.190631] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.756s)
[354.192804] Subtest test-cursor: SUCCESS (4.496s)
[355.362645] [073/133] (667s left) core_setmaster (master-drop-set-shared-fd)
[355.441836] Starting subtest: master-drop-set-shared-fd
[355.460815] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[355.500259] [074/133] (667s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[355.559700] Starting subtest: multi-wait-available-unsubmitted-signaled
[355.561942] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[355.652258] [075/133] (667s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[355.739523] Starting subtest: cursor-tearing-framebuffer-change
[355.741848] Starting dynamic subtest: pipe-A-eDP-1
[356.282888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.541s)
[356.293983] Starting dynamic subtest: pipe-D-eDP-1
[357.500666] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.215s)
[357.503079] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.759s)
[358.321566] [076/133] (664s left) gem_eio (in-flight-external)
[358.539435] Starting subtest: in-flight-external
[360.052738] Subtest in-flight-external: SUCCESS (1.511s)
[360.188011] [077/133] (662s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[360.278031] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[360.282018] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[360.358880] [078/133] (662s left) kms_pm_rpm (i2c)
[362.163325] Starting subtest: i2c
[367.167228] Subtest i2c: SUCCESS (5.001s)
[367.271227] [079/133] (655s left) kms_vblank (query-forked-hang)
[367.351730] Starting subtest: query-forked-hang
[367.353929] Starting dynamic subtest: pipe-A-eDP-1
[369.208872] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.855s)
[369.227139] Starting dynamic subtest: pipe-D-eDP-1
[371.751435] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s)
[371.756508] Subtest query-forked-hang: SUCCESS (4.403s)
[372.603551] [080/133] (650s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[372.703240] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[372.707425] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[372.811052] [081/133] (650s left) kms_pipe_crc_basic (bad-source)
[372.877378] Starting subtest: bad-source
[372.879581] Subtest bad-source: SUCCESS (0.000s)
[372.955825] [082/133] (650s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[373.011281] Starting subtest: single-wait-for-submit-available-signaled
[373.013666] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[373.103757] [083/133] (650s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[373.167075] Starting subtest: yf-tiled-to-vebox-yf-tiled
[373.172343] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[373.355879] [084/133] (650s left) api_intel_bb (crc32)
[373.418682] Starting subtest: crc32
[373.421091] Starting dynamic subtest: rcs0-smem0
[373.753496] Dynamic subtest rcs0-smem0: SUCCESS (0.333s)
[373.767054] Starting dynamic subtest: bcs0-smem0
[374.061767] Dynamic subtest bcs0-smem0: SUCCESS (0.305s)
[374.075425] Starting dynamic subtest: vcs0-smem0
[374.395232] Dynamic subtest vcs0-smem0: SUCCESS (0.331s)
[374.408675] Starting dynamic subtest: vcs1-smem0
[374.731874] Dynamic subtest vcs1-smem0: SUCCESS (0.334s)
[374.745320] Starting dynamic subtest: vecs0-smem0
[375.072085] Dynamic subtest vecs0-smem0: SUCCESS (0.338s)
[375.084289] Starting dynamic subtest: ccs0-smem0
[375.385334] Dynamic subtest ccs0-smem0: SUCCESS (0.312s)
[375.388023] Subtest crc32: SUCCESS (1.969s)
[375.501898] [085/133] (648s left) template (a)
[375.561177] Starting subtest: A
[375.563663] Subtest A: SUCCESS (0.000s)
[375.679182] [086/133] (647s left) gem_exec_fence (parallel)
[375.852600] Starting subtest: parallel
[375.855294] Starting dynamic subtest: rcs0
[378.003782] Dynamic subtest rcs0: SUCCESS (2.153s)
[378.016990] Starting dynamic subtest: bcs0
[380.157063] Dynamic subtest bcs0: SUCCESS (2.154s)
[380.167529] Starting dynamic subtest: vcs0
[382.307624] Dynamic subtest vcs0: SUCCESS (2.151s)
[382.317967] Starting dynamic subtest: vcs1
[384.459894] Dynamic subtest vcs1: SUCCESS (2.152s)
[384.471803] Starting dynamic subtest: vecs0
[386.611066] Dynamic subtest vecs0: SUCCESS (2.150s)
[386.624134] Starting dynamic subtest: ccs0
[388.761296] Dynamic subtest ccs0: SUCCESS (2.150s)
[388.763357] Subtest parallel: SUCCESS (12.916s)
[391.020750] [087/133] (632s left) kms_psr (psr-sprite-blt)
[391.096702] Starting subtest: psr-sprite-blt
[391.099115] Starting dynamic subtest: eDP-1
[392.882900] Dynamic subtest eDP-1: SUCCESS (1.786s)
[392.885384] Subtest psr-sprite-blt: SUCCESS (1.787s)
[393.724752] [088/133] (629s left) gem_mmap (bad-object)
[393.799392] Subtest bad-object: SKIP (0.000s)
[393.873279] [089/133] (629s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[393.921997] Starting subtest: etime-single-wait-available-unsubmitted
[394.024233] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.103s)
[394.099979] [090/133] (629s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[394.181678] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[394.183882] Starting dynamic subtest: pipe-A-eDP-1
[394.205917] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[394.217823] Starting dynamic subtest: pipe-B-eDP-1
[394.239133] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[394.249390] Starting dynamic subtest: pipe-C-eDP-1
[394.270689] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[394.280968] Starting dynamic subtest: pipe-D-eDP-1
[394.302680] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[394.306674] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[396.484993] [091/133] (627s left) kms_psr (fbc-pr-basic)
[396.563394] Starting subtest: fbc-pr-basic
[396.567781] Subtest fbc-pr-basic: SKIP (0.000s)
[396.640137] [092/133] (627s left) fbdev (unaligned-read)
[396.688170] Starting subtest: unaligned-read
[396.703170] Subtest unaligned-read: SUCCESS (0.015s)
[396.758904] [093/133] (627s left) gem_ctx_param (set-priority-not-supported)
[396.819990] Starting subtest: set-priority-not-supported
[396.824453] Subtest set-priority-not-supported: SKIP (0.000s)
[396.894924] [094/133] (626s left) gem_exec_schedule (preempt)
[396.975908] Starting subtest: preempt
[396.978219] Starting dynamic subtest: rcs0
[397.004054] Dynamic subtest rcs0: SUCCESS (0.031s)
[397.017154] Starting dynamic subtest: bcs0
[397.024826] Dynamic subtest bcs0: SUCCESS (0.020s)
[397.035450] Starting dynamic subtest: vcs0
[397.046891] Dynamic subtest vcs0: SUCCESS (0.021s)
[397.057174] Starting dynamic subtest: vcs1
[397.071442] Dynamic subtest vcs1: SUCCESS (0.024s)
[397.082271] Starting dynamic subtest: vecs0
[397.092941] Dynamic subtest vecs0: SUCCESS (0.021s)
[397.104084] Starting dynamic subtest: ccs0
[397.116341] Dynamic subtest ccs0: SUCCESS (0.023s)
[397.118610] Subtest preempt: SUCCESS (0.145s)
[399.046581] [095/133] (624s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[399.128025] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[406.089724] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (6.965s)
[406.220129] [096/133] (617s left) sw_sync (sync_multi_consumer)
[406.261027] Starting subtest: sync_multi_consumer
[406.709222] Subtest sync_multi_consumer: SUCCESS (0.447s)
[406.770210] [097/133] (617s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[406.863641] Starting subtest: y-tiled-addfb-size-offset-overflow
[406.867601] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[406.973619] [098/133] (617s left) perf_pmu (all-busy-idle-check-all)
[407.020357] Starting subtest: all-busy-idle-check-all
[408.949502] Subtest all-busy-idle-check-all: SUCCESS (1.928s)
[409.065447] [099/133] (615s left) gem_eio (wait-wedge-10ms)
[409.273814] Starting subtest: wait-wedge-10ms
[411.023817] Subtest wait-wedge-10ms: SUCCESS (1.749s)
[411.158072] [100/133] (612s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[411.237717] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[415.166325] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (3.931s)
[416.330197] [101/133] (607s left) kms_psr (pr-sprite-mmap-cpu)
[416.412404] Starting subtest: pr-sprite-mmap-cpu
[416.416923] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[416.499697] [102/133] (607s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[416.552741] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[416.638288] [103/133] (607s left) syncobj_timeline (invalid-query-illegal-handle)
[416.685043] Starting subtest: invalid-query-illegal-handle
[416.687355] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[416.772793] [104/133] (607s left) kms_cursor_crc (cursor-random-32x10)
[416.920095] Starting subtest: cursor-random-32x10
[416.963502] Subtest cursor-random-32x10: SKIP (0.038s)
[417.241796] [105/133] (607s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[417.318417] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[417.320209] Starting dynamic subtest: pipe-A-eDP-1
[419.447138] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.128s)
[419.459220] Starting dynamic subtest: pipe-B-eDP-1
[421.758977] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.307s)
[421.769674] Starting dynamic subtest: pipe-C-eDP-1
[423.789193] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.027s)
[423.800182] Starting dynamic subtest: pipe-D-eDP-1
[425.813336] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.025s)
[425.815383] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.494s)
[427.038014] [106/133] (597s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[427.139089] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[427.143596] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[427.255002] [107/133] (597s left) gem_pread (display)
[427.320078] Subtest display: SKIP (0.000s)
[427.379405] [108/133] (597s left) gem_exec_basic (basic)
[427.434743] Starting subtest: basic
[427.437061] Starting dynamic subtest: rcs0-smem
[427.439243] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[427.449901] Starting dynamic subtest: bcs0-smem
[427.452081] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[427.461546] Starting dynamic subtest: vcs0-smem
[427.463376] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[427.472800] Starting dynamic subtest: vcs1-smem
[427.474680] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[427.483922] Starting dynamic subtest: vecs0-smem
[427.485797] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[427.494927] Starting dynamic subtest: ccs0-smem
[427.496797] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[427.498672] Subtest basic: SUCCESS (0.017s)
[428.955716] [109/133] (595s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[429.036524] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[429.041698] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[429.134669] [110/133] (595s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[429.207034] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[429.209292] Starting dynamic subtest: pipe-A
[429.547064] Dynamic subtest pipe-A: SUCCESS (0.341s)
[429.557646] Starting dynamic subtest: pipe-B
[430.954379] Dynamic subtest pipe-B: SUCCESS (1.406s)
[430.965041] Starting dynamic subtest: pipe-C
[432.371547] Dynamic subtest pipe-C: SUCCESS (1.416s)
[432.382473] Starting dynamic subtest: pipe-D
[433.798956] Dynamic subtest pipe-D: SUCCESS (1.426s)
[433.801635] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.593s)
[434.979813] [111/133] (589s left) gem_exec_balancer (bonded-pair)
[435.040206] Starting subtest: bonded-pair
[435.045058] Subtest bonded-pair: SKIP (0.000s)
[435.332026] [112/133] (589s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[435.405542] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[442.577171] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.173s)
[442.725465] [113/133] (581s left) kms_legacy_colorkey (invalid-plane)
[442.803198] Starting subtest: invalid-plane
[442.805423] Starting dynamic subtest: zero-id
[442.807722] Dynamic subtest zero-id: SUCCESS (0.000s)
[442.817896] Starting dynamic subtest: outrange-id
[442.819796] Dynamic subtest outrange-id: SUCCESS (0.000s)
[442.821684] Subtest invalid-plane: SUCCESS (0.001s)
[442.876880] [114/133] (581s left) api_intel_bb (purge-bb)
[442.934860] Starting subtest: purge-bb
[442.937258] Subtest purge-bb: SUCCESS (0.004s)
[443.048701] [115/133] (581s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[443.119691] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[449.805670] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.691s)
[449.942473] [116/133] (574s left) perf_pmu (rc6)
[449.985082] Starting subtest: rc6
[449.987274] Starting dynamic subtest: gt0
[456.354451] Dynamic subtest gt0: SUCCESS (6.369s)
[456.367643] Starting dynamic subtest: runtime-pm-gt0
[464.084137] Dynamic subtest runtime-pm-gt0: SUCCESS (7.726s)
[464.098348] Starting dynamic subtest: runtime-pm-long-gt0
[476.556612] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.468s)
[476.570192] Starting dynamic subtest: other-idle-gt0
[482.899263] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[482.913488] Starting dynamic subtest: gt1
[489.241656] Dynamic subtest gt1: SUCCESS (6.338s)
[489.255620] Starting dynamic subtest: runtime-pm-gt1
[496.688201] Dynamic subtest runtime-pm-gt1: SUCCESS (7.443s)
[496.701363] Starting dynamic subtest: runtime-pm-long-gt1
[509.185362] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.493s)
[509.198946] Starting dynamic subtest: other-idle-gt1
[515.529127] Dynamic subtest other-idle-gt1: SUCCESS (6.340s)
[515.532208] Subtest rc6: SUCCESS (65.536s)
[516.676770] [117/133] (508s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[516.752738] Starting subtest: plane-primary-toggle-with-vblank-wait
[516.755079] Starting dynamic subtest: pipe-A-eDP-1
[516.926071] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[517.244468] Starting dynamic subtest: pipe-B-eDP-1
[518.096831] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.861s)
[518.398197] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.646s)
[519.233284] [118/133] (505s left) gem_cs_tlb (engines)
[519.293614] Starting subtest: engines
[519.295643] Starting dynamic subtest: rcs0
[521.447043] Dynamic subtest rcs0: SUCCESS (2.152s)
[521.456950] Starting dynamic subtest: bcs0
[523.599896] Dynamic subtest bcs0: SUCCESS (2.152s)
[523.616081] Starting dynamic subtest: vcs0
[525.755670] Dynamic subtest vcs0: SUCCESS (2.154s)
[525.766020] Starting dynamic subtest: vcs1
[527.909007] Dynamic subtest vcs1: SUCCESS (2.152s)
[527.921979] Starting dynamic subtest: vecs0
[530.063763] Dynamic subtest vecs0: SUCCESS (2.153s)
[530.081322] Starting dynamic subtest: ccs0
[532.221760] Dynamic subtest ccs0: SUCCESS (2.157s)
[532.224365] Subtest engines: SUCCESS (12.926s)
[532.319746] [119/133] (492s left) kms_cdclk (mode-transition-all-outputs)
[532.396819] Starting subtest: mode-transition-all-outputs
[532.701549] Subtest mode-transition-all-outputs: SKIP (0.302s)
[533.476909] [120/133] (491s left) kms_psr (fbc-pr-sprite-plane-onoff)
[533.552238] Starting subtest: fbc-pr-sprite-plane-onoff
[533.556196] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[533.600886] [121/133] (491s left) gem_exec_whisper (basic-normal-all)
[533.672110] Starting subtest: basic-normal-all
[535.825759] Subtest basic-normal-all: SUCCESS (2.151s)
[535.990676] [122/133] (489s left) kms_flip (2x-flip-vs-panning-interruptible)
[536.064714] Starting subtest: 2x-flip-vs-panning-interruptible
[536.074121] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.008s)
[536.158416] [123/133] (488s left) kms_addfb_basic (unused-pitches)
[536.207859] Starting subtest: unused-pitches
[536.210031] Subtest unused-pitches: SUCCESS (0.000s)
[536.300066] [124/133] (488s left) gem_ctx_shared (q-out-order)
[536.362238] Starting subtest: Q-out-order
[536.364443] Starting dynamic subtest: rcs0
[536.481653] Dynamic subtest rcs0: SUCCESS (0.120s)
[536.495163] Starting dynamic subtest: vcs0
[536.581351] Dynamic subtest vcs0: SUCCESS (0.100s)
[536.599957] Starting dynamic subtest: vcs1
[536.667290] Dynamic subtest vcs1: SUCCESS (0.085s)
[536.685329] Starting dynamic subtest: vecs0
[536.747368] Dynamic subtest vecs0: SUCCESS (0.079s)
[536.764266] Starting dynamic subtest: ccs0
[536.837609] Dynamic subtest ccs0: SUCCESS (0.089s)
[536.840361] Subtest Q-out-order: SUCCESS (0.478s)
[538.050717] [125/133] (487s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[538.127909] Starting subtest: psr-rgb565-draw-mmap-wc
[544.853811] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.726s)
[544.944386] [126/133] (480s left) gem_media_fill (media-fill)
[545.003917] Subtest media-fill: SKIP (0.000s)
[545.087502] [127/133] (480s left) kms_vrr (seamless-rr-switch-virtual)
[545.165408] Starting subtest: seamless-rr-switch-virtual
[545.172113] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[545.213734] [128/133] (480s left) gem_exec_flush (basic-uc-set-default)
[545.283544] Starting subtest: basic-uc-set-default
[550.739246] Subtest basic-uc-set-default: SUCCESS (5.456s)
[550.909210] [129/133] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[550.995808] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[551.000213] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[551.065475] [130/133] (474s left) gen9_exec_parse (bb-large)
[551.134873] Subtest bb-large: SKIP (0.000s)
[551.201466] [131/133] (474s left) vgem_basic (bad-flag)
[551.242340] Starting subtest: bad-flag
[551.244580] Subtest bad-flag: SUCCESS (0.000s)
[551.284574] [132/133] (474s left) i915_suspend (forcewake)
[551.342932] Starting subtest: forcewake
[583.411070] Subtest forcewake: SUCCESS (2.293s)
[584.467619] [133/133] (442s left) kms_psr (psr2-primary-blt)
[584.551941] Starting subtest: psr2-primary-blt
[584.554398] Starting dynamic subtest: eDP-1
[586.266699] Dynamic subtest eDP-1: SUCCESS (1.712s)
[586.268840] Subtest psr2-primary-blt: SUCCESS (1.713s)
[587.126032] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_ctx_param
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_pxp
results: parsing output: 4/ for test: kms_invalid_mode
results: parsing output: 5/ for test: kms_vblank
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_softpin
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_vm_create
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: core_setmaster_vs_auth
results: parsing output: 13/ for test: gem_softpin
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: kms_psr2_sf
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_chamelium_hpd
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: kms_dsc
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_atomic_transition
results: parsing output: 24/ for test: kms_content_protection
results: parsing output: 25/ for test: kms_color
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_async_flips
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_flip_scaled_crc
results: parsing output: 34/ for test: kms_dp_link_training
results: parsing output: 35/ for test: kms_plane_multiple
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: i915_drm_fdinfo
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: kms_chamelium_color
results: parsing output: 43/ for test: sw_sync
results: parsing output: 44/ for test: prime_self_import
results: parsing output: 45/ for test: i915_drm_fdinfo
results: parsing output: 46/ for test: kms_plane_scaling
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_fbcon_fbt
results: parsing output: 50/ for test: kms_pm_backlight
results: parsing output: 51/ for test: kms_pipe_b_c_ivb
results: parsing output: 52/ for test: prime_vgem
results: parsing output: 53/ for test: gem_softpin
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: gem_lmem_evict
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_pipe_crc_basic
results: parsing output: 59/ for test: gem_mmap_gtt
results: parsing output: 60/ for test: syncobj_wait
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: kms_hdr
results: parsing output: 63/ for test: gem_ctx_persistence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: gem_create
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: gem_pxp
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_async_flips
results: parsing output: 72/ for test: core_setmaster
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: gem_eio
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_pm_rpm
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_pipe_crc_basic
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: api_intel_bb
results: parsing output: 84/ for test: template
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_mmap
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: fbdev
results: parsing output: 92/ for test: gem_ctx_param
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: gem_eio
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_cursor_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: gem_pread
results: parsing output: 107/ for test: gem_exec_basic
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_plane_scaling
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_legacy_colorkey
results: parsing output: 113/ for test: api_intel_bb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: perf_pmu
results: parsing output: 116/ for test: kms_atomic_transition
results: parsing output: 117/ for test: gem_cs_tlb
results: parsing output: 118/ for test: kms_cdclk
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_whisper
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: gem_ctx_shared
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_media_fill
results: parsing output: 126/ for test: kms_vrr
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gen9_exec_parse
results: parsing output: 130/ for test: vgem_basic
results: parsing output: 131/ for test: i915_suspend
results: parsing output: 132/ for test: kms_psr
Done.