[57.675456] Initializing watchdogs
[57.675506]   /dev/watchdog0
[57.690213] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.694610] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.696978] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.700341] [001/133] (960s left) kms_big_fb (linear-8bpp-rotate-90)
[57.830318] Starting subtest: linear-8bpp-rotate-90
[57.867108] Subtest linear-8bpp-rotate-90: SKIP (0.037s)
[57.973179] [002/133] (960s left) gem_ctx_param (non-root-set)
[58.023595] Starting subtest: non-root-set
[58.042211] Subtest non-root-set: SUCCESS (0.019s)
[58.141474] [003/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[58.252703] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[58.257270] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.356173] [004/133] (959s left) gem_pxp (create-protected-buffer)
[58.480265] Starting subtest: create-protected-buffer
[58.482197] Subtest create-protected-buffer: SUCCESS (0.000s)
[58.594471] [005/133] (959s left) kms_invalid_mode (bad-htotal)
[58.690153] Starting subtest: bad-htotal
[58.692071] Starting dynamic subtest: pipe-A-eDP-1
[58.974834] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[58.983050] Starting dynamic subtest: pipe-B-eDP-1
[58.985238] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[58.992095] Starting dynamic subtest: pipe-C-eDP-1
[58.994272] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[59.000938] Starting dynamic subtest: pipe-D-eDP-1
[59.003223] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[59.005402] Subtest bad-htotal: SUCCESS (0.293s)
[59.768985] [006/133] (958s left) kms_vblank (invalid)
[59.864436] Starting subtest: invalid
[59.866817] Starting dynamic subtest: pipe-A-eDP-1
[60.284772] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.420s)
[60.287122] Subtest invalid: SUCCESS (0.421s)
[61.099684] [007/133] (957s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[61.196420] Starting subtest: fbc-2p-pri-indfb-multidraw
[61.200978] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[61.305817] [008/133] (957s left) gem_softpin (overlap)
[61.360976] Starting subtest: overlap
[62.272852] Subtest overlap: SUCCESS (0.917s)
[62.381168] [009/133] (956s left) kms_psr (fbc-psr-basic)
[62.482381] Starting subtest: fbc-psr-basic
[62.484614] Starting dynamic subtest: eDP-1
[62.488586] Dynamic subtest eDP-1: SKIP (0.000s)
[62.492713] Subtest fbc-psr-basic: SKIP (0.000s)
[62.550847] [010/133] (955s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[62.652469] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[62.654861] Starting dynamic subtest: pipe-A-eDP-1
[63.157800] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.481s)
[63.166950] Starting dynamic subtest: pipe-B-eDP-1
[64.624562] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.472s)
[64.634098] Starting dynamic subtest: pipe-C-eDP-1
[66.005813] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.381s)
[66.014534] Starting dynamic subtest: pipe-D-eDP-1
[67.341190] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.342s)
[67.343624] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.679s)
[68.823407] [011/133] (949s left) gem_vm_create (invalid-destroy)
[68.884307] Starting subtest: invalid-destroy
[68.886581] Subtest invalid-destroy: SUCCESS (0.002s)
[68.981084] [012/133] (949s left) i915_pm_rpm (debugfs-forcewake-user)
[71.136906] Starting subtest: debugfs-forcewake-user
[82.524456] Subtest debugfs-forcewake-user: SUCCESS (11.398s)
[82.918208] [013/133] (935s left) core_setmaster_vs_auth
[83.085534] [014/133] (935s left) gem_softpin (noreloc)
[83.140051] Starting subtest: noreloc
[88.550893] Subtest noreloc: SUCCESS (5.408s)
[88.656534] [015/133] (930s left) kms_flip (flip-vs-expired-vblank-interruptible)
[88.762748] Starting subtest: flip-vs-expired-vblank-interruptible
[88.765063] Starting dynamic subtest: A-eDP1
[97.414457] Dynamic subtest A-eDP1: SUCCESS (8.649s)
[97.425094] Starting dynamic subtest: B-eDP1
[106.043510] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[106.053117] Starting dynamic subtest: C-eDP1
[114.655942] Dynamic subtest C-eDP1: SUCCESS (8.611s)
[114.666183] Starting dynamic subtest: D-eDP1
[123.285209] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[123.289967] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.525s)
[124.458982] [016/133] (894s left) gem_mmap_gtt (isolation)
[124.524839] Subtest isolation: SKIP (0.000s)
[124.584218] [017/133] (894s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[124.692889] Starting subtest: pr-cursor-plane-move-continuous-sf
[124.697154] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[124.761963] [018/133] (894s left) gem_exec_params (larger-than-life-batch)
[124.825705] Starting subtest: larger-than-life-batch
[127.056845] Subtest larger-than-life-batch: SUCCESS (2.230s)
[127.940521] [019/133] (890s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[128.110734] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[128.114989] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[128.217836] [020/133] (890s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[128.608432] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[129.471490] [021/133] (889s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[129.572072] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[129.576378] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[129.639872] [022/133] (889s left) kms_dsc (dsc-basic)
[129.734267] Starting subtest: dsc-basic
[129.747784] Subtest dsc-basic: SKIP (0.001s)
[129.805506] [023/133] (889s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[129.877088] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[129.985884] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.106s)
[130.058251] [024/133] (889s left) kms_atomic_transition (plane-all-transition-nonblocking)
[130.152113] Starting subtest: plane-all-transition-nonblocking
[130.154583] Starting dynamic subtest: pipe-A-eDP-1
[132.645412] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[132.959857] Starting dynamic subtest: pipe-B-eDP-1
[136.233462] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.280s)
[136.535147] Subtest plane-all-transition-nonblocking: SUCCESS (6.383s)
[137.350663] [025/133] (881s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[137.505223] Starting subtest: dp-mst-lic-type-0-hdcp14
[137.509955] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[137.639466] [026/133] (881s left) kms_color (ctm-max)
[137.738782] Starting subtest: ctm-max
[137.740640] Starting dynamic subtest: pipe-A-eDP-1
[138.486744] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.745s)
[138.495585] Starting dynamic subtest: pipe-B-eDP-1
[139.892017] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.407s)
[139.900792] Starting dynamic subtest: pipe-C-eDP-1
[141.237374] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.345s)
[141.245344] Starting dynamic subtest: pipe-D-eDP-1
[142.576655] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.339s)
[142.578975] Subtest ctm-max: SUCCESS (4.838s)
[143.382132] [027/133] (875s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[143.481644] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[143.485936] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[143.583099] [028/133] (875s left) kms_async_flips (crc)
[143.677195] Starting subtest: crc
[144.068325] Starting dynamic subtest: pipe-A-eDP-1
[147.055156] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.984s)
[147.434917] Starting dynamic subtest: pipe-B-eDP-1
[150.340495] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.913s)
[150.720486] Starting dynamic subtest: pipe-C-eDP-1
[153.637514] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.925s)
[154.006867] Starting dynamic subtest: pipe-D-eDP-1
[156.859221] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.861s)
[156.861530] Subtest crc: SUCCESS (13.181s)
[158.055217] [029/133] (861s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[158.157189] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[158.159412] Starting dynamic subtest: pipe-A-eDP-1
[158.168172] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[158.176942] Starting dynamic subtest: pipe-B-eDP-1
[158.185398] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[158.193088] Starting dynamic subtest: pipe-C-eDP-1
[158.200598] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[158.207858] Starting dynamic subtest: pipe-D-eDP-1
[158.215728] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[158.219720] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[160.405494] [030/133] (858s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[160.506253] Starting subtest: fbc-1p-pri-indfb-multidraw
[173.494720] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (12.992s)
[174.831045] [031/133] (844s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[174.939519] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[174.943945] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[175.002598] [032/133] (844s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[175.098885] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[175.103072] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[175.187642] [033/133] (844s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[175.277607] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[175.282052] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[175.372022] [034/133] (844s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[175.470101] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[175.474160] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[175.538368] [035/133] (843s left) kms_dp_link_training (uhbr-mst)
[175.632731] Starting subtest: uhbr-mst
[175.641525] Subtest uhbr-mst: SKIP (0.000s)
[175.714538] [036/133] (843s left) kms_plane_multiple (2x-tiling-x)
[175.803336] Starting subtest: 2x-tiling-x
[175.806716] Subtest 2x-tiling-x: SKIP (0.000s)
[175.873049] [037/133] (843s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[175.974767] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[175.978488] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[176.060729] [038/133] (843s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[176.147861] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[176.151367] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[176.233387] [039/133] (843s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[176.333658] Starting subtest: 4-tiled-32bpp-rotate-0
[177.802185] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.469s)
[177.931438] [040/133] (841s left) gem_ctx_persistence (engines-mixed-process)
[177.983694] Starting subtest: engines-mixed-process
[177.985658] Starting dynamic subtest: rcs0
[179.929598] Dynamic subtest rcs0: SUCCESS (1.945s)
[179.939973] Starting dynamic subtest: bcs0
[181.909044] Dynamic subtest bcs0: SUCCESS (1.978s)
[181.919295] Starting dynamic subtest: vcs0
[183.898621] Dynamic subtest vcs0: SUCCESS (1.988s)
[183.909539] Starting dynamic subtest: vcs1
[185.868899] Dynamic subtest vcs1: SUCCESS (1.969s)
[185.878726] Starting dynamic subtest: vecs0
[187.909777] Dynamic subtest vecs0: SUCCESS (2.036s)
[187.921072] Starting dynamic subtest: ccs0
[189.893084] Dynamic subtest ccs0: SUCCESS (1.983s)
[189.895418] Subtest engines-mixed-process: SUCCESS (11.906s)
[190.015448] [041/133] (829s left) i915_drm_fdinfo (virtual-busy-hang-all)
[190.065657] Starting subtest: virtual-busy-hang-all
[190.070138] Subtest virtual-busy-hang-all: SKIP (0.000s)
[190.140846] [042/133] (829s left) kms_flip (2x-blocking-wf_vblank)
[190.240397] Starting subtest: 2x-blocking-wf_vblank
[190.247067] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[190.337619] [043/133] (829s left) kms_chamelium_color (gamma)
[190.432160] Subtest gamma: SKIP (0.000s)
[190.475563] [044/133] (829s left) sw_sync (sync_multi_timeline_wait)
[190.515655] Starting subtest: sync_multi_timeline_wait
[190.518038] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[190.564687] [045/133] (829s left) prime_self_import (basic-with_fd_dup)
[190.595806] Starting subtest: basic-with_fd_dup
[190.615333] Subtest basic-with_fd_dup: SUCCESS (0.020s)
[190.705815] [046/133] (829s left) i915_drm_fdinfo (virtual-busy)
[190.744360] Starting subtest: virtual-busy
[190.748836] Subtest virtual-busy: SKIP (0.000s)
[190.802543] [047/133] (829s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[190.886331] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[190.888676] Starting dynamic subtest: pipe-A
[190.907590] Dynamic subtest pipe-A: SKIP (0.018s)
[190.918473] Starting dynamic subtest: pipe-B
[190.938887] Dynamic subtest pipe-B: SKIP (0.032s)
[190.947325] Starting dynamic subtest: pipe-C
[190.970534] Dynamic subtest pipe-C: SKIP (0.031s)
[190.978757] Starting dynamic subtest: pipe-D
[191.003544] Dynamic subtest pipe-D: SKIP (0.033s)
[191.006661] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.115s)
[191.097726] [048/133] (828s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[191.199427] Starting subtest: fbc-rgb565-draw-pwrite
[195.777393] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.581s)
[196.913600] [049/133] (823s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[197.025385] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[197.029370] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[197.114638] [050/133] (822s left) kms_fbcon_fbt (psr)
[197.176602] Starting subtest: psr
[204.549029] Subtest psr: SUCCESS (7.368s)
[204.627184] [051/133] (815s left) kms_pm_backlight (fade-with-suspend)
[204.716129] Starting subtest: fade-with-suspend
[221.381657] Starting dynamic subtest: eDP-1
[223.852355] Dynamic subtest eDP-1: SUCCESS (2.547s)
[223.854726] Subtest fade-with-suspend: SUCCESS (4.623s)
[224.681761] [052/133] (795s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[224.776652] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[224.839357] [053/133] (795s left) prime_vgem (basic-blt)
[224.989375] Starting subtest: basic-blt
[225.014756] Subtest basic-blt: SUCCESS (0.026s)
[225.138696] [FACT prime_vgem (basic-blt)] new: kernel.kmod_is_loaded.vgem: true
[225.141883] [054/133] (795s left) gem_softpin (allocator-basic-reserve)
[225.198549] Starting subtest: allocator-basic-reserve
[225.270932] Subtest allocator-basic-reserve: SUCCESS (0.073s)
[225.406618] [055/133] (794s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[225.507396] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[232.101187] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.705s)
[232.213873] [056/133] (788s left) gem_exec_schedule (u-fairslice)
[232.293426] Starting subtest: u-fairslice
[232.295670] Starting dynamic subtest: rcs0
[234.309376] Dynamic subtest rcs0: SUCCESS (2.040s)
[234.320254] Starting dynamic subtest: bcs0
[236.327717] Dynamic subtest bcs0: SUCCESS (2.032s)
[236.338110] Starting dynamic subtest: vcs0
[238.346105] Dynamic subtest vcs0: SUCCESS (2.029s)
[238.356194] Starting dynamic subtest: vcs1
[240.365491] Dynamic subtest vcs1: SUCCESS (2.027s)
[240.377112] Starting dynamic subtest: vecs0
[242.386479] Dynamic subtest vecs0: SUCCESS (2.026s)
[242.396725] Starting dynamic subtest: ccs0
[244.405319] Dynamic subtest ccs0: SUCCESS (2.024s)
[244.407250] Subtest u-fairslice: SUCCESS (12.183s)
[244.705642] [057/133] (775s left) gem_lmem_evict (dontneed-evict-race)
[244.748388] Subtest dontneed-evict-race: SKIP (0.000s)
[244.791323] [058/133] (775s left) gem_exec_reloc (basic-gtt-cpu-active)
[244.856794] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[244.953892] [059/133] (775s left) kms_pipe_crc_basic (suspend-read-crc)
[245.052365] Starting subtest: suspend-read-crc
[245.054668] Starting dynamic subtest: pipe-A-eDP-1
[263.278316] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.509s)
[263.288566] Starting dynamic subtest: pipe-B-eDP-1
[281.327177] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.242s)
[281.339090] Starting dynamic subtest: pipe-C-eDP-1
[299.254137] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.049s)
[299.264482] Starting dynamic subtest: pipe-D-eDP-1
[317.241165] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.051s)
[317.243912] Subtest suspend-read-crc: SUCCESS (11.856s)
[318.108302] [060/133] (702s left) gem_mmap_gtt (basic)
[318.172062] Subtest basic: SKIP (0.000s)
[318.242503] [061/133] (702s left) syncobj_wait (invalid-signal-illegal-handle)
[318.305822] Starting subtest: invalid-signal-illegal-handle
[318.308202] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[318.401059] [062/133] (702s left) perf_pmu (busy-idle-check-all)
[318.445801] Starting subtest: busy-idle-check-all
[318.448235] Starting dynamic subtest: rcs0
[319.096663] Dynamic subtest rcs0: SUCCESS (0.657s)
[319.108640] Starting dynamic subtest: bcs0
[319.753641] Dynamic subtest bcs0: SUCCESS (0.655s)
[319.766967] Starting dynamic subtest: vcs0
[320.474306] Dynamic subtest vcs0: FAIL (0.643s)
[320.484652] Starting dynamic subtest: vcs1
[322.765461] Dynamic subtest vcs1: SUCCESS (2.367s)
[322.777621] Starting dynamic subtest: vecs0
[324.813021] Dynamic subtest vecs0: SUCCESS (2.045s)
[324.825401] Starting dynamic subtest: ccs0
[325.472298] Dynamic subtest ccs0: SUCCESS (0.658s)
[325.475604] Subtest busy-idle-check-all: FAIL (7.039s)
[325.583700] [063/133] (695s left) kms_hdr (bpc-switch-suspend)
[325.683279] Starting subtest: bpc-switch-suspend
[326.744192] Starting dynamic subtest: pipe-A-eDP-1
[346.504380] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.605s)
[346.507016] Subtest bpc-switch-suspend: SUCCESS (5.670s)
[346.605373] [064/133] (674s left) gem_ctx_persistence (legacy-engines-hang)
[346.677108] Starting subtest: legacy-engines-hang
[346.679267] Starting dynamic subtest: render
[346.794929] Dynamic subtest render: SUCCESS (0.124s)
[346.808107] Starting dynamic subtest: bsd1
[346.889878] Dynamic subtest bsd1: SUCCESS (0.095s)
[346.901629] Starting dynamic subtest: bsd2
[346.982932] Dynamic subtest bsd2: SUCCESS (0.093s)
[346.997048] Starting dynamic subtest: blt
[347.006525] Dynamic subtest blt: SUCCESS (0.021s)
[347.016089] Starting dynamic subtest: vebox
[347.117915] Dynamic subtest vebox: SUCCESS (0.113s)
[347.120634] Subtest legacy-engines-hang: SUCCESS (0.460s)
[347.201620] [065/133] (673s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[347.300508] Starting subtest: yf-tiled-32bpp-rotate-270
[347.305403] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[347.396830] [066/133] (673s left) kms_cursor_crc (cursor-offscreen-128x42)
[347.603540] Starting subtest: cursor-offscreen-128x42
[347.659975] Subtest cursor-offscreen-128x42: SKIP (0.054s)
[347.855750] [067/133] (672s left) gem_create (create-valid-nonaligned)
[347.901853] Starting subtest: create-valid-nonaligned
[347.903950] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[348.082764] [068/133] (672s left) kms_rotation_crc (primary-rotation-270)
[348.187412] Starting subtest: primary-rotation-270
[348.192373] Subtest primary-rotation-270: SKIP (0.001s)
[348.273156] [069/133] (672s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[348.363091] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[348.367503] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[348.441750] [070/133] (672s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[348.554088] Starting subtest: verify-pxp-stale-buf-optout-execution
[349.613593] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.087s)
[349.738507] [071/133] (671s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[349.860306] Starting subtest: basic-flip-before-cursor-legacy
[350.759071] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.919s)
[351.561145] [072/133] (669s left) kms_async_flips (test-cursor)
[351.667619] Starting subtest: test-cursor
[352.033291] Starting dynamic subtest: pipe-A-eDP-1
[352.821634] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.802s)
[353.211926] Starting dynamic subtest: pipe-B-eDP-1
[353.957204] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[354.347203] Starting dynamic subtest: pipe-C-eDP-1
[355.082463] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.753s)
[355.458362] Starting dynamic subtest: pipe-D-eDP-1
[356.206217] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.764s)
[356.208901] Subtest test-cursor: SUCCESS (4.606s)
[357.365318] [073/133] (663s left) core_setmaster (master-drop-set-shared-fd)
[357.443057] Starting subtest: master-drop-set-shared-fd
[357.451164] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[357.489760] [074/133] (663s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[357.549551] Starting subtest: multi-wait-available-unsubmitted-signaled
[357.552099] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[357.647736] [075/133] (663s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[357.756496] Starting subtest: cursor-tearing-framebuffer-change
[357.759107] Starting dynamic subtest: pipe-A-eDP-1
[358.329140] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[358.340330] Starting dynamic subtest: pipe-D-eDP-1
[359.564278] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.243s)
[359.567107] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.819s)
[360.397444] [076/133] (660s left) gem_eio (in-flight-external)
[360.630907] Starting subtest: in-flight-external
[362.087302] Subtest in-flight-external: SUCCESS (1.465s)
[362.206118] [077/133] (659s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[362.307639] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[362.312860] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[362.409822] [078/133] (658s left) kms_pm_rpm (i2c)
[364.142388] Starting subtest: i2c
[369.386664] Subtest i2c: SUCCESS (5.263s)
[369.508428] [079/133] (651s left) kms_vblank (query-forked-hang)
[369.599467] Starting subtest: query-forked-hang
[369.601924] Starting dynamic subtest: pipe-A-eDP-1
[371.457166] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.863s)
[371.474316] Starting dynamic subtest: pipe-D-eDP-1
[374.062484] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.604s)
[374.064907] Subtest query-forked-hang: SUCCESS (4.472s)
[374.922828] [080/133] (646s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[375.021325] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[375.026302] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[375.115595] [081/133] (646s left) kms_pipe_crc_basic (bad-source)
[375.217497] Starting subtest: bad-source
[375.220448] Subtest bad-source: SUCCESS (0.000s)
[375.289957] [082/133] (646s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[375.351415] Starting subtest: single-wait-for-submit-available-signaled
[375.354195] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[375.464060] [083/133] (646s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[375.537186] Starting subtest: yf-tiled-to-vebox-yf-tiled
[375.542518] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[375.726763] [084/133] (645s left) api_intel_bb (crc32)
[375.785407] Starting subtest: crc32
[375.787638] Starting dynamic subtest: rcs0-smem0
[376.098506] Dynamic subtest rcs0-smem0: SUCCESS (0.313s)
[376.110840] Starting dynamic subtest: bcs0-smem0
[376.410872] Dynamic subtest bcs0-smem0: SUCCESS (0.310s)
[376.421935] Starting dynamic subtest: vcs0-smem0
[376.831649] Dynamic subtest vcs0-smem0: SUCCESS (0.418s)
[376.848650] Starting dynamic subtest: vcs1-smem0
[377.205920] Dynamic subtest vcs1-smem0: SUCCESS (0.372s)
[377.219723] Starting dynamic subtest: vecs0-smem0
[377.555003] Dynamic subtest vecs0-smem0: SUCCESS (0.347s)
[377.568729] Starting dynamic subtest: ccs0-smem0
[377.906700] Dynamic subtest ccs0-smem0: SUCCESS (0.350s)
[377.909702] Subtest crc32: SUCCESS (2.125s)
[378.020575] [085/133] (643s left) template (a)
[378.094963] Starting subtest: A
[378.097437] Subtest A: SUCCESS (0.000s)
[378.176780] [086/133] (643s left) gem_exec_fence (parallel)
[378.351063] Starting subtest: parallel
[378.353810] Starting dynamic subtest: rcs0
[380.504995] Dynamic subtest rcs0: SUCCESS (2.154s)
[380.516376] Starting dynamic subtest: bcs0
[382.657241] Dynamic subtest bcs0: SUCCESS (2.150s)
[382.670977] Starting dynamic subtest: vcs0
[384.809952] Dynamic subtest vcs0: SUCCESS (2.150s)
[384.821326] Starting dynamic subtest: vcs1
[386.961886] Dynamic subtest vcs1: SUCCESS (2.150s)
[386.972063] Starting dynamic subtest: vecs0
[389.119923] Dynamic subtest vecs0: SUCCESS (2.156s)
[389.132186] Starting dynamic subtest: ccs0
[391.270978] Dynamic subtest ccs0: SUCCESS (2.150s)
[391.273314] Subtest parallel: SUCCESS (12.916s)
[392.130086] [087/133] (629s left) kms_psr (psr-sprite-blt)
[392.227112] Starting subtest: psr-sprite-blt
[392.229398] Starting dynamic subtest: eDP-1
[393.948493] Dynamic subtest eDP-1: SUCCESS (1.718s)
[393.951428] Subtest psr-sprite-blt: SUCCESS (1.719s)
[394.783100] [088/133] (626s left) gem_mmap (bad-object)
[394.836281] Subtest bad-object: SKIP (0.000s)
[394.901482] [089/133] (626s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[394.964211] Starting subtest: etime-single-wait-available-unsubmitted
[395.063356] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.101s)
[395.141024] [090/133] (626s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[395.241690] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[395.243893] Starting dynamic subtest: pipe-A-eDP-1
[395.265427] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[395.277442] Starting dynamic subtest: pipe-B-eDP-1
[395.301812] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[395.311586] Starting dynamic subtest: pipe-C-eDP-1
[395.337027] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[395.347344] Starting dynamic subtest: pipe-D-eDP-1
[395.372841] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[395.377489] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[397.546654] [091/133] (624s left) kms_psr (fbc-pr-basic)
[397.635439] Starting subtest: fbc-pr-basic
[397.639875] Subtest fbc-pr-basic: SKIP (0.000s)
[397.707280] [092/133] (624s left) fbdev (unaligned-read)
[397.758814] Starting subtest: unaligned-read
[397.773339] Subtest unaligned-read: SUCCESS (0.015s)
[397.828694] [093/133] (624s left) gem_ctx_param (set-priority-not-supported)
[397.874893] Starting subtest: set-priority-not-supported
[397.879562] Subtest set-priority-not-supported: SKIP (0.000s)
[397.940009] [094/133] (624s left) gem_exec_schedule (preempt)
[398.015496] Starting subtest: preempt
[398.018405] Starting dynamic subtest: rcs0
[398.028536] Dynamic subtest rcs0: SUCCESS (0.025s)
[398.040448] Starting dynamic subtest: bcs0
[398.051128] Dynamic subtest bcs0: SUCCESS (0.022s)
[398.061331] Starting dynamic subtest: vcs0
[398.073053] Dynamic subtest vcs0: SUCCESS (0.021s)
[398.083420] Starting dynamic subtest: vcs1
[398.103628] Dynamic subtest vcs1: SUCCESS (0.030s)
[398.113685] Starting dynamic subtest: vecs0
[398.124485] Dynamic subtest vecs0: SUCCESS (0.020s)
[398.135282] Starting dynamic subtest: ccs0
[398.150999] Dynamic subtest ccs0: SUCCESS (0.025s)
[398.153548] Subtest preempt: SUCCESS (0.148s)
[400.105592] [095/133] (621s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[400.213698] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[407.366821] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.157s)
[407.481187] [096/133] (614s left) sw_sync (sync_multi_consumer)
[407.525256] Starting subtest: sync_multi_consumer
[408.410132] Subtest sync_multi_consumer: SUCCESS (0.884s)
[408.473701] [097/133] (613s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[408.572793] Starting subtest: y-tiled-addfb-size-offset-overflow
[408.578284] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[408.672336] [098/133] (613s left) perf_pmu (all-busy-idle-check-all)
[408.720963] Starting subtest: all-busy-idle-check-all
[410.014152] Subtest all-busy-idle-check-all: SUCCESS (1.293s)
[410.131025] [099/133] (612s left) gem_eio (wait-wedge-10ms)
[410.353323] Starting subtest: wait-wedge-10ms
[412.151394] Subtest wait-wedge-10ms: SUCCESS (1.797s)
[412.306047] [100/133] (609s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[412.414250] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[416.422637] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.013s)
[417.569317] [101/133] (604s left) kms_psr (pr-sprite-mmap-cpu)
[417.659554] Starting subtest: pr-sprite-mmap-cpu
[417.664929] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[417.735347] [102/133] (604s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[417.801760] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[417.871043] [103/133] (604s left) syncobj_timeline (invalid-query-illegal-handle)
[417.927102] Starting subtest: invalid-query-illegal-handle
[417.929488] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[418.022414] [104/133] (604s left) kms_cursor_crc (cursor-random-32x10)
[418.212327] Starting subtest: cursor-random-32x10
[418.257203] Subtest cursor-random-32x10: SKIP (0.039s)
[418.576295] [105/133] (603s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[418.672257] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[418.674164] Starting dynamic subtest: pipe-A-eDP-1
[420.798042] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.127s)
[420.808070] Starting dynamic subtest: pipe-B-eDP-1
[422.997361] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.198s)
[423.008087] Starting dynamic subtest: pipe-C-eDP-1
[425.112317] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.116s)
[425.122357] Starting dynamic subtest: pipe-D-eDP-1
[427.320217] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.206s)
[427.322446] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.652s)
[428.527378] [106/133] (594s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[428.624496] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[428.629879] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[428.711501] [107/133] (593s left) gem_pread (display)
[428.773381] Subtest display: SKIP (0.000s)
[428.833835] [108/133] (593s left) gem_exec_basic (basic)
[428.891429] Starting subtest: basic
[428.893683] Starting dynamic subtest: rcs0-smem
[428.896107] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[428.906325] Starting dynamic subtest: bcs0-smem
[428.908508] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[428.919915] Starting dynamic subtest: vcs0-smem
[428.922640] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[428.933268] Starting dynamic subtest: vcs1-smem
[428.935871] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[428.946407] Starting dynamic subtest: vecs0-smem
[428.949209] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[428.959655] Starting dynamic subtest: ccs0-smem
[428.962469] Dynamic subtest ccs0-smem: SUCCESS (0.003s)
[428.965307] Subtest basic: SUCCESS (0.019s)
[430.097034] [109/133] (592s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[430.207543] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[430.213198] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[430.290421] [110/133] (592s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[430.379398] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[430.382082] Starting dynamic subtest: pipe-A
[430.718969] Dynamic subtest pipe-A: SUCCESS (0.342s)
[430.729388] Starting dynamic subtest: pipe-B
[432.116081] Dynamic subtest pipe-B: SUCCESS (1.397s)
[432.126588] Starting dynamic subtest: pipe-C
[433.531222] Dynamic subtest pipe-C: SUCCESS (1.414s)
[433.544570] Starting dynamic subtest: pipe-D
[435.083414] Dynamic subtest pipe-D: SUCCESS (1.552s)
[435.086125] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.709s)
[436.236941] [111/133] (586s left) gem_exec_balancer (bonded-pair)
[436.302907] Starting subtest: bonded-pair
[436.308468] Subtest bonded-pair: SKIP (0.000s)
[436.611226] [112/133] (586s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[436.712802] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[444.074552] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.366s)
[444.176500] [113/133] (578s left) kms_legacy_colorkey (invalid-plane)
[444.272805] Starting subtest: invalid-plane
[444.275198] Starting dynamic subtest: zero-id
[444.277373] Dynamic subtest zero-id: SUCCESS (0.000s)
[444.288661] Starting dynamic subtest: outrange-id
[444.291433] Dynamic subtest outrange-id: SUCCESS (0.000s)
[444.294043] Subtest invalid-plane: SUCCESS (0.001s)
[444.346087] [114/133] (578s left) api_intel_bb (purge-bb)
[444.414358] Starting subtest: purge-bb
[444.417195] Subtest purge-bb: SUCCESS (0.005s)
[444.547365] [115/133] (578s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[444.650626] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[451.364161] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.714s)
[451.478889] [116/133] (571s left) perf_pmu (rc6)
[451.520158] Starting subtest: rc6
[451.522909] Starting dynamic subtest: gt0
[457.893392] Dynamic subtest gt0: SUCCESS (6.369s)
[457.906635] Starting dynamic subtest: runtime-pm-gt0
[465.617912] Dynamic subtest runtime-pm-gt0: SUCCESS (7.722s)
[465.631669] Starting dynamic subtest: runtime-pm-long-gt0
[478.148894] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.502s)
[478.162129] Starting dynamic subtest: other-idle-gt0
[484.465969] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[484.480454] Starting dynamic subtest: gt1
[490.808154] Dynamic subtest gt1: SUCCESS (6.340s)
[490.821913] Starting dynamic subtest: runtime-pm-gt1
[498.283478] Dynamic subtest runtime-pm-gt1: SUCCESS (7.473s)
[498.297571] Starting dynamic subtest: runtime-pm-long-gt1
[510.733085] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.449s)
[510.747677] Starting dynamic subtest: other-idle-gt1
[517.076525] Dynamic subtest other-idle-gt1: SUCCESS (6.343s)
[517.079538] Subtest rc6: SUCCESS (65.556s)
[518.364391] [117/133] (504s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[518.452360] Starting subtest: plane-primary-toggle-with-vblank-wait
[518.454633] Starting dynamic subtest: pipe-A-eDP-1
[518.628904] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.177s)
[518.950885] Starting dynamic subtest: pipe-B-eDP-1
[519.837635] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.900s)
[520.140919] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.688s)
[520.990569] [118/133] (502s left) gem_cs_tlb (engines)
[521.053593] Starting subtest: engines
[521.056501] Starting dynamic subtest: rcs0
[523.211505] Dynamic subtest rcs0: SUCCESS (2.154s)
[523.224912] Starting dynamic subtest: bcs0
[525.363443] Dynamic subtest bcs0: SUCCESS (2.152s)
[525.376543] Starting dynamic subtest: vcs0
[527.519647] Dynamic subtest vcs0: SUCCESS (2.155s)
[527.530644] Starting dynamic subtest: vcs1
[529.674883] Dynamic subtest vcs1: SUCCESS (2.154s)
[529.686302] Starting dynamic subtest: vecs0
[531.829429] Dynamic subtest vecs0: SUCCESS (2.154s)
[531.840130] Starting dynamic subtest: ccs0
[533.984438] Dynamic subtest ccs0: SUCCESS (2.154s)
[533.987268] Subtest engines: SUCCESS (12.930s)
[534.078445] [119/133] (489s left) kms_cdclk (mode-transition-all-outputs)
[534.171319] Starting subtest: mode-transition-all-outputs
[534.473009] Subtest mode-transition-all-outputs: SKIP (0.297s)
[535.317866] [120/133] (487s left) kms_psr (fbc-pr-sprite-plane-onoff)
[535.403383] Starting subtest: fbc-pr-sprite-plane-onoff
[535.408056] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[535.459087] [121/133] (487s left) gem_exec_whisper (basic-normal-all)
[535.519970] Starting subtest: basic-normal-all
[537.874982] Subtest basic-normal-all: SUCCESS (2.354s)
[538.039700] [122/133] (485s left) kms_flip (2x-flip-vs-panning-interruptible)
[538.133830] Starting subtest: 2x-flip-vs-panning-interruptible
[538.147955] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[538.223477] [123/133] (485s left) kms_addfb_basic (unused-pitches)
[538.276857] Starting subtest: unused-pitches
[538.279507] Subtest unused-pitches: SUCCESS (0.000s)
[538.380084] [124/133] (484s left) gem_ctx_shared (q-out-order)
[538.438669] Starting subtest: Q-out-order
[538.441469] Starting dynamic subtest: rcs0
[538.566487] Dynamic subtest rcs0: SUCCESS (0.129s)
[538.581002] Starting dynamic subtest: vcs0
[538.660636] Dynamic subtest vcs0: SUCCESS (0.094s)
[538.680940] Starting dynamic subtest: vcs1
[538.749292] Dynamic subtest vcs1: SUCCESS (0.087s)
[538.768492] Starting dynamic subtest: vecs0
[538.830376] Dynamic subtest vecs0: SUCCESS (0.080s)
[538.849879] Starting dynamic subtest: ccs0
[538.930218] Dynamic subtest ccs0: SUCCESS (0.099s)
[538.933513] Subtest Q-out-order: SUCCESS (0.495s)
[540.140797] [125/133] (483s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[540.248577] Starting subtest: psr-rgb565-draw-mmap-wc
[546.960574] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.716s)
[547.042782] [126/133] (476s left) gem_media_fill (media-fill)
[547.110340] Subtest media-fill: SKIP (0.000s)
[547.170659] [127/133] (476s left) kms_vrr (seamless-rr-switch-virtual)
[547.267818] Starting subtest: seamless-rr-switch-virtual
[547.275557] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[547.319527] [128/133] (476s left) gem_exec_flush (basic-uc-set-default)
[547.386372] Starting subtest: basic-uc-set-default
[552.847225] Subtest basic-uc-set-default: SUCCESS (5.463s)
[553.013935] [129/133] (470s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[553.122042] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[553.126790] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[553.204890] [130/133] (470s left) gen9_exec_parse (bb-large)
[553.263328] Subtest bb-large: SKIP (0.000s)
[553.332285] [131/133] (470s left) vgem_basic (bad-flag)
[553.385539] Starting subtest: bad-flag
[553.388411] Subtest bad-flag: SUCCESS (0.000s)
[553.429302] [132/133] (470s left) i915_suspend (forcewake)
[553.480835] Starting subtest: forcewake
[586.394312] Subtest forcewake: SUCCESS (2.231s)
[587.446168] [133/133] (437s left) kms_psr (psr2-primary-blt)
[587.564467] Starting subtest: psr2-primary-blt
[587.566802] Starting dynamic subtest: eDP-1
[589.317009] Dynamic subtest eDP-1: SUCCESS (1.805s)
[589.319159] Subtest psr2-primary-blt: SUCCESS (1.807s)
[590.122381] 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.