[58.600546] Initializing watchdogs
[58.600598]   /dev/watchdog0
[58.631032] [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.635428] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.637768] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.641112] [001/132] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[58.773631] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[58.777805] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[58.906584] [002/132] (960s left) kms_cursor_crc (cursor-onscreen-64x21)
[59.099640] Starting subtest: cursor-onscreen-64x21
[59.148481] Subtest cursor-onscreen-64x21: SKIP (0.046s)
[59.417107] [003/132] (959s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[64.429701] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[64.432115] Starting dynamic subtest: pipe-A-eDP-1
[65.213735] Dynamic subtest pipe-A-eDP-1: SKIP (0.780s)
[65.222495] Starting dynamic subtest: pipe-B-eDP-1
[66.351274] Dynamic subtest pipe-B-eDP-1: SKIP (1.135s)
[66.355277] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.919s)
[67.567443] [004/132] (951s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[67.728432] Starting subtest: primary-y-tiled-reflect-x-90
[67.732587] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[67.907557] [005/132] (951s left) kms_vrr (negative-basic)
[68.065835] Starting subtest: negative-basic
[68.072737] Starting dynamic subtest: pipe-A-eDP-1
[73.965716] Dynamic subtest pipe-A-eDP-1: FAIL (5.420s)
[73.967521] Subtest negative-basic: FAIL (5.739s)
[74.698667] [006/132] (944s left) gem_exec_whisper (basic-fds-priority)
[74.777563] Starting subtest: basic-fds-priority
[97.942648] Subtest basic-fds-priority: SUCCESS (23.154s)
[98.147332] [007/132] (921s left) kms_chamelium_edid (dp-edid-resolution-list)
[98.552357] Subtest dp-edid-resolution-list: SKIP (0.000s)
[99.381339] [008/132] (920s left) kms_frontbuffer_tracking (fbc-suspend)
[99.481766] Starting subtest: fbc-suspend
[137.651812] Subtest fbc-suspend: SUCCESS (12.189s)
[138.876377] [009/132] (880s left) syncobj_timeline (multi-wait-submitted-signaled)
[138.945160] Starting subtest: multi-wait-submitted-signaled
[138.947713] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[139.063097] [010/132] (880s left) syncobj_wait (multi-wait-all-submitted)
[139.116024] Starting subtest: multi-wait-all-submitted
[139.220646] Subtest multi-wait-all-submitted: SUCCESS (0.103s)
[139.323914] [011/132] (880s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[139.423817] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[139.428380] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[139.536681] [012/132] (880s left) gem_partial_pwrite_pread (write-uncached)
[139.597234] Subtest write-uncached: SKIP (0.000s)
[139.678242] [013/132] (879s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[139.771396] Starting subtest: fbc-pr-sprite-mmap-gtt
[139.775822] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[139.863154] [014/132] (879s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[139.964478] Starting subtest: x-tiled-32bpp-rotate-270
[139.988246] Subtest x-tiled-32bpp-rotate-270: SKIP (0.023s)
[140.145823] [015/132] (879s left) gem_exec_fence (basic-wait)
[140.332961] Starting subtest: basic-wait
[140.335451] Starting dynamic subtest: rcs0
[140.437960] Dynamic subtest rcs0: SUCCESS (0.105s)
[140.446723] Starting dynamic subtest: bcs0
[140.493741] Dynamic subtest bcs0: SUCCESS (0.055s)
[140.508786] Starting dynamic subtest: vcs0
[140.560557] Dynamic subtest vcs0: SUCCESS (0.065s)
[140.571649] Starting dynamic subtest: vcs1
[140.617669] Dynamic subtest vcs1: SUCCESS (0.056s)
[140.628034] Starting dynamic subtest: vecs0
[140.670470] Dynamic subtest vecs0: SUCCESS (0.052s)
[140.680646] Starting dynamic subtest: ccs0
[140.730158] Dynamic subtest ccs0: SUCCESS (0.058s)
[140.733046] Subtest basic-wait: SUCCESS (0.396s)
[142.936884] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[142.941325] [016/132] (876s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[143.048949] Starting subtest: plane-lut1d-post-ctm3x4
[143.062588] Starting dynamic subtest: pipe-A-eDP-1
[143.767497] Dynamic subtest pipe-A-eDP-1: FAIL (0.294s)
[144.441259] Starting dynamic subtest: pipe-B-eDP-1
[145.279919] Dynamic subtest pipe-B-eDP-1: FAIL (0.302s)
[145.766919] Starting dynamic subtest: pipe-C-eDP-1
[146.624759] Dynamic subtest pipe-C-eDP-1: FAIL (0.269s)
[147.091746] Starting dynamic subtest: pipe-D-eDP-1
[147.959277] Dynamic subtest pipe-D-eDP-1: FAIL (0.280s)
[147.961720] Subtest plane-lut1d-post-ctm3x4: FAIL (4.612s)
[148.504509] [017/132] (871s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[148.609903] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[148.612255] Starting dynamic subtest: pipe-A-default-mode
[151.304663] Dynamic subtest pipe-A-default-mode: SUCCESS (2.690s)
[151.306877] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.692s)
[152.200900] [018/132] (867s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[152.300211] Starting subtest: 2x-absolute-wf_vblank-interruptible
[152.312072] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[152.410095] [019/132] (867s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[152.505786] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[159.043546] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.535s)
[159.179908] [020/132] (860s left) gem_exec_params (invalid-bsd2-flag-on-render)
[159.240725] Starting subtest: invalid-bsd2-flag-on-render
[159.243222] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[159.374441] [021/132] (860s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[159.486576] Starting subtest: psr-rgb101010-draw-blt
[166.926506] Subtest psr-rgb101010-draw-blt: SUCCESS (7.439s)
[167.088510] [022/132] (852s left) syncobj_timeline (invalid-wait-bad-flags)
[167.151378] Starting subtest: invalid-wait-bad-flags
[167.153785] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[167.287920] [023/132] (852s left) gem_mmap_gtt (cpuset-big-copy-odd)
[167.355781] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[167.415181] [024/132] (852s left) gem_mmap_wc (bad-size)
[167.479540] Subtest bad-size: SKIP (0.000s)
[167.552350] [025/132] (852s left) gem_exec_balancer (invalid-bonds)
[167.632679] Starting subtest: invalid-bonds
[167.636845] Subtest invalid-bonds: SKIP (0.001s)
[167.973500] [026/132] (852s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[168.083992] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[168.087964] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[168.220656] [027/132] (852s left) kms_lease (lease-get)
[168.313897] Starting subtest: lease-get
[168.316126] Starting dynamic subtest: pipe-A-eDP-1
[168.318439] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[168.326850] Starting dynamic subtest: pipe-B-eDP-1
[168.328763] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[168.336661] Starting dynamic subtest: pipe-C-eDP-1
[168.338530] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[168.346513] Starting dynamic subtest: pipe-D-eDP-1
[168.348445] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[168.350316] Subtest lease-get: SUCCESS (0.008s)
[168.440340] [028/132] (851s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[168.547015] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[172.792694] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.251s)
[173.935463] [029/132] (846s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[174.343985] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[175.113176] [030/132] (845s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[175.233129] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[178.860545] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.632s)
[180.039362] [031/132] (840s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[180.147459] Starting subtest: yf-tiled-64bpp-rotate-0
[180.151796] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[180.281024] [032/132] (840s left) gem_mmap_gtt (medium-copy-odd)
[180.336364] Subtest medium-copy-odd: SKIP (0.000s)
[180.409972] [033/132] (840s left) gem_pxp (protected-raw-src-copy-not-readible)
[180.542868] Starting subtest: protected-raw-src-copy-not-readible
[180.603242] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.062s)
[180.774943] [034/132] (839s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[180.900249] Starting subtest: flip-vs-cursor-crc-atomic
[181.582246] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.682s)
[182.452964] [035/132] (838s left) kms_atomic_transition (plane-all-transition-fencing)
[182.547251] Starting subtest: plane-all-transition-fencing
[182.549434] Starting dynamic subtest: pipe-A-eDP-1
[185.041719] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[185.346891] Starting dynamic subtest: pipe-B-eDP-1
[188.556555] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.219s)
[188.853606] Subtest plane-all-transition-fencing: SUCCESS (6.307s)
[189.708447] [036/132] (830s left) prime_self_import (basic-with_one_bo_two_files)
[189.756476] Starting subtest: basic-with_one_bo_two_files
[189.781844] Subtest basic-with_one_bo_two_files: SUCCESS (0.028s)
[189.929425] [037/132] (830s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[190.039116] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[190.041411] Starting dynamic subtest: pipe-A-eDP-1
[190.063523] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[190.073849] Starting dynamic subtest: pipe-B-eDP-1
[190.094530] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[190.102658] Starting dynamic subtest: pipe-C-eDP-1
[190.123490] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[190.131722] Starting dynamic subtest: pipe-D-eDP-1
[190.153731] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[190.157803] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[192.368946] [038/132] (828s left) i915_drm_fdinfo (all-busy-check-all)
[192.415561] Starting subtest: all-busy-check-all
[192.422672] Subtest all-busy-check-all: SKIP (0.004s)
[192.504568] [039/132] (828s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[192.609739] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[192.612022] Starting dynamic subtest: pipe-A-default-mode
[192.899097] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[192.903810] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.285s)
[193.808458] [040/132] (826s left) kms_addfb_basic (bad-pitch-0)
[193.872798] Starting subtest: bad-pitch-0
[193.875046] Subtest bad-pitch-0: SUCCESS (0.000s)
[194.048826] [041/132] (826s left) gem_ctx_persistence (heartbeat-stop)
[194.108419] Starting subtest: heartbeat-stop
[194.113033] Subtest heartbeat-stop: SKIP (0.000s)
[194.232262] [042/132] (826s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[194.329842] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[194.333982] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[194.460564] [043/132] (826s left) gem_exec_schedule (smoketest)
[194.653208] Starting subtest: smoketest
[194.655559] Starting dynamic subtest: rcs0
[200.197119] Dynamic subtest rcs0: SUCCESS (5.492s)
[200.208253] Starting dynamic subtest: bcs0
[205.703301] Dynamic subtest bcs0: SUCCESS (5.508s)
[205.715643] Starting dynamic subtest: vcs0
[211.200312] Dynamic subtest vcs0: SUCCESS (5.488s)
[211.220216] Starting dynamic subtest: vcs1
[216.700243] Dynamic subtest vcs1: SUCCESS (5.501s)
[216.715460] Starting dynamic subtest: vecs0
[222.193676] Dynamic subtest vecs0: SUCCESS (5.493s)
[222.204278] Starting dynamic subtest: ccs0
[227.704620] Dynamic subtest ccs0: SUCCESS (5.504s)
[227.706732] Subtest smoketest: SUCCESS (32.992s)
[227.871624] [044/132] (793s left) gem_exec_parallel (contexts)
[227.946925] Starting subtest: contexts
[227.949263] Starting dynamic subtest: rcs0
[231.620042] Dynamic subtest rcs0: SUCCESS (3.670s)
[231.632056] Starting dynamic subtest: bcs0
[235.052485] Dynamic subtest bcs0: SUCCESS (3.431s)
[235.061649] Starting dynamic subtest: vcs0
[239.315885] Dynamic subtest vcs0: SUCCESS (4.263s)
[239.326862] Starting dynamic subtest: vcs1
[243.374531] Dynamic subtest vcs1: SUCCESS (4.058s)
[243.386603] Starting dynamic subtest: vecs0
[247.419233] Dynamic subtest vecs0: SUCCESS (4.043s)
[247.429391] Starting dynamic subtest: ccs0
[250.932999] Dynamic subtest ccs0: SUCCESS (3.512s)
[250.935264] Subtest contexts: SUCCESS (22.988s)
[251.144811] [045/132] (769s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[251.212272] Starting subtest: invalid-bsd2-flag-on-blt
[251.214564] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[251.337284] [046/132] (769s left) kms_psr (fbc-psr2-primary-render)
[251.437115] Starting subtest: fbc-psr2-primary-render
[251.439324] Starting dynamic subtest: eDP-1
[251.443451] Dynamic subtest eDP-1: SKIP (0.000s)
[251.447605] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[251.533740] [047/132] (769s left) prime_vgem (coherency-gtt)
[251.619973] Starting subtest: coherency-gtt
[251.624540] Subtest coherency-gtt: SKIP (0.000s)
[251.750145] [048/132] (769s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[251.875771] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[251.880354] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[252.042036] [049/132] (769s left) kms_flip (flip-vs-expired-vblank)
[252.139727] Starting subtest: flip-vs-expired-vblank
[252.141695] Starting dynamic subtest: A-eDP1
[260.784955] Dynamic subtest A-eDP1: SUCCESS (8.643s)
[260.795782] Starting dynamic subtest: B-eDP1
[269.417603] Dynamic subtest B-eDP1: SUCCESS (8.631s)
[269.430221] Starting dynamic subtest: C-eDP1
[278.037753] Dynamic subtest C-eDP1: SUCCESS (8.617s)
[278.052066] Starting dynamic subtest: D-eDP1
[286.680142] Dynamic subtest D-eDP1: SUCCESS (8.639s)
[286.682603] Subtest flip-vs-expired-vblank: SUCCESS (34.540s)
[287.970246] [050/132] (733s left) gem_mmap_wc (write-read)
[288.033946] Subtest write-read: SKIP (0.000s)
[288.092651] [051/132] (733s left) kms_psr (psr-sprite-render)
[288.196967] Starting subtest: psr-sprite-render
[288.199178] Starting dynamic subtest: eDP-1
[289.965839] Dynamic subtest eDP-1: SUCCESS (1.768s)
[289.968091] Subtest psr-sprite-render: SUCCESS (1.769s)
[290.825559] [052/132] (730s left) kms_psr (psr2-cursor-blt)
[290.922362] Starting subtest: psr2-cursor-blt
[290.924638] Starting dynamic subtest: eDP-1
[292.605745] Dynamic subtest eDP-1: SUCCESS (1.684s)
[292.608156] Subtest psr2-cursor-blt: SUCCESS (1.685s)
[293.429055] [053/132] (727s left) kms_prime (basic-modeset-hybrid)
[293.508672] Subtest basic-modeset-hybrid: SKIP (0.000s)
[293.623830] [054/132] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[293.722769] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[293.726914] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[293.852683] [055/132] (727s left) kms_psr (fbc-psr-sprite-plane-onoff)
[293.953460] Starting subtest: fbc-psr-sprite-plane-onoff
[293.955795] Starting dynamic subtest: eDP-1
[293.960308] Dynamic subtest eDP-1: SKIP (0.000s)
[293.964635] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[294.053715] [056/132] (727s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[294.119006] Starting subtest: stress-mm-invalidate-close-overlap
[296.268680] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[296.421536] [057/132] (725s left) gem_exec_params (batch-first)
[296.490579] Starting subtest: batch-first
[296.502210] Subtest batch-first: SUCCESS (0.012s)
[296.650941] [058/132] (724s left) i915_pm_rpm (gem-mmap-type)
[298.176243] Starting subtest: gem-mmap-type
[298.178523] Starting dynamic subtest: wb-smem0
[302.323338] Dynamic subtest wb-smem0: SUCCESS (4.147s)
[302.332718] Starting dynamic subtest: wc-smem0
[305.480230] Dynamic subtest wc-smem0: SUCCESS (3.156s)
[305.489107] Starting dynamic subtest: uc-smem0
[308.628064] Dynamic subtest uc-smem0: SUCCESS (3.146s)
[308.630436] Subtest gem-mmap-type: SUCCESS (10.454s)
[308.743002] [059/132] (712s left) dumb_buffer (create-clear)
[308.800273] Starting subtest: create-clear
[343.342311] Subtest create-clear: SUCCESS (34.530s)
[343.488445] [060/132] (678s left) i915_module_load (resize-bar)
[343.525182] Starting subtest: resize-bar
[343.531846] Subtest resize-bar: SKIP (0.006s)
[343.577799] [061/132] (678s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[343.640234] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[343.644773] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[343.851678] [062/132] (677s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[343.983220] Starting subtest: basic-busy-flip-before-cursor-varying-size
[343.987825] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[344.150797] [063/132] (677s left) gem_eio (kms)
[344.419277] Starting subtest: kms
[365.281462] Subtest kms: SUCCESS (20.860s)
[366.481579] [064/132] (655s left) gem_exec_fence (keep-in-fence)
[366.674600] Starting subtest: keep-in-fence
[366.677078] Starting dynamic subtest: rcs0
[366.741779] Dynamic subtest rcs0: SUCCESS (0.067s)
[366.753357] Starting dynamic subtest: bcs0
[366.796383] Dynamic subtest bcs0: SUCCESS (0.053s)
[366.807752] Starting dynamic subtest: vcs0
[367.871661] Dynamic subtest vcs0: SUCCESS (1.072s)
[367.883949] Starting dynamic subtest: vcs1
[369.864245] Dynamic subtest vcs1: SUCCESS (1.982s)
[369.876038] Starting dynamic subtest: vecs0
[371.840071] Dynamic subtest vecs0: SUCCESS (1.982s)
[371.852255] Starting dynamic subtest: ccs0
[372.074498] Dynamic subtest ccs0: SUCCESS (0.233s)
[372.077126] Subtest keep-in-fence: SUCCESS (5.403s)
[374.020560] [065/132] (647s left) kms_big_fb (linear-8bpp-rotate-270)
[374.128704] Starting subtest: linear-8bpp-rotate-270
[374.152868] Subtest linear-8bpp-rotate-270: SKIP (0.024s)
[374.301714] [066/132] (647s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[374.398616] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[381.438623] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.043s)
[381.575564] [067/132] (640s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[381.681800] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[381.686474] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[382.819421] [068/132] (639s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[382.925646] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[382.929621] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[383.069507] [069/132] (638s left) i915_query (query-garbage)
[383.128550] Starting subtest: query-garbage
[383.130780] Subtest query-garbage: SUCCESS (0.000s)
[383.249349] [070/132] (638s left) gem_huc_copy (huc-copy)
[383.302795] Starting subtest: huc-copy
[383.307796] Subtest huc-copy: SUCCESS (0.003s)
[383.471111] [071/132] (638s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[383.561012] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[387.353169] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.797s)
[388.528553] [072/132] (633s left) gem_create (create-invalid-size)
[388.590826] Starting subtest: create-invalid-size
[388.593005] Subtest create-invalid-size: SUCCESS (0.000s)
[388.799877] [073/132] (633s left) kms_pm_rpm (drm-resources-equal)
[391.021101] Starting subtest: drm-resources-equal
[396.064524] Subtest drm-resources-equal: SUCCESS (5.048s)
[396.180463] [074/132] (625s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[396.278594] Starting subtest: random-ccs-data-yf-tiled-ccs
[396.280949] Starting dynamic subtest: pipe-A-eDP-1
[396.306659] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[396.318585] Starting dynamic subtest: pipe-B-eDP-1
[396.342420] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[396.351453] Starting dynamic subtest: pipe-C-eDP-1
[396.375511] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[396.384862] Starting dynamic subtest: pipe-D-eDP-1
[396.409381] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[396.413441] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[398.574753] [075/132] (623s left) kms_lease (empty-lease)
[398.672359] Starting subtest: empty-lease
[398.674580] Starting dynamic subtest: pipe-A-eDP-1
[398.676931] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[398.686592] Starting dynamic subtest: pipe-B-eDP-1
[398.688478] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[398.697288] Starting dynamic subtest: pipe-C-eDP-1
[398.699139] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[398.707613] Starting dynamic subtest: pipe-D-eDP-1
[398.709511] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[398.711399] Subtest empty-lease: SUCCESS (0.009s)
[398.805905] [076/132] (623s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[398.907797] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[398.912226] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[399.034085] [077/132] (623s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[399.093886] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[399.096224] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[399.222792] [078/132] (623s left) api_intel_allocator (gem-pool)
[399.286551] Starting subtest: gem-pool
[399.292223] Subtest gem-pool: SUCCESS (0.007s)
[399.415958] [079/132] (622s left) core_getclient (basic)
[399.468993] Starting subtest: basic
[399.471137] Subtest basic: SUCCESS (0.000s)
[399.600794] [080/132] (622s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[399.683728] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[399.685606] Starting dynamic subtest: pipe-A
[400.034174] Dynamic subtest pipe-A: SUCCESS (0.352s)
[400.051961] Starting dynamic subtest: pipe-B
[401.477706] Dynamic subtest pipe-B: SUCCESS (1.441s)
[401.486851] Starting dynamic subtest: pipe-C
[402.867132] Dynamic subtest pipe-C: SUCCESS (1.389s)
[402.876658] Starting dynamic subtest: pipe-D
[404.329872] Dynamic subtest pipe-D: SUCCESS (1.462s)
[404.332311] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.649s)
[405.600093] [081/132] (616s left) syncobj_timeline (etime-single-wait-submitted)
[405.661677] Starting subtest: etime-single-wait-submitted
[405.763709] Subtest etime-single-wait-submitted: SUCCESS (0.103s)
[405.866969] [082/132] (616s left) gem_basic (create-close)
[405.929870] Starting subtest: create-close
[405.933932] Subtest create-close: SUCCESS (0.000s)
[406.051559] [083/132] (616s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[406.156844] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[412.864212] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.710s)
[413.041251] [084/132] (609s left) gem_spin_batch (legacy-resubmit)
[413.111628] Starting subtest: legacy-resubmit
[413.114017] Starting dynamic subtest: default
[413.119459] Dynamic subtest default: SUCCESS (0.005s)
[413.130614] Starting dynamic subtest: render
[413.132839] Dynamic subtest render: SUCCESS (0.001s)
[413.141591] Starting dynamic subtest: bsd
[413.143739] Dynamic subtest bsd: SUCCESS (0.003s)
[413.152611] Starting dynamic subtest: bsd1
[413.154763] Dynamic subtest bsd1: SUCCESS (0.002s)
[413.163599] Starting dynamic subtest: bsd2
[413.165758] Dynamic subtest bsd2: SUCCESS (0.001s)
[413.174486] Starting dynamic subtest: blt
[413.176644] Dynamic subtest blt: SUCCESS (0.002s)
[413.185563] Starting dynamic subtest: vebox
[413.187758] Dynamic subtest vebox: SUCCESS (0.002s)
[413.189931] Subtest legacy-resubmit: SUCCESS (0.022s)
[413.389616] [085/132] (609s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[413.491027] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[413.493283] Starting dynamic subtest: pipe-A-eDP-1
[413.513235] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[413.524338] Starting dynamic subtest: pipe-B-eDP-1
[413.543338] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[413.552418] Starting dynamic subtest: pipe-C-eDP-1
[413.572022] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[413.581087] Starting dynamic subtest: pipe-D-eDP-1
[413.600445] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[413.602614] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[415.777832] [086/132] (606s left) perf_pmu (semaphore-wait-idle)
[415.820139] Starting subtest: semaphore-wait-idle
[415.822392] Starting dynamic subtest: rcs0
[416.333263] Dynamic subtest rcs0: SUCCESS (0.513s)
[416.345680] Starting dynamic subtest: bcs0
[416.848669] Dynamic subtest bcs0: SUCCESS (0.512s)
[416.860951] Starting dynamic subtest: vcs0
[417.361565] Dynamic subtest vcs0: SUCCESS (0.510s)
[417.374020] Starting dynamic subtest: vcs1
[417.873892] Dynamic subtest vcs1: SUCCESS (0.510s)
[417.886049] Starting dynamic subtest: vecs0
[418.385472] Dynamic subtest vecs0: SUCCESS (0.510s)
[418.398070] Starting dynamic subtest: ccs0
[418.900681] Dynamic subtest ccs0: SUCCESS (0.512s)
[418.903272] Subtest semaphore-wait-idle: SUCCESS (3.083s)
[419.065916] [087/132] (603s left) gem_wait (write-wait)
[419.129805] Starting subtest: write-wait
[419.132281] Starting dynamic subtest: all-engines
[420.256119] Dynamic subtest all-engines: SUCCESS (1.125s)
[420.273612] Starting dynamic subtest: rcs0
[421.313845] Dynamic subtest rcs0: SUCCESS (1.055s)
[421.330716] Starting dynamic subtest: bcs0
[422.372522] Dynamic subtest bcs0: SUCCESS (1.056s)
[422.389096] Starting dynamic subtest: vcs0
[423.425947] Dynamic subtest vcs0: SUCCESS (1.051s)
[423.442701] Starting dynamic subtest: vcs1
[424.479273] Dynamic subtest vcs1: SUCCESS (1.051s)
[424.495772] Starting dynamic subtest: vecs0
[425.532232] Dynamic subtest vecs0: SUCCESS (1.050s)
[425.548497] Starting dynamic subtest: ccs0
[426.589886] Dynamic subtest ccs0: SUCCESS (1.056s)
[426.592569] Subtest write-wait: SUCCESS (7.460s)
[426.816948] [088/132] (595s left) kms_atomic (plane-primary-legacy)
[426.914042] Starting subtest: plane-primary-legacy
[426.916650] Starting dynamic subtest: pipe-A-eDP-1
[427.366497] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.452s)
[427.368796] Subtest plane-primary-legacy: SUCCESS (0.454s)
[428.205219] [089/132] (594s left) sysfs_preempt_timeout (invalid)
[428.276375] Starting subtest: invalid
[428.278639] Starting dynamic subtest: vecs0
[428.280984] Dynamic subtest vecs0: SUCCESS (0.000s)
[428.291254] Starting dynamic subtest: ccs0
[428.293149] Dynamic subtest ccs0: SUCCESS (0.000s)
[428.301437] Starting dynamic subtest: vcs0
[428.303276] Dynamic subtest vcs0: SUCCESS (0.000s)
[428.311551] Starting dynamic subtest: bcs0
[428.313484] Dynamic subtest bcs0: SUCCESS (0.000s)
[428.321815] Starting dynamic subtest: rcs0
[428.323729] Dynamic subtest rcs0: SUCCESS (0.000s)
[428.331975] Starting dynamic subtest: vcs1
[428.333887] Dynamic subtest vcs1: SUCCESS (0.000s)
[428.336248] Subtest invalid: SUCCESS (0.005s)
[428.419154] [090/132] (594s left) gem_exec_fence (syncobj-timeline-signal)
[428.942738] Starting subtest: syncobj-timeline-signal
[428.948585] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[430.011850] [091/132] (592s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[430.219365] Starting subtest: cursor-rapid-movement-64x21
[430.266539] Subtest cursor-rapid-movement-64x21: SKIP (0.041s)
[430.543652] [092/132] (592s left) perf (oa-formats)
[430.678491] Starting subtest: oa-formats
[430.680664] Starting dynamic subtest: 0-rcs0
[430.691690] Dynamic subtest 0-rcs0: SUCCESS (0.014s)
[430.701838] Starting dynamic subtest: 1-vcs0
[430.713178] Dynamic subtest 1-vcs0: SUCCESS (0.021s)
[430.715487] Subtest oa-formats: SUCCESS (0.037s)
[430.765046] [093/132] (592s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[430.852348] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[430.854489] Starting dynamic subtest: pipe-A-eDP-1
[430.918986] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.068s)
[430.928362] Starting dynamic subtest: pipe-B-eDP-1
[430.985071] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.066s)
[430.993914] Starting dynamic subtest: pipe-C-eDP-1
[431.048086] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.062s)
[431.056634] Starting dynamic subtest: pipe-D-eDP-1
[431.113345] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.064s)
[431.115250] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.264s)
[433.386316] [094/132] (589s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[433.450862] Starting subtest: invalid-bsd1-flag-on-blt
[433.453258] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[433.579604] [095/132] (589s left) gem_ctx_persistence (engines-cleanup)
[433.650727] Starting subtest: engines-cleanup
[433.653228] Starting dynamic subtest: rcs0
[433.762208] Dynamic subtest rcs0: SUCCESS (0.111s)
[433.774338] Starting dynamic subtest: bcs0
[433.833408] Dynamic subtest bcs0: SUCCESS (0.069s)
[433.844760] Starting dynamic subtest: vcs0
[433.907124] Dynamic subtest vcs0: SUCCESS (0.072s)
[433.918749] Starting dynamic subtest: vcs1
[433.936567] Dynamic subtest vcs1: SUCCESS (0.028s)
[433.945489] Starting dynamic subtest: vecs0
[434.044428] Dynamic subtest vecs0: SUCCESS (0.106s)
[434.056266] Starting dynamic subtest: ccs0
[434.110453] Dynamic subtest ccs0: SUCCESS (0.064s)
[434.113462] Subtest engines-cleanup: SUCCESS (0.461s)
[434.217378] [096/132] (588s left) i915_suspend (debugfs-reader)
[434.291103] Starting subtest: debugfs-reader
[452.294168] Subtest debugfs-reader: SUCCESS (3.317s)
[452.425243] [097/132] (570s left) kms_draw_crc (draw-method-mmap-wc)
[452.523392] Starting subtest: draw-method-mmap-wc
[452.525663] Starting dynamic subtest: xrgb8888-untiled
[452.744180] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[452.753928] Starting dynamic subtest: rgb565-untiled
[452.959300] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[452.969059] Starting dynamic subtest: xrgb2101010-untiled
[453.169556] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.208s)
[453.179595] Starting dynamic subtest: xbgr16161616f-untiled
[453.381703] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[453.391197] Starting dynamic subtest: xrgb8888-xtiled
[453.515934] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[453.525183] Starting dynamic subtest: rgb565-xtiled
[453.650051] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[453.659600] Starting dynamic subtest: xrgb2101010-xtiled
[453.784150] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[453.793812] Starting dynamic subtest: xbgr16161616f-xtiled
[453.918390] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[453.928303] Starting dynamic subtest: xrgb8888-4tiled
[454.075080] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.154s)
[454.096344] Starting dynamic subtest: rgb565-4tiled
[454.219963] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[454.234007] Starting dynamic subtest: xrgb2101010-4tiled
[454.365165] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[454.374696] Starting dynamic subtest: xbgr16161616f-4tiled
[454.543892] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[454.546275] Subtest draw-method-mmap-wc: SUCCESS (2.012s)
[454.673661] [098/132] (568s left) gem_userptr_blits (sync-unmap)
[454.734242] Starting subtest: sync-unmap
[454.749809] Subtest sync-unmap: SUCCESS (0.014s)
[454.905656] [099/132] (568s left) kms_content_protection (dp-mst-suspend-resume)
[455.067239] Starting subtest: dp-mst-suspend-resume
[455.072137] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[455.236041] [100/132] (568s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[455.344151] Starting subtest: addfb25-y-tiled-small-legacy
[455.348563] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[455.479110] [101/132] (567s left) syncobj_timeline (wait-for-submit-snapshot)
[455.543200] Starting subtest: wait-for-submit-snapshot
[455.624154] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[455.721768] [102/132] (567s left) gem_eio (in-flight-internal-1us)
[455.941007] Starting subtest: in-flight-internal-1us
[457.623976] Subtest in-flight-internal-1us: SUCCESS (1.675s)
[457.808204] [103/132] (565s left) gem_create (create-size-update)
[457.881173] Starting subtest: create-size-update
[457.883882] Subtest create-size-update: SUCCESS (0.000s)
[458.140387] [104/132] (565s left) kms_draw_crc (fill-fb)
[458.244874] Starting subtest: fill-fb
[458.686618] Subtest fill-fb: SUCCESS (0.442s)
[458.805661] [105/132] (564s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[458.909831] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[458.912182] Starting dynamic subtest: pipe-A-eDP-1
[459.396942] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.463s)
[459.407053] Starting dynamic subtest: pipe-B-eDP-1
[460.797846] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.385s)
[460.808884] Starting dynamic subtest: pipe-C-eDP-1
[462.208573] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.410s)
[462.219180] Starting dynamic subtest: pipe-D-eDP-1
[463.520252] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.303s)
[463.522779] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.567s)
[464.730755] [106/132] (558s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[464.853005] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[464.858179] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[464.996777] [107/132] (558s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[465.111216] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[465.115899] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[465.233602] [108/132] (558s left) gem_mmap_offset (bad-flags)
[465.297031] Starting subtest: bad-flags
[465.299414] Subtest bad-flags: SUCCESS (0.000s)
[465.417681] [109/132] (558s left) gem_mmap_gtt (big-copy)
[465.482376] Subtest big-copy: SKIP (0.000s)
[465.550949] [110/132] (558s left) kms_plane_multiple (tiling-y)
[465.648280] Starting subtest: tiling-y
[465.653019] Subtest tiling-y: SKIP (0.000s)
[465.744869] [111/132] (558s left) kms_plane_scaling (planes-upscale-factor-0-25)
[465.838870] Starting subtest: planes-upscale-factor-0-25
[465.840872] Starting dynamic subtest: pipe-A
[465.899780] Dynamic subtest pipe-A: SUCCESS (0.063s)
[465.910610] Starting dynamic subtest: pipe-B
[467.036649] Dynamic subtest pipe-B: SUCCESS (1.134s)
[467.048666] Starting dynamic subtest: pipe-C
[468.137164] Dynamic subtest pipe-C: SUCCESS (1.098s)
[468.146669] Starting dynamic subtest: pipe-D
[469.270913] Dynamic subtest pipe-D: SUCCESS (1.132s)
[469.273446] Subtest planes-upscale-factor-0-25: SUCCESS (3.430s)
[470.485667] [112/132] (553s left) gem_ctx_param (set-priority-range)
[470.543193] Starting subtest: set-priority-range
[470.558057] Subtest set-priority-range: SUCCESS (0.015s)
[470.691483] [113/132] (553s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[470.788080] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[470.792527] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[470.935751] [114/132] (552s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[471.038539] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[477.689415] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.648s)
[477.818393] [115/132] (546s left) kms_scaling_modes (scaling-mode-center)
[477.915386] Starting subtest: scaling-mode-center
[477.918280] Starting dynamic subtest: pipe-A-eDP-1
[478.008951] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[478.019017] Starting dynamic subtest: pipe-B-eDP-1
[479.223897] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.213s)
[479.235865] Starting dynamic subtest: pipe-C-eDP-1
[480.342682] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[480.352396] Starting dynamic subtest: pipe-D-eDP-1
[481.471985] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[481.474846] Subtest scaling-mode-center: SUCCESS (3.557s)
[482.652679] [116/132] (541s left) gen9_exec_parse (secure-batches)
[482.725314] Subtest secure-batches: SKIP (0.000s)
[482.828949] [117/132] (541s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[482.940282] Starting subtest: 4-tiled-64bpp-rotate-180
[484.454191] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.512s)
[484.645715] [118/132] (539s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[489.706107] Starting subtest: psr2-plane-move-sf-dmg-area
[489.708969] Starting dynamic subtest: pipe-A-eDP-1
[494.991416] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.287s)
[495.002267] Starting dynamic subtest: pipe-B-eDP-1
[500.318393] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.326s)
[500.321137] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.618s)
[501.219657] [119/132] (522s left) kms_vblank (query-forked-busy)
[501.320996] Starting subtest: query-forked-busy
[501.323458] Starting dynamic subtest: pipe-A-eDP-1
[503.196861] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.874s)
[503.213161] Starting dynamic subtest: pipe-D-eDP-1
[505.738874] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s)
[505.741392] Subtest query-forked-busy: SUCCESS (4.418s)
[506.564618] [120/132] (517s left) gem_ccs (block-copy-uncompressed)
[506.625102] Starting subtest: block-copy-uncompressed
[506.627672] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[506.650557] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.024s)
[506.660764] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[506.669122] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[506.678025] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[506.686138] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[506.694893] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[506.704836] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[506.707293] Subtest block-copy-uncompressed: SUCCESS (0.080s)
[506.857076] [121/132] (517s left) syncobj_eventfd (binary-wait-signaled)
[506.920704] Starting subtest: binary-wait-signaled
[506.923307] Subtest binary-wait-signaled: SUCCESS (0.000s)
[507.054749] [122/132] (517s left) kms_draw_crc (draw-method-mmap-gtt)
[507.149128] Starting subtest: draw-method-mmap-gtt
[507.153899] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[507.289147] [123/132] (517s left) gem_exec_schedule (preempt-other)
[507.370436] Starting subtest: preempt-other
[507.372835] Starting dynamic subtest: rcs0
[507.435724] Dynamic subtest rcs0: SUCCESS (0.074s)
[507.447374] Starting dynamic subtest: bcs0
[507.504093] Dynamic subtest bcs0: SUCCESS (0.068s)
[507.516332] Starting dynamic subtest: vcs0
[507.575702] Dynamic subtest vcs0: SUCCESS (0.071s)
[507.586004] Starting dynamic subtest: vcs1
[507.642270] Dynamic subtest vcs1: SUCCESS (0.066s)
[507.655567] Starting dynamic subtest: vecs0
[507.707637] Dynamic subtest vecs0: SUCCESS (0.065s)
[507.718900] Starting dynamic subtest: ccs0
[507.774706] Dynamic subtest ccs0: SUCCESS (0.066s)
[507.777154] Subtest preempt-other: SUCCESS (0.414s)
[508.693799] [124/132] (515s left) kms_plane_lowres (tiling-4)
[508.793321] Starting subtest: tiling-4
[508.796169] Starting dynamic subtest: pipe-A-eDP-1
[508.801236] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[509.101655] Starting dynamic subtest: pipe-B-eDP-1
[509.107189] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[509.118113] Starting dynamic subtest: pipe-C-eDP-1
[509.123170] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[509.133688] Starting dynamic subtest: pipe-D-eDP-1
[509.140106] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[509.146271] Subtest tiling-4: SKIP (0.307s)
[509.911787] [125/132] (514s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[510.022277] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[516.312442] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.292s)
[517.533019] [126/132] (506s left) vgem_basic (setversion)
[517.583120] Starting subtest: setversion
[517.588320] Subtest setversion: SUCCESS (0.000s)
[517.664171] [127/132] (506s left) gem_exec_suspend (basic-s3-devices)
[517.731139] Starting subtest: basic-S3-devices
[517.733408] Starting dynamic subtest: smem
[529.497653] Dynamic subtest smem: SUCCESS (11.764s)
[529.500747] Subtest basic-S3-devices: SUCCESS (11.767s)
[529.707493] [128/132] (494s left) gem_eio (in-flight-suspend)
[529.962893] Starting subtest: in-flight-suspend
[573.524224] Subtest in-flight-suspend: SUCCESS (13.844s)
[573.715660] [129/132] (450s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[573.833856] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[581.320296] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.484s)
[581.479453] [130/132] (443s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[581.585305] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[586.249565] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.667s)
[587.406893] [131/132] (437s left) perf (non-zero-reason)
[587.516381] Starting subtest: non-zero-reason
[587.519467] Starting dynamic subtest: 0-rcs0
[592.927097] Dynamic subtest 0-rcs0: SUCCESS (5.407s)
[592.939588] Starting dynamic subtest: 1-vcs1
[596.830191] Dynamic subtest 1-vcs1: SUCCESS (3.901s)
[596.833295] Subtest non-zero-reason: SUCCESS (9.310s)
[596.882503] [132/132] (427s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[596.939101] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[597.040589] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[597.146452] 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.