[58.959780] Initializing watchdogs
[58.959827]   /dev/watchdog0
[58.973287] [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]
[58.977446] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.979543] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.983072] [001/132] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[59.107196] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[59.111340] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[59.215749] [002/132] (960s left) kms_cursor_crc (cursor-onscreen-64x21)
[59.406073] Starting subtest: cursor-onscreen-64x21
[59.471699] Subtest cursor-onscreen-64x21: SKIP (0.060s)
[59.748151] [003/132] (959s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[64.770918] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[64.773355] Starting dynamic subtest: pipe-A-eDP-1
[65.553394] Dynamic subtest pipe-A-eDP-1: SKIP (0.779s)
[65.561802] Starting dynamic subtest: pipe-B-eDP-1
[66.687094] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[66.691513] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.915s)
[67.985055] [004/132] (951s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[68.084573] Starting subtest: primary-y-tiled-reflect-x-90
[68.088881] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[68.175973] [005/132] (951s left) kms_vrr (negative-basic)
[68.280847] Starting subtest: negative-basic
[68.287677] Starting dynamic subtest: pipe-A-eDP-1
[73.701471] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.414s)
[74.000712] Subtest negative-basic: SUCCESS (5.720s)
[74.819019] [006/132] (944s left) gem_exec_whisper (basic-fds-priority)
[74.885045] Starting subtest: basic-fds-priority
[97.953195] Subtest basic-fds-priority: SUCCESS (23.062s)
[98.157936] [007/132] (921s left) kms_chamelium_edid (dp-edid-resolution-list)
[98.543919] Subtest dp-edid-resolution-list: SKIP (0.000s)
[99.304582] [008/132] (920s left) kms_frontbuffer_tracking (fbc-suspend)
[99.406387] Starting subtest: fbc-suspend
[137.962985] Subtest fbc-suspend: SUCCESS (11.135s)
[139.179997] [009/132] (880s left) syncobj_timeline (multi-wait-submitted-signaled)
[139.243698] Starting subtest: multi-wait-submitted-signaled
[139.246117] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[139.353385] [010/132] (880s left) syncobj_wait (multi-wait-all-submitted)
[139.405115] Starting subtest: multi-wait-all-submitted
[139.513409] Subtest multi-wait-all-submitted: SUCCESS (0.107s)
[139.643395] [011/132] (880s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[139.762010] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[139.766397] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[139.927598] [012/132] (880s left) gem_partial_pwrite_pread (write-uncached)
[139.991864] Subtest write-uncached: SKIP (0.000s)
[140.065942] [013/132] (879s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[140.158732] Starting subtest: fbc-pr-sprite-mmap-gtt
[140.162881] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[140.252097] [014/132] (879s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[140.360820] Starting subtest: x-tiled-32bpp-rotate-270
[140.379974] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[140.525201] [015/132] (879s left) gem_exec_fence (basic-wait)
[140.703037] Starting subtest: basic-wait
[140.705486] Starting dynamic subtest: rcs0
[140.806043] Dynamic subtest rcs0: SUCCESS (0.102s)
[140.816651] Starting dynamic subtest: bcs0
[140.861924] Dynamic subtest bcs0: SUCCESS (0.056s)
[140.872124] Starting dynamic subtest: vcs0
[140.924634] Dynamic subtest vcs0: SUCCESS (0.062s)
[140.934423] Starting dynamic subtest: vcs1
[140.992319] Dynamic subtest vcs1: SUCCESS (0.067s)
[141.002870] Starting dynamic subtest: vecs0
[141.056731] Dynamic subtest vecs0: SUCCESS (0.063s)
[141.066174] Starting dynamic subtest: ccs0
[141.122035] Dynamic subtest ccs0: SUCCESS (0.064s)
[141.124566] Subtest basic-wait: SUCCESS (0.420s)
[143.435413] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[143.441124] [016/132] (876s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[143.541746] Starting subtest: plane-lut1d-post-ctm3x4
[143.554188] Starting dynamic subtest: pipe-A-eDP-1
[144.285518] Dynamic subtest pipe-A-eDP-1: FAIL (0.336s)
[144.932563] Starting dynamic subtest: pipe-B-eDP-1
[145.751874] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[146.275062] Starting dynamic subtest: pipe-C-eDP-1
[147.115769] Dynamic subtest pipe-C-eDP-1: FAIL (0.258s)
[147.601188] Starting dynamic subtest: pipe-D-eDP-1
[148.430145] Dynamic subtest pipe-D-eDP-1: FAIL (0.247s)
[148.432076] Subtest plane-lut1d-post-ctm3x4: FAIL (4.578s)
[148.994132] [017/132] (871s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[149.099037] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[149.101199] Starting dynamic subtest: pipe-A-default-mode
[151.792375] Dynamic subtest pipe-A-default-mode: SUCCESS (2.693s)
[151.794812] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.694s)
[152.683660] [018/132] (867s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[152.788517] Starting subtest: 2x-absolute-wf_vblank-interruptible
[152.798377] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.010s)
[152.896405] [019/132] (867s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[153.005555] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[159.508911] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.507s)
[159.647076] [020/132] (860s left) gem_exec_params (invalid-bsd2-flag-on-render)
[159.714938] Starting subtest: invalid-bsd2-flag-on-render
[159.717330] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[159.842532] [021/132] (860s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[159.946200] Starting subtest: psr-rgb101010-draw-blt
[167.177073] Subtest psr-rgb101010-draw-blt: SUCCESS (7.232s)
[167.338171] [022/132] (853s left) syncobj_timeline (invalid-wait-bad-flags)
[167.405151] Starting subtest: invalid-wait-bad-flags
[167.407343] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[167.537148] [023/132] (852s left) gem_mmap_gtt (cpuset-big-copy-odd)
[167.603281] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[167.672490] [024/132] (852s left) gem_mmap_wc (bad-size)
[167.732058] Subtest bad-size: SKIP (0.000s)
[167.796904] [025/132] (852s left) gem_exec_balancer (invalid-bonds)
[167.862981] Starting subtest: invalid-bonds
[167.867617] Subtest invalid-bonds: SKIP (0.000s)
[168.196244] [026/132] (852s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[168.303250] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[168.307704] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[168.433542] [027/132] (852s left) kms_lease (lease-get)
[168.535902] Starting subtest: lease-get
[168.538259] Starting dynamic subtest: pipe-A-eDP-1
[168.540496] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[168.550964] Starting dynamic subtest: pipe-B-eDP-1
[168.553030] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[168.561288] Starting dynamic subtest: pipe-C-eDP-1
[168.563740] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[168.571830] Starting dynamic subtest: pipe-D-eDP-1
[168.573947] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[168.575960] Subtest lease-get: SUCCESS (0.008s)
[168.684113] [028/132] (851s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[168.782044] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[172.970320] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.191s)
[174.183146] [029/132] (846s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[174.567676] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[175.360541] [030/132] (845s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[175.471909] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[179.165671] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.697s)
[180.407377] [031/132] (840s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[180.509979] Starting subtest: yf-tiled-64bpp-rotate-0
[180.514492] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[180.648789] [032/132] (840s left) gem_mmap_gtt (medium-copy-odd)
[180.716251] Subtest medium-copy-odd: SKIP (0.000s)
[180.794629] [033/132] (840s left) gem_pxp (protected-raw-src-copy-not-readible)
[180.917103] Starting subtest: protected-raw-src-copy-not-readible
[180.974269] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.059s)
[181.134058] [034/132] (839s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[181.253284] Starting subtest: flip-vs-cursor-crc-atomic
[181.924208] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.670s)
[182.815088] [035/132] (838s left) kms_atomic_transition (plane-all-transition-fencing)
[182.909526] Starting subtest: plane-all-transition-fencing
[182.911885] Starting dynamic subtest: pipe-A-eDP-1
[185.403724] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[185.722460] Starting dynamic subtest: pipe-B-eDP-1
[188.930375] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.216s)
[189.231200] Subtest plane-all-transition-fencing: SUCCESS (6.319s)
[190.083249] [036/132] (830s left) prime_self_import (basic-with_one_bo_two_files)
[190.126418] Starting subtest: basic-with_one_bo_two_files
[190.150748] Subtest basic-with_one_bo_two_files: SUCCESS (0.026s)
[190.291441] [037/132] (830s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[190.388778] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[190.391090] Starting dynamic subtest: pipe-A-eDP-1
[190.413860] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[190.423901] Starting dynamic subtest: pipe-B-eDP-1
[190.445461] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[190.452858] Starting dynamic subtest: pipe-C-eDP-1
[190.474345] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[190.482055] Starting dynamic subtest: pipe-D-eDP-1
[190.504040] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[190.508429] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[192.554238] [038/132] (828s left) i915_drm_fdinfo (all-busy-check-all)
[192.594754] Starting subtest: all-busy-check-all
[192.598836] Subtest all-busy-check-all: SKIP (0.003s)
[192.700118] [039/132] (828s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[192.829364] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[192.831562] Starting dynamic subtest: pipe-A-default-mode
[193.124844] Dynamic subtest pipe-A-default-mode: SKIP (0.292s)
[193.129356] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.294s)
[193.986307] [040/132] (827s left) kms_addfb_basic (bad-pitch-0)
[194.050373] Starting subtest: bad-pitch-0
[194.052366] Subtest bad-pitch-0: SUCCESS (0.000s)
[194.225741] [041/132] (826s left) gem_ctx_persistence (heartbeat-stop)
[194.290459] Starting subtest: heartbeat-stop
[194.295015] Subtest heartbeat-stop: SKIP (0.000s)
[194.420772] [042/132] (826s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[194.529594] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[194.533949] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[194.655081] [043/132] (826s left) gem_exec_schedule (smoketest)
[194.833620] Starting subtest: smoketest
[194.836441] Starting dynamic subtest: rcs0
[200.372544] Dynamic subtest rcs0: SUCCESS (5.491s)
[200.387936] Starting dynamic subtest: bcs0
[205.906321] Dynamic subtest bcs0: SUCCESS (5.532s)
[205.922273] Starting dynamic subtest: vcs0
[211.424122] Dynamic subtest vcs0: SUCCESS (5.511s)
[211.439506] Starting dynamic subtest: vcs1
[216.917047] Dynamic subtest vcs1: SUCCESS (5.491s)
[216.928033] Starting dynamic subtest: vecs0
[222.423306] Dynamic subtest vecs0: SUCCESS (5.506s)
[222.438805] Starting dynamic subtest: ccs0
[227.924779] Dynamic subtest ccs0: SUCCESS (5.499s)
[227.926970] Subtest smoketest: SUCCESS (33.038s)
[228.149520] [044/132] (793s left) gem_exec_parallel (contexts)
[228.220480] Starting subtest: contexts
[228.222849] Starting dynamic subtest: rcs0
[232.278296] Dynamic subtest rcs0: SUCCESS (4.055s)
[232.289875] Starting dynamic subtest: bcs0
[236.055279] Dynamic subtest bcs0: SUCCESS (3.775s)
[236.063786] Starting dynamic subtest: vcs0
[239.812037] Dynamic subtest vcs0: SUCCESS (3.753s)
[239.820464] Starting dynamic subtest: vcs1
[243.822570] Dynamic subtest vcs1: SUCCESS (4.007s)
[243.833204] Starting dynamic subtest: vecs0
[247.846709] Dynamic subtest vecs0: SUCCESS (4.022s)
[247.856570] Starting dynamic subtest: ccs0
[251.609712] Dynamic subtest ccs0: SUCCESS (3.760s)
[251.612421] Subtest contexts: SUCCESS (23.388s)
[251.827333] [045/132] (769s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[251.895974] Starting subtest: invalid-bsd2-flag-on-blt
[251.898216] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[252.040669] [046/132] (769s left) kms_psr (fbc-psr2-primary-render)
[252.156572] Starting subtest: fbc-psr2-primary-render
[252.158864] Starting dynamic subtest: eDP-1
[252.163215] Dynamic subtest eDP-1: SKIP (0.000s)
[252.167672] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[252.256513] [047/132] (769s left) prime_vgem (coherency-gtt)
[252.338563] Starting subtest: coherency-gtt
[252.342830] Subtest coherency-gtt: SKIP (0.000s)
[252.479296] [048/132] (768s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[252.605107] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[252.609632] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[252.771867] [049/132] (768s left) kms_flip (flip-vs-expired-vblank)
[252.869218] Starting subtest: flip-vs-expired-vblank
[252.871172] Starting dynamic subtest: A-eDP1
[261.559004] Dynamic subtest A-eDP1: SUCCESS (8.689s)
[261.569200] Starting dynamic subtest: B-eDP1
[270.199089] Dynamic subtest B-eDP1: SUCCESS (8.637s)
[270.208591] Starting dynamic subtest: C-eDP1
[278.828749] Dynamic subtest C-eDP1: SUCCESS (8.628s)
[278.839804] Starting dynamic subtest: D-eDP1
[287.458440] Dynamic subtest D-eDP1: SUCCESS (8.626s)
[287.463699] Subtest flip-vs-expired-vblank: SUCCESS (34.593s)
[288.662959] [050/132] (732s left) gem_mmap_wc (write-read)
[288.731931] Subtest write-read: SKIP (0.000s)
[288.802253] [051/132] (732s left) kms_psr (psr-sprite-render)
[288.892655] Starting subtest: psr-sprite-render
[288.894902] Starting dynamic subtest: eDP-1
[290.663159] Dynamic subtest eDP-1: SUCCESS (1.771s)
[290.665482] Subtest psr-sprite-render: SUCCESS (1.772s)
[291.517250] [052/132] (730s left) kms_psr (psr2-cursor-blt)
[291.616004] Starting subtest: psr2-cursor-blt
[291.618330] Starting dynamic subtest: eDP-1
[293.271569] Dynamic subtest eDP-1: SUCCESS (1.656s)
[293.273988] Subtest psr2-cursor-blt: SUCCESS (1.657s)
[294.096021] [053/132] (727s left) kms_prime (basic-modeset-hybrid)
[294.179458] Subtest basic-modeset-hybrid: SKIP (0.000s)
[294.295418] [054/132] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[294.397165] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[294.401575] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[294.523841] [055/132] (727s left) kms_psr (fbc-psr-sprite-plane-onoff)
[294.632706] Starting subtest: fbc-psr-sprite-plane-onoff
[294.634925] Starting dynamic subtest: eDP-1
[294.638875] Dynamic subtest eDP-1: SKIP (0.000s)
[294.642867] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[294.732104] [056/132] (727s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[294.808847] Starting subtest: stress-mm-invalidate-close-overlap
[296.957206] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[297.122486] [057/132] (724s left) gem_exec_params (batch-first)
[297.181803] Starting subtest: batch-first
[297.193119] Subtest batch-first: SUCCESS (0.011s)
[297.366848] [058/132] (724s left) i915_pm_rpm (gem-mmap-type)
[299.493038] Starting subtest: gem-mmap-type
[299.495585] Starting dynamic subtest: wb-smem0
[303.247264] Dynamic subtest wb-smem0: SUCCESS (3.756s)
[303.256428] Starting dynamic subtest: wc-smem0
[306.715733] Dynamic subtest wc-smem0: SUCCESS (3.468s)
[306.724766] Starting dynamic subtest: uc-smem0
[310.201837] Dynamic subtest uc-smem0: SUCCESS (3.482s)
[310.205041] Subtest gem-mmap-type: SUCCESS (10.709s)
[310.322131] [059/132] (711s left) dumb_buffer (create-clear)
[310.378091] Starting subtest: create-clear
[345.045733] Subtest create-clear: SUCCESS (34.663s)
[345.201013] [060/132] (676s left) i915_module_load (resize-bar)
[345.237346] Starting subtest: resize-bar
[345.243608] Subtest resize-bar: SKIP (0.005s)
[345.296928] [061/132] (676s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[345.364062] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[345.368500] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[345.574828] [062/132] (676s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[345.701354] Starting subtest: basic-busy-flip-before-cursor-varying-size
[345.706215] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[345.877614] [063/132] (676s left) gem_eio (kms)
[346.151653] Starting subtest: kms
[367.038753] Subtest kms: SUCCESS (20.886s)
[368.237308] [064/132] (653s left) gem_exec_fence (keep-in-fence)
[368.419517] Starting subtest: keep-in-fence
[368.422021] Starting dynamic subtest: rcs0
[368.474099] Dynamic subtest rcs0: SUCCESS (0.055s)
[368.485794] Starting dynamic subtest: bcs0
[368.528046] Dynamic subtest bcs0: SUCCESS (0.052s)
[368.539796] Starting dynamic subtest: vcs0
[370.357358] Dynamic subtest vcs0: SUCCESS (1.825s)
[370.367981] Starting dynamic subtest: vcs1
[372.341046] Dynamic subtest vcs1: SUCCESS (1.981s)
[372.353504] Starting dynamic subtest: vecs0
[374.259719] Dynamic subtest vecs0: SUCCESS (1.916s)
[374.280430] Starting dynamic subtest: ccs0
[374.515456] Dynamic subtest ccs0: SUCCESS (0.253s)
[374.518349] Subtest keep-in-fence: SUCCESS (6.097s)
[376.433844] [065/132] (645s left) kms_big_fb (linear-8bpp-rotate-270)
[376.537097] Starting subtest: linear-8bpp-rotate-270
[376.559183] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[376.721261] [066/132] (645s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[376.809661] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[383.855874] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.047s)
[384.027590] [067/132] (638s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[384.120576] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[384.125118] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[385.306299] [068/132] (636s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[385.412202] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[385.416612] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[385.523140] [069/132] (636s left) i915_query (query-garbage)
[385.577257] Starting subtest: query-garbage
[385.579230] Subtest query-garbage: SUCCESS (0.000s)
[385.712313] [070/132] (636s left) gem_huc_copy (huc-copy)
[385.780651] Starting subtest: huc-copy
[385.785725] Subtest huc-copy: SUCCESS (0.003s)
[385.949154] [071/132] (636s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[386.057559] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[389.824492] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.770s)
[391.012902] [072/132] (631s left) gem_create (create-invalid-size)
[391.077728] Starting subtest: create-invalid-size
[391.080109] Subtest create-invalid-size: SUCCESS (0.000s)
[391.289075] [073/132] (631s left) kms_pm_rpm (drm-resources-equal)
[393.512668] Starting subtest: drm-resources-equal
[398.500596] Subtest drm-resources-equal: SUCCESS (4.989s)
[398.639283] [074/132] (623s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[398.747397] Starting subtest: random-ccs-data-yf-tiled-ccs
[398.749547] Starting dynamic subtest: pipe-A-eDP-1
[398.773457] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[398.784332] Starting dynamic subtest: pipe-B-eDP-1
[398.806823] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[398.815200] Starting dynamic subtest: pipe-C-eDP-1
[398.838112] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[398.846334] Starting dynamic subtest: pipe-D-eDP-1
[398.870081] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[398.874353] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[400.910806] [075/132] (621s left) kms_lease (empty-lease)
[401.010970] Starting subtest: empty-lease
[401.013256] Starting dynamic subtest: pipe-A-eDP-1
[401.015491] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[401.024960] Starting dynamic subtest: pipe-B-eDP-1
[401.027141] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[401.036115] Starting dynamic subtest: pipe-C-eDP-1
[401.038396] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[401.047233] Starting dynamic subtest: pipe-D-eDP-1
[401.049453] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[401.051731] Subtest empty-lease: SUCCESS (0.008s)
[401.138034] [076/132] (621s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[401.240871] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[401.245018] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[401.360099] [077/132] (621s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[401.424930] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[401.426998] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[401.541545] [078/132] (621s left) api_intel_allocator (gem-pool)
[401.607292] Starting subtest: gem-pool
[401.612425] Subtest gem-pool: SUCCESS (0.007s)
[401.730537] [079/132] (621s left) core_getclient (basic)
[401.794250] Starting subtest: basic
[401.796487] Subtest basic: SUCCESS (0.000s)
[401.962606] [080/132] (620s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[402.065161] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[402.066967] Starting dynamic subtest: pipe-A
[402.427197] Dynamic subtest pipe-A: SUCCESS (0.364s)
[402.436749] Starting dynamic subtest: pipe-B
[403.890432] Dynamic subtest pipe-B: SUCCESS (1.462s)
[403.899846] Starting dynamic subtest: pipe-C
[405.318676] Dynamic subtest pipe-C: SUCCESS (1.427s)
[405.334716] Starting dynamic subtest: pipe-D
[406.778927] Dynamic subtest pipe-D: SUCCESS (1.459s)
[406.781172] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.716s)
[407.956870] [081/132] (614s left) syncobj_timeline (etime-single-wait-submitted)
[408.026911] Starting subtest: etime-single-wait-submitted
[408.127864] Subtest etime-single-wait-submitted: SUCCESS (0.102s)
[408.233152] [082/132] (614s left) gem_basic (create-close)
[408.284665] Starting subtest: create-close
[408.288947] Subtest create-close: SUCCESS (0.000s)
[408.413282] [083/132] (614s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[408.522595] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[415.092794] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.572s)
[415.257002] [084/132] (607s left) gem_spin_batch (legacy-resubmit)
[415.325786] Starting subtest: legacy-resubmit
[415.327674] Starting dynamic subtest: default
[415.330112] Dynamic subtest default: SUCCESS (0.005s)
[415.340224] Starting dynamic subtest: render
[415.342644] Dynamic subtest render: SUCCESS (0.001s)
[415.351226] Starting dynamic subtest: bsd
[415.353138] Dynamic subtest bsd: SUCCESS (0.002s)
[415.361182] Starting dynamic subtest: bsd1
[415.363119] Dynamic subtest bsd1: SUCCESS (0.001s)
[415.371293] Starting dynamic subtest: bsd2
[415.373219] Dynamic subtest bsd2: SUCCESS (0.002s)
[415.381444] Starting dynamic subtest: blt
[415.383340] Dynamic subtest blt: SUCCESS (0.002s)
[415.391428] Starting dynamic subtest: vebox
[415.393353] Dynamic subtest vebox: SUCCESS (0.003s)
[415.395281] Subtest legacy-resubmit: SUCCESS (0.023s)
[415.627527] [085/132] (607s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[415.727771] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[415.730068] Starting dynamic subtest: pipe-A-eDP-1
[415.750619] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[415.762262] Starting dynamic subtest: pipe-B-eDP-1
[415.781921] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[415.791170] Starting dynamic subtest: pipe-C-eDP-1
[415.811146] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[415.820724] Starting dynamic subtest: pipe-D-eDP-1
[415.840330] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[415.842650] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[417.969941] [086/132] (605s left) perf_pmu (semaphore-wait-idle)
[418.010423] Starting subtest: semaphore-wait-idle
[418.012750] Starting dynamic subtest: rcs0
[418.525517] Dynamic subtest rcs0: SUCCESS (0.515s)
[418.538412] Starting dynamic subtest: bcs0
[419.039131] Dynamic subtest bcs0: SUCCESS (0.511s)
[419.051856] Starting dynamic subtest: vcs0
[419.552965] Dynamic subtest vcs0: SUCCESS (0.510s)
[419.565392] Starting dynamic subtest: vcs1
[420.066686] Dynamic subtest vcs1: SUCCESS (0.511s)
[420.079006] Starting dynamic subtest: vecs0
[420.580048] Dynamic subtest vecs0: SUCCESS (0.510s)
[420.593527] Starting dynamic subtest: ccs0
[421.095748] Dynamic subtest ccs0: SUCCESS (0.513s)
[421.098562] Subtest semaphore-wait-idle: SUCCESS (3.089s)
[422.352642] [087/132] (600s left) gem_wait (write-wait)
[422.420018] Starting subtest: write-wait
[422.422429] Starting dynamic subtest: all-engines
[423.564679] Dynamic subtest all-engines: SUCCESS (1.144s)
[423.582560] Starting dynamic subtest: rcs0
[424.625439] Dynamic subtest rcs0: SUCCESS (1.058s)
[424.641378] Starting dynamic subtest: bcs0
[425.682244] Dynamic subtest bcs0: SUCCESS (1.055s)
[425.697357] Starting dynamic subtest: vcs0
[426.735847] Dynamic subtest vcs0: SUCCESS (1.052s)
[426.752785] Starting dynamic subtest: vcs1
[427.789759] Dynamic subtest vcs1: SUCCESS (1.052s)
[427.805782] Starting dynamic subtest: vecs0
[428.844004] Dynamic subtest vecs0: SUCCESS (1.052s)
[428.860583] Starting dynamic subtest: ccs0
[429.902458] Dynamic subtest ccs0: SUCCESS (1.056s)
[429.905039] Subtest write-wait: SUCCESS (7.483s)
[430.162590] [088/132] (593s left) kms_atomic (plane-primary-legacy)
[430.259917] Starting subtest: plane-primary-legacy
[430.261851] Starting dynamic subtest: pipe-A-eDP-1
[430.715018] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[430.717201] Subtest plane-primary-legacy: SUCCESS (0.456s)
[431.603152] [089/132] (591s left) sysfs_preempt_timeout (invalid)
[431.663678] Starting subtest: invalid
[431.665941] Starting dynamic subtest: vecs0
[431.668029] Dynamic subtest vecs0: SUCCESS (0.000s)
[431.677292] Starting dynamic subtest: ccs0
[431.679524] Dynamic subtest ccs0: SUCCESS (0.000s)
[431.694877] Starting dynamic subtest: vcs0
[431.696980] Dynamic subtest vcs0: SUCCESS (0.000s)
[431.705821] Starting dynamic subtest: bcs0
[431.707896] Dynamic subtest bcs0: SUCCESS (0.000s)
[431.716313] Starting dynamic subtest: rcs0
[431.718367] Dynamic subtest rcs0: SUCCESS (0.000s)
[431.726527] Starting dynamic subtest: vcs1
[431.728631] Dynamic subtest vcs1: SUCCESS (0.000s)
[431.730752] Subtest invalid: SUCCESS (0.007s)
[431.834103] [090/132] (591s left) gem_exec_fence (syncobj-timeline-signal)
[432.362496] Starting subtest: syncobj-timeline-signal
[432.365517] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[433.418947] [091/132] (589s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[433.617799] Starting subtest: cursor-rapid-movement-64x21
[433.661575] Subtest cursor-rapid-movement-64x21: SKIP (0.038s)
[433.949628] [092/132] (589s left) perf (oa-formats)
[434.090842] Starting subtest: oa-formats
[434.092734] Starting dynamic subtest: 0-rcs0
[434.106664] Dynamic subtest 0-rcs0: SUCCESS (0.017s)
[434.116794] Starting dynamic subtest: 1-vcs0
[434.129153] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[434.131513] Subtest oa-formats: SUCCESS (0.041s)
[434.177998] [093/132] (589s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[434.281758] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[434.283665] Starting dynamic subtest: pipe-A-eDP-1
[434.346918] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.064s)
[434.355860] Starting dynamic subtest: pipe-B-eDP-1
[434.405031] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.060s)
[434.413926] Starting dynamic subtest: pipe-C-eDP-1
[434.464477] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.058s)
[434.473570] Starting dynamic subtest: pipe-D-eDP-1
[434.523681] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.058s)
[434.525506] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.244s)
[436.777717] [094/132] (586s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[436.840604] Starting subtest: invalid-bsd1-flag-on-blt
[436.842959] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[436.971534] [095/132] (586s left) gem_ctx_persistence (engines-cleanup)
[437.034306] Starting subtest: engines-cleanup
[437.036953] Starting dynamic subtest: rcs0
[437.159251] Dynamic subtest rcs0: SUCCESS (0.125s)
[437.174060] Starting dynamic subtest: bcs0
[437.235673] Dynamic subtest bcs0: SUCCESS (0.074s)
[437.244959] Starting dynamic subtest: vcs0
[437.322732] Dynamic subtest vcs0: SUCCESS (0.085s)
[437.334387] Starting dynamic subtest: vcs1
[437.387620] Dynamic subtest vcs1: SUCCESS (0.063s)
[437.399260] Starting dynamic subtest: vecs0
[437.460343] Dynamic subtest vecs0: SUCCESS (0.071s)
[437.471170] Starting dynamic subtest: ccs0
[437.535486] Dynamic subtest ccs0: SUCCESS (0.073s)
[437.538031] Subtest engines-cleanup: SUCCESS (0.502s)
[437.640880] [096/132] (585s left) i915_suspend (debugfs-reader)
[437.719546] Starting subtest: debugfs-reader
[455.490731] Subtest debugfs-reader: SUCCESS (3.144s)
[455.595387] [097/132] (568s left) kms_draw_crc (draw-method-mmap-wc)
[455.694598] Starting subtest: draw-method-mmap-wc
[455.696851] Starting dynamic subtest: xrgb8888-untiled
[455.914387] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[455.923354] Starting dynamic subtest: rgb565-untiled
[456.192124] Dynamic subtest rgb565-untiled: SUCCESS (0.277s)
[456.201532] Starting dynamic subtest: xrgb2101010-untiled
[456.403616] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[456.413471] Starting dynamic subtest: xbgr16161616f-untiled
[456.614367] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[456.624106] Starting dynamic subtest: xrgb8888-xtiled
[456.748172] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[456.757523] Starting dynamic subtest: rgb565-xtiled
[456.870209] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[456.879265] Starting dynamic subtest: xrgb2101010-xtiled
[456.992477] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[457.001995] Starting dynamic subtest: xbgr16161616f-xtiled
[457.114963] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[457.123711] Starting dynamic subtest: xrgb8888-4tiled
[457.259112] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.142s)
[457.268479] Starting dynamic subtest: rgb565-4tiled
[457.414723] Dynamic subtest rgb565-4tiled: SUCCESS (0.155s)
[457.423754] Starting dynamic subtest: xrgb2101010-4tiled
[457.548220] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[457.557175] Starting dynamic subtest: xbgr16161616f-4tiled
[457.726759] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[457.729204] Subtest draw-method-mmap-wc: SUCCESS (2.036s)
[457.848845] [098/132] (565s left) gem_userptr_blits (sync-unmap)
[457.919547] Starting subtest: sync-unmap
[457.931815] Subtest sync-unmap: SUCCESS (0.014s)
[458.098520] [099/132] (565s left) kms_content_protection (dp-mst-suspend-resume)
[458.238414] Starting subtest: dp-mst-suspend-resume
[458.243147] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[458.399028] [100/132] (565s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[458.497419] Starting subtest: addfb25-y-tiled-small-legacy
[458.501544] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[458.621951] [101/132] (565s left) syncobj_timeline (wait-for-submit-snapshot)
[458.686752] Starting subtest: wait-for-submit-snapshot
[458.767327] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[458.869964] [102/132] (564s left) gem_eio (in-flight-internal-1us)
[459.074990] Starting subtest: in-flight-internal-1us
[461.013528] Subtest in-flight-internal-1us: SUCCESS (1.936s)
[461.202060] [103/132] (562s left) gem_create (create-size-update)
[461.261012] Starting subtest: create-size-update
[461.263608] Subtest create-size-update: SUCCESS (0.000s)
[461.519026] [104/132] (562s left) kms_draw_crc (fill-fb)
[461.625272] Starting subtest: fill-fb
[462.043527] Subtest fill-fb: SUCCESS (0.420s)
[462.163355] [105/132] (561s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[462.266640] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[462.269423] Starting dynamic subtest: pipe-A-eDP-1
[462.687587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.394s)
[462.696328] Starting dynamic subtest: pipe-B-eDP-1
[464.046966] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.354s)
[464.056657] Starting dynamic subtest: pipe-C-eDP-1
[465.390519] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.342s)
[465.401150] Starting dynamic subtest: pipe-D-eDP-1
[466.709696] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.321s)
[466.712292] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.416s)
[467.842179] [106/132] (556s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[467.961008] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[467.966407] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[468.099915] [107/132] (555s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[468.209548] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[468.214543] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[468.337910] [108/132] (555s left) gem_mmap_offset (bad-flags)
[468.392107] Starting subtest: bad-flags
[468.394562] Subtest bad-flags: SUCCESS (0.000s)
[468.523805] [109/132] (555s left) gem_mmap_gtt (big-copy)
[468.592477] Subtest big-copy: SKIP (0.000s)
[468.658192] [110/132] (555s left) kms_plane_multiple (tiling-y)
[468.749454] Starting subtest: tiling-y
[468.753128] Subtest tiling-y: SKIP (0.000s)
[468.841047] [111/132] (555s left) kms_plane_scaling (planes-upscale-factor-0-25)
[468.935643] Starting subtest: planes-upscale-factor-0-25
[468.938266] Starting dynamic subtest: pipe-A
[468.995610] Dynamic subtest pipe-A: SUCCESS (0.062s)
[469.005792] Starting dynamic subtest: pipe-B
[470.145760] Dynamic subtest pipe-B: SUCCESS (1.149s)
[470.157652] Starting dynamic subtest: pipe-C
[471.267858] Dynamic subtest pipe-C: SUCCESS (1.121s)
[471.280700] Starting dynamic subtest: pipe-D
[472.383303] Dynamic subtest pipe-D: SUCCESS (1.115s)
[472.385776] Subtest planes-upscale-factor-0-25: SUCCESS (3.450s)
[473.567346] [112/132] (550s left) gem_ctx_param (set-priority-range)
[473.626710] Starting subtest: set-priority-range
[473.642099] Subtest set-priority-range: SUCCESS (0.015s)
[473.769410] [113/132] (550s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[473.876757] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[473.881757] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[474.003172] [114/132] (550s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[474.105696] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[480.678320] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.575s)
[480.847560] [115/132] (543s left) kms_scaling_modes (scaling-mode-center)
[480.940535] Starting subtest: scaling-mode-center
[480.943306] Starting dynamic subtest: pipe-A-eDP-1
[481.034247] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[481.054434] Starting dynamic subtest: pipe-B-eDP-1
[482.212866] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.175s)
[482.225833] Starting dynamic subtest: pipe-C-eDP-1
[483.324707] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.108s)
[483.337593] Starting dynamic subtest: pipe-D-eDP-1
[484.453151] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[484.455733] Subtest scaling-mode-center: SUCCESS (3.515s)
[485.656070] [116/132] (538s left) gen9_exec_parse (secure-batches)
[485.720000] Subtest secure-batches: SKIP (0.000s)
[485.786790] [117/132] (538s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[485.897421] Starting subtest: 4-tiled-64bpp-rotate-180
[487.422641] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.526s)
[487.649199] [118/132] (536s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[492.703498] Starting subtest: psr2-plane-move-sf-dmg-area
[492.706269] Starting dynamic subtest: pipe-A-eDP-1
[498.007383] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.301s)
[498.017258] Starting dynamic subtest: pipe-B-eDP-1
[503.311492] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.303s)
[503.314545] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.608s)
[504.212771] [119/132] (520s left) kms_vblank (query-forked-busy)
[504.311497] Starting subtest: query-forked-busy
[504.314078] Starting dynamic subtest: pipe-A-eDP-1
[506.191718] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.882s)
[506.208425] Starting dynamic subtest: pipe-D-eDP-1
[508.747910] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.553s)
[508.750634] Subtest query-forked-busy: SUCCESS (4.438s)
[509.568536] [120/132] (515s left) gem_ccs (block-copy-uncompressed)
[509.645372] Starting subtest: block-copy-uncompressed
[509.647775] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[509.669640] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[509.681626] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[509.686733] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[509.695941] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[509.704324] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[509.713656] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[509.721696] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[509.724503] Subtest block-copy-uncompressed: SUCCESS (0.076s)
[509.888328] [121/132] (514s left) syncobj_eventfd (binary-wait-signaled)
[509.950998] Starting subtest: binary-wait-signaled
[509.953732] Subtest binary-wait-signaled: SUCCESS (0.000s)
[510.077363] [122/132] (514s left) kms_draw_crc (draw-method-mmap-gtt)
[510.176226] Starting subtest: draw-method-mmap-gtt
[510.180628] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[510.313764] [123/132] (514s left) gem_exec_schedule (preempt-other)
[510.379788] Starting subtest: preempt-other
[510.381985] Starting dynamic subtest: rcs0
[510.443915] Dynamic subtest rcs0: SUCCESS (0.074s)
[510.456385] Starting dynamic subtest: bcs0
[510.511996] Dynamic subtest bcs0: SUCCESS (0.067s)
[510.523333] Starting dynamic subtest: vcs0
[510.578439] Dynamic subtest vcs0: SUCCESS (0.066s)
[510.590875] Starting dynamic subtest: vcs1
[510.643047] Dynamic subtest vcs1: SUCCESS (0.064s)
[510.657369] Starting dynamic subtest: vecs0
[510.710187] Dynamic subtest vecs0: SUCCESS (0.067s)
[510.721602] Starting dynamic subtest: ccs0
[510.775726] Dynamic subtest ccs0: SUCCESS (0.065s)
[510.778388] Subtest preempt-other: SUCCESS (0.407s)
[512.073241] [124/132] (512s left) kms_plane_lowres (tiling-4)
[512.161304] Starting subtest: tiling-4
[512.163737] Starting dynamic subtest: pipe-A-eDP-1
[512.168786] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[512.455619] Starting dynamic subtest: pipe-B-eDP-1
[512.460638] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[512.470333] Starting dynamic subtest: pipe-C-eDP-1
[512.475383] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[512.484729] Starting dynamic subtest: pipe-D-eDP-1
[512.489837] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[512.494869] Subtest tiling-4: SKIP (0.292s)
[513.305692] [125/132] (511s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[513.399615] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[519.581547] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.184s)
[520.811126] [126/132] (504s left) vgem_basic (setversion)
[520.858894] Starting subtest: setversion
[520.863474] Subtest setversion: SUCCESS (0.000s)
[520.939230] [127/132] (503s left) gem_exec_suspend (basic-s3-devices)
[520.994694] Starting subtest: basic-S3-devices
[520.997482] Starting dynamic subtest: smem
[532.951567] Dynamic subtest smem: SUCCESS (11.954s)
[532.954750] Subtest basic-S3-devices: SUCCESS (11.956s)
[533.158237] [128/132] (491s left) gem_eio (in-flight-suspend)
[533.399065] Starting subtest: in-flight-suspend
[576.804235] Subtest in-flight-suspend: SUCCESS (13.621s)
[576.991199] [129/132] (447s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[577.101096] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[584.484437] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.385s)
[584.645542] [130/132] (440s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[584.748072] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[589.084940] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.341s)
[590.316057] [131/132] (434s left) perf (non-zero-reason)
[590.427268] Starting subtest: non-zero-reason
[590.430469] Starting dynamic subtest: 0-rcs0
[595.788763] Dynamic subtest 0-rcs0: SUCCESS (5.360s)
[595.800307] Starting dynamic subtest: 1-vcs0
[599.699264] Dynamic subtest 1-vcs0: SUCCESS (3.909s)
[599.702407] Subtest non-zero-reason: SUCCESS (9.271s)
[599.748621] [132/132] (425s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[599.808488] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[599.913497] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[600.020683] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_cursor_crc
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_rotation_crc
results: parsing output: 4/ for test: kms_vrr
results: parsing output: 5/ for test: gem_exec_whisper
results: parsing output: 6/ for test: kms_chamelium_edid
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_partial_pwrite_pread
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: kms_color_pipeline
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_mmap_gtt
results: parsing output: 23/ for test: gem_mmap_wc
results: parsing output: 24/ for test: gem_exec_balancer
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_lease
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_chamelium_hpd
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: gem_pxp
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_atomic_transition
results: parsing output: 35/ for test: prime_self_import
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: gem_exec_parallel
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: prime_vgem
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_mmap_wc
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_prime
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_userptr_blits
results: parsing output: 56/ for test: gem_exec_params
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: dumb_buffer
results: parsing output: 59/ for test: i915_module_load
results: parsing output: 60/ for test: gem_render_copy
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: gem_eio
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: i915_query
results: parsing output: 69/ for test: gem_huc_copy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_create
results: parsing output: 72/ for test: kms_pm_rpm
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_lease
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: api_intel_allocator
results: parsing output: 78/ for test: core_getclient
results: parsing output: 79/ for test: kms_plane_scaling
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_basic
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_spin_batch
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: gem_wait
results: parsing output: 87/ for test: kms_atomic
results: parsing output: 88/ for test: sysfs_preempt_timeout
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: kms_cursor_crc
results: parsing output: 91/ for test: perf
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: i915_suspend
results: parsing output: 96/ for test: kms_draw_crc
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_content_protection
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: gem_create
results: parsing output: 103/ for test: kms_draw_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_mmap_offset
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_plane_multiple
results: parsing output: 110/ for test: kms_plane_scaling
results: parsing output: 111/ for test: gem_ctx_param
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_scaling_modes
results: parsing output: 115/ for test: gen9_exec_parse
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_vblank
results: parsing output: 119/ for test: gem_ccs
results: parsing output: 120/ for test: syncobj_eventfd
results: parsing output: 121/ for test: kms_draw_crc
results: parsing output: 122/ for test: gem_exec_schedule
results: parsing output: 123/ for test: kms_plane_lowres
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: vgem_basic
results: parsing output: 126/ for test: gem_exec_suspend
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: perf
results: parsing output: 131/ for test: syncobj_timeline
Done.