[64.365280] Initializing watchdogs
[64.365324]   /dev/watchdog0
[64.379826] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[64.383969] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[64.386053] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.389404] [001/133] (960s left) gem_softpin (noreloc-s3)
[64.479751] Starting subtest: noreloc-S3
[84.444313] Subtest noreloc-S3: SUCCESS (5.408s)
[84.582937] [002/133] (940s left) perf_pmu (gt-awake)
[84.637913] Starting subtest: gt-awake
[93.424494] Subtest gt-awake: SUCCESS (8.784s)
[93.527431] [003/133] (931s left) kms_invalid_mode (zero-hdisplay)
[93.608954] Starting subtest: zero-hdisplay
[93.611169] Starting dynamic subtest: pipe-A-eDP-1
[93.899535] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[93.909058] Starting dynamic subtest: pipe-B-eDP-1
[93.911183] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[93.919015] Starting dynamic subtest: pipe-C-eDP-1
[93.921205] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[93.928833] Starting dynamic subtest: pipe-D-eDP-1
[93.930661] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[93.932516] Subtest zero-hdisplay: SUCCESS (0.296s)
[94.715436] [004/133] (930s left) kms_prop_blob (invalid-set-prop-any)
[94.776703] Starting subtest: invalid-set-prop-any
[94.778757] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[94.878601] [005/133] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[94.962874] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[102.401946] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.442s)
[102.536173] [006/133] (922s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[102.627114] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[102.631638] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[102.707662] [007/133] (922s left) kms_cursor_legacy (torture-move)
[102.818551] Starting subtest: torture-move
[102.820934] Starting dynamic subtest: pipe-A
[109.083144] Dynamic subtest pipe-A: SUCCESS (6.260s)
[109.095749] Starting dynamic subtest: pipe-B
[115.680882] Dynamic subtest pipe-B: SUCCESS (6.597s)
[115.693223] Starting dynamic subtest: pipe-C
[121.964765] Dynamic subtest pipe-C: SUCCESS (6.286s)
[121.973488] Starting dynamic subtest: pipe-D
[128.405390] Dynamic subtest pipe-D: SUCCESS (6.441s)
[128.424713] Starting dynamic subtest: all-pipes
[134.788392] Dynamic subtest all-pipes: SUCCESS (6.380s)
[134.790908] Subtest torture-move: SUCCESS (31.968s)
[134.939516] [008/133] (890s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[135.016574] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[138.614438] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.602s)
[139.818404] [009/133] (885s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[139.905837] Starting subtest: fbc-rgb101010-draw-blt
[144.539824] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.639s)
[145.731415] [010/133] (879s left) gem_pxp (create-regular-context-2)
[145.863852] Starting subtest: create-regular-context-2
[145.866517] Subtest create-regular-context-2: SUCCESS (0.002s)
[145.986167] [011/133] (879s left) kms_flip (2x-flip-vs-panning-interruptible)
[146.064410] Starting subtest: 2x-flip-vs-panning-interruptible
[146.072925] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.008s)
[146.148583] [012/133] (879s left) kms_plane_alpha_blend (constant-alpha-max)
[146.227576] Starting subtest: constant-alpha-max
[146.516411] Starting dynamic subtest: pipe-A-eDP-1
[147.492507] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.976s)
[147.501933] Starting dynamic subtest: pipe-D-eDP-1
[149.129073] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.635s)
[149.131331] Subtest constant-alpha-max: SUCCESS (2.903s)
[149.970301] [013/133] (875s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[150.044382] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[150.071539] Starting dynamic subtest: pipe-A-plane-0
[150.360130] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[150.369853] Starting dynamic subtest: pipe-A-plane-3
[150.593682] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[150.602707] Starting dynamic subtest: pipe-A-plane-5
[150.607146] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[151.652762] Starting dynamic subtest: pipe-B-plane-0
[151.945034] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[151.953923] Starting dynamic subtest: pipe-B-plane-3
[152.178254] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[152.188136] Starting dynamic subtest: pipe-B-plane-5
[152.192697] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[153.249732] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (3.203s)
[153.637391] [014/133] (871s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[153.689984] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[153.748210] [015/133] (871s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[153.796545] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[153.855478] [016/133] (871s left) gem_vm_create (invalid-destroy)
[153.910047] Starting subtest: invalid-destroy
[153.912363] Subtest invalid-destroy: SUCCESS (0.002s)
[154.013033] [017/133] (871s left) gem_lmem_swapping (heavy-verify-random-ccs)
[154.055807] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[154.093168] [018/133] (871s left) kms_feature_discovery (display-2x)
[154.166642] Starting subtest: display-2x
[154.171172] Subtest display-2x: SKIP (0.000s)
[154.237002] [019/133] (871s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[154.312728] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[154.315085] Starting dynamic subtest: pipe-A-eDP-1
[154.337718] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[154.348571] Starting dynamic subtest: pipe-B-eDP-1
[154.369955] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[154.378102] Starting dynamic subtest: pipe-C-eDP-1
[154.398997] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[154.406825] Starting dynamic subtest: pipe-D-eDP-1
[154.428079] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[154.432371] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[156.579004] [020/133] (869s left) gem_mmap_offset (blt-coherency)
[156.633747] Starting subtest: blt-coherency
[156.638569] Subtest blt-coherency: SUCCESS (0.006s)
[156.765992] [021/133] (868s left) gem_pxp (hw-rejects-pxp-context)
[156.828420] Starting subtest: hw-rejects-pxp-context
[156.833218] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[156.924341] [022/133] (868s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[156.992820] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[163.529951] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.540s)
[163.643089] [023/133] (862s left) kms_psr (pr-sprite-mmap-gtt)
[163.724788] Starting subtest: pr-sprite-mmap-gtt
[163.729274] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[163.795716] [024/133] (862s left) gem_exec_reloc (basic-write-gtt-noreloc)
[163.851789] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[163.919353] [025/133] (861s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[163.989206] Starting subtest: plane-lut1d-ctm3x4
[164.113518] Starting dynamic subtest: pipe-A-plane-0
[164.270481] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[164.281119] Starting dynamic subtest: pipe-A-plane-1
[164.402093] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[164.411478] Starting dynamic subtest: pipe-A-plane-2
[164.545973] Dynamic subtest pipe-A-plane-2: SUCCESS (0.143s)
[165.707027] Starting dynamic subtest: pipe-B-plane-0
[165.889474] Dynamic subtest pipe-B-plane-0: SUCCESS (0.189s)
[165.899366] Starting dynamic subtest: pipe-B-plane-1
[166.044476] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[166.053747] Starting dynamic subtest: pipe-B-plane-2
[166.177751] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[167.326032] Starting dynamic subtest: pipe-C-plane-0
[167.452211] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[167.461767] Starting dynamic subtest: pipe-C-plane-1
[167.596408] Dynamic subtest pipe-C-plane-1: SUCCESS (0.143s)
[167.605907] Starting dynamic subtest: pipe-C-plane-2
[167.729805] Dynamic subtest pipe-C-plane-2: SUCCESS (0.133s)
[168.958277] Starting dynamic subtest: pipe-D-plane-0
[169.094916] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[169.103871] Starting dynamic subtest: pipe-D-plane-1
[169.228367] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[169.237268] Starting dynamic subtest: pipe-D-plane-2
[169.361712] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[169.648824] Subtest plane-lut1d-ctm3x4: SUCCESS (5.659s)
[170.560003] [026/133] (855s left) gem_exec_schedule (preempt-hang)
[170.749841] Starting subtest: preempt-hang
[170.752815] Starting dynamic subtest: rcs0
[171.001339] Dynamic subtest rcs0: SUCCESS (0.249s)
[171.012219] Starting dynamic subtest: bcs0
[171.239486] Dynamic subtest bcs0: SUCCESS (0.237s)
[171.248164] Starting dynamic subtest: vcs0
[171.463029] Dynamic subtest vcs0: SUCCESS (0.222s)
[171.471533] Starting dynamic subtest: vcs1
[171.685680] Dynamic subtest vcs1: SUCCESS (0.222s)
[171.695567] Starting dynamic subtest: vecs0
[171.920723] Dynamic subtest vecs0: SUCCESS (0.235s)
[171.929146] Starting dynamic subtest: ccs0
[172.150638] Dynamic subtest ccs0: SUCCESS (0.229s)
[172.152976] Subtest preempt-hang: SUCCESS (1.401s)
[172.486998] [027/133] (853s left) kms_psr (psr-cursor-mmap-cpu)
[172.569541] Starting subtest: psr-cursor-mmap-cpu
[172.571971] Starting dynamic subtest: eDP-1
[174.327266] Dynamic subtest eDP-1: SUCCESS (1.756s)
[174.329396] Subtest psr-cursor-mmap-cpu: SUCCESS (1.757s)
[175.206978] [028/133] (850s left) gem_exec_whisper (basic-contexts-priority-all)
[175.266039] Starting subtest: basic-contexts-priority-all
[198.302516] Subtest basic-contexts-priority-all: SUCCESS (23.038s)
[198.483902] [029/133] (827s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[198.537139] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[198.605341] [030/133] (827s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[198.704139] Starting subtest: cursorB-vs-flipA-atomic-transitions
[198.708663] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[198.857854] [031/133] (827s left) gem_exec_params (rel-constants-invalid)
[198.917524] Starting subtest: rel-constants-invalid
[198.919886] Subtest rel-constants-invalid: SUCCESS (0.000s)
[199.028806] [032/133] (827s left) kms_joiner (invalid-modeset-ultra-joiner)
[199.100186] Starting subtest: invalid-modeset-ultra-joiner
[199.104301] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[199.164019] [033/133] (827s left) syncobj_wait (invalid-signal-bad-pad)
[199.222089] Starting subtest: invalid-signal-bad-pad
[199.223988] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[199.335223] [034/133] (826s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[199.401136] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[199.410550] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.008s)
[199.499796] [035/133] (826s left) gem_eio (in-flight-contexts-1us)
[199.716221] Starting subtest: in-flight-contexts-1us
[211.390112] Subtest in-flight-contexts-1us: SUCCESS (11.677s)
[211.548856] [036/133] (814s left) kms_pm_lpsp (kms-lpsp)
[212.623198] Starting subtest: kms-lpsp
[212.625563] Starting dynamic subtest: pipe-A-eDP-1
[212.793244] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[213.103952] Subtest kms-lpsp: SUCCESS (0.484s)
[213.907966] [037/133] (812s left) i915_drm_fdinfo (busy-check-all)
[213.955347] Starting subtest: busy-check-all
[213.957668] Starting dynamic subtest: rcs0
[213.964629] Dynamic subtest rcs0: SKIP (0.004s)
[213.973881] Starting dynamic subtest: bcs0
[213.978052] Dynamic subtest bcs0: SKIP (0.000s)
[213.986609] Starting dynamic subtest: vcs0
[213.990728] Dynamic subtest vcs0: SKIP (0.000s)
[214.000488] Starting dynamic subtest: vcs1
[214.004780] Dynamic subtest vcs1: SKIP (0.000s)
[214.013811] Starting dynamic subtest: vecs0
[214.018130] Dynamic subtest vecs0: SKIP (0.000s)
[214.027323] Starting dynamic subtest: ccs0
[214.031844] Dynamic subtest ccs0: SKIP (0.000s)
[214.036261] Subtest busy-check-all: SKIP (0.009s)
[214.071216] [038/133] (812s left) drm_read (fault-buffer)
[214.218102] Starting subtest: fault-buffer
[214.220516] Subtest fault-buffer: SUCCESS (0.000s)
[214.339128] [039/133] (812s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[214.418164] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[215.374719] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.957s)
[215.499609] [040/133] (810s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[215.579885] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[215.582234] Starting dynamic subtest: pipe-A-eDP-1
[216.369779] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.784s)
[216.379962] Starting dynamic subtest: pipe-B-eDP-1
[217.927117] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.553s)
[217.937700] Starting dynamic subtest: pipe-C-eDP-1
[219.423851] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.498s)
[219.433595] Starting dynamic subtest: pipe-D-eDP-1
[220.938514] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.511s)
[220.940379] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.351s)
[222.110003] [041/133] (804s left) gem_ctx_shared (create-shared-gtt)
[222.183665] Starting subtest: create-shared-gtt
[224.335566] Subtest create-shared-gtt: SUCCESS (2.150s)
[224.663085] [042/133] (801s left) gem_sync (basic-store-each)
[224.735848] Starting subtest: basic-store-each
[227.423466] Subtest basic-store-each: SUCCESS (2.697s)
[227.611357] [043/133] (798s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[227.688147] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[227.690465] Starting dynamic subtest: A-eDP1
[236.556630] Dynamic subtest A-eDP1: SUCCESS (8.868s)
[236.570004] Starting dynamic subtest: B-eDP1
[245.492959] Dynamic subtest B-eDP1: SUCCESS (8.936s)
[245.504374] Starting dynamic subtest: C-eDP1
[254.414807] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[254.428338] Starting dynamic subtest: D-eDP1
[263.339099] Dynamic subtest D-eDP1: SUCCESS (8.924s)
[263.344695] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.658s)
[264.570143] [044/133] (762s left) i915_pm_freq_api (freq-basic-api)
[264.635855] Starting subtest: freq-basic-api
[264.638174] Starting dynamic subtest: gt0
[264.640372] Dynamic subtest gt0: SUCCESS (0.003s)
[264.650889] Starting dynamic subtest: gt1
[264.653070] Dynamic subtest gt1: SUCCESS (0.002s)
[264.655285] Subtest freq-basic-api: SUCCESS (0.007s)
[264.716349] [045/133] (761s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[264.811552] Starting subtest: plane-use-after-nonblocking-unbind
[264.813855] Starting dynamic subtest: pipe-A-eDP-1
[264.880325] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.069s)
[265.177236] Starting dynamic subtest: pipe-B-eDP-1
[265.961685] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.795s)
[266.253704] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.440s)
[267.164103] [046/133] (759s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[267.257873] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[267.262098] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[267.413176] [047/133] (759s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[267.503089] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[267.507388] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[267.620613] [048/133] (759s left) kms_getfb (getfb2-handle-closed)
[267.703392] Starting subtest: getfb2-handle-closed
[267.705678] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[267.810361] [049/133] (759s left) kms_psr (fbc-pr-no-drrs)
[267.875457] Starting subtest: fbc-pr-no-drrs
[267.879523] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[267.974370] [050/133] (758s left) drm_read (short-buffer-nonblock)
[268.102637] Starting subtest: short-buffer-nonblock
[268.104583] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[268.215161] [051/133] (758s left) gen9_exec_parse (bb-secure)
[268.267891] Subtest bb-secure: SKIP (0.000s)
[268.327790] [052/133] (758s left) kms_psr (fbc-pr-sprite-render)
[268.401670] Starting subtest: fbc-pr-sprite-render
[268.405052] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[268.472320] [053/133] (758s left) kms_big_fb (linear-64bpp-rotate-0)
[268.554391] Starting subtest: linear-64bpp-rotate-0
[270.054813] Subtest linear-64bpp-rotate-0: SUCCESS (1.500s)
[270.231148] [054/133] (756s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[270.298692] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[270.302519] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[270.374314] [055/133] (756s left) gem_exec_balancer (full)
[270.431563] Starting subtest: full
[275.275652] Subtest full: SUCCESS (4.844s)
[275.576612] [056/133] (751s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[275.654880] Starting subtest: plane-upscale-20x20-with-pixel-format
[275.657061] Starting dynamic subtest: pipe-A
[276.703067] Dynamic subtest pipe-A: SUCCESS (1.050s)
[276.712683] Starting dynamic subtest: pipe-B
[278.835705] Dynamic subtest pipe-B: SUCCESS (2.132s)
[278.845155] Starting dynamic subtest: pipe-C
[280.022364] Dynamic subtest pipe-C: SUCCESS (1.185s)
[280.037419] Starting dynamic subtest: pipe-D
[281.256654] Dynamic subtest pipe-D: SUCCESS (1.232s)
[281.259157] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.604s)
[282.447185] [057/133] (744s left) kms_flip (modeset-vs-vblank-race-interruptible)
[282.524060] Starting subtest: modeset-vs-vblank-race-interruptible
[282.526480] Starting dynamic subtest: A-eDP1
[287.197697] Dynamic subtest A-eDP1: SUCCESS (4.673s)
[287.210963] Starting dynamic subtest: B-eDP1
[291.837485] Dynamic subtest B-eDP1: SUCCESS (4.637s)
[291.852513] Starting dynamic subtest: C-eDP1
[296.434154] Dynamic subtest C-eDP1: SUCCESS (4.594s)
[296.445309] Starting dynamic subtest: D-eDP1
[300.965529] Dynamic subtest D-eDP1: SUCCESS (4.530s)
[300.970633] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.448s)
[302.135151] [058/133] (725s left) i915_pm_rps (engine-order)
[302.203849] Starting subtest: engine-order
[308.292890] Subtest engine-order: SUCCESS (6.088s)
[308.401651] [059/133] (718s left) kms_plane_cursor (overlay)
[308.491060] Starting subtest: overlay
[308.495343] Starting dynamic subtest: pipe-A-eDP-1-size-64
[310.909111] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.420s)
[310.969223] Starting dynamic subtest: pipe-A-eDP-1-size-128
[313.399884] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.438s)
[313.460294] Starting dynamic subtest: pipe-A-eDP-1-size-256
[315.855790] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.405s)
[316.212732] Starting dynamic subtest: pipe-B-eDP-1-size-64
[319.347101] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.145s)
[319.408120] Starting dynamic subtest: pipe-B-eDP-1-size-128
[321.826822] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.427s)
[321.890020] Starting dynamic subtest: pipe-B-eDP-1-size-256
[324.260711] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.383s)
[324.607691] Starting dynamic subtest: pipe-C-eDP-1-size-64
[327.428535] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.831s)
[327.466844] Starting dynamic subtest: pipe-C-eDP-1-size-128
[329.494806] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.036s)
[329.532363] Starting dynamic subtest: pipe-C-eDP-1-size-256
[331.583065] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.059s)
[331.911484] Starting dynamic subtest: pipe-D-eDP-1-size-64
[334.676039] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.775s)
[334.714649] Starting dynamic subtest: pipe-D-eDP-1-size-128
[336.765439] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.058s)
[336.803230] Starting dynamic subtest: pipe-D-eDP-1-size-256
[338.898038] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.103s)
[339.222860] Subtest overlay: SUCCESS (30.729s)
[340.080153] [060/133] (687s left) kms_sequence (queue-busy)
[340.157957] Starting subtest: queue-busy
[340.160139] Starting dynamic subtest: pipe-A-eDP-1
[342.094880] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.937s)
[342.104884] Starting dynamic subtest: pipe-B-eDP-1
[344.654892] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.558s)
[344.665351] Starting dynamic subtest: pipe-C-eDP-1
[347.265854] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.609s)
[347.278681] Starting dynamic subtest: pipe-D-eDP-1
[349.791844] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.526s)
[349.794084] Subtest queue-busy: SUCCESS (9.636s)
[350.583294] [061/133] (676s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[350.656557] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[350.658925] Starting dynamic subtest: A-eDP1
[354.335356] Dynamic subtest A-eDP1: SUCCESS (3.678s)
[354.345700] Starting dynamic subtest: B-eDP1
[357.532140] Dynamic subtest B-eDP1: SUCCESS (3.197s)
[357.542494] Starting dynamic subtest: C-eDP1
[360.639369] Dynamic subtest C-eDP1: SUCCESS (3.106s)
[360.650198] Starting dynamic subtest: D-eDP1
[363.838419] Dynamic subtest D-eDP1: SUCCESS (3.197s)
[363.840388] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.185s)
[364.630426] [062/133] (662s left) gem_flink_basic (double-flink)
[364.695435] Starting subtest: double-flink
[364.697758] Subtest double-flink: SUCCESS (0.000s)
[364.792577] [063/133] (662s left) gem_ctx_isolation (dirty-switch)
[364.853740] Starting subtest: dirty-switch
[364.856149] Starting dynamic subtest: rcs0
[365.039899] Dynamic subtest rcs0: SUCCESS (0.186s)
[365.051868] Starting dynamic subtest: bcs0
[365.243655] Dynamic subtest bcs0: SUCCESS (0.203s)
[365.255747] Starting dynamic subtest: vcs0
[365.446388] Dynamic subtest vcs0: SUCCESS (0.202s)
[365.457972] Starting dynamic subtest: vcs1
[366.542617] Dynamic subtest vcs1: SUCCESS (1.095s)
[366.554821] Starting dynamic subtest: vecs0
[368.625459] Dynamic subtest vecs0: SUCCESS (2.082s)
[368.637321] Starting dynamic subtest: ccs0
[370.623136] Dynamic subtest ccs0: SUCCESS (1.997s)
[370.625445] Subtest dirty-switch: SUCCESS (5.770s)
[370.868332] [064/133] (656s left) kms_cursor_edge_walk (64x64-top-edge)
[370.949670] Starting subtest: 64x64-top-edge
[370.952006] Starting dynamic subtest: pipe-A-eDP-1
[374.417756] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.467s)
[374.430339] Starting dynamic subtest: pipe-D-eDP-1
[377.599592] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.177s)
[377.601855] Subtest 64x64-top-edge: SUCCESS (6.650s)
[378.408303] [065/133] (649s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[378.480123] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[378.482366] Starting dynamic subtest: pipe-A-eDP-1
[378.506690] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[378.516414] Starting dynamic subtest: pipe-B-eDP-1
[378.539850] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[378.549360] Starting dynamic subtest: pipe-C-eDP-1
[378.572451] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[378.581211] Starting dynamic subtest: pipe-D-eDP-1
[378.603980] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[378.608163] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[380.798911] [066/133] (646s left) gem_eio (hibernate)
[381.022008] Starting subtest: hibernate
[394.613134] Subtest hibernate: SUCCESS (13.592s)
[402.752741] [067/133] (632s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[402.839893] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[402.849330] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.008s)
[402.933416] [068/133] (632s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[403.010876] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[403.014787] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[403.104232] [069/133] (632s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[403.184521] Starting subtest: extended-modeset-hang-newfb-with-reset
[403.186839] Starting dynamic subtest: pipe-A
[418.123073] Dynamic subtest pipe-A: SUCCESS (14.937s)
[418.133472] Starting dynamic subtest: pipe-D
[433.047157] Dynamic subtest pipe-D: SUCCESS (14.920s)
[433.049367] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.861s)
[433.851120] [070/133] (601s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[433.932629] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[433.936725] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[434.008180] [071/133] (601s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[434.092249] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[437.746377] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.660s)
[438.959227] [072/133] (596s left) sysfs_preempt_timeout (invalid)
[439.015276] Starting subtest: invalid
[439.017492] Starting dynamic subtest: vecs0
[439.019978] Dynamic subtest vecs0: SUCCESS (0.000s)
[439.031135] Starting dynamic subtest: ccs0
[439.032987] Dynamic subtest ccs0: SUCCESS (0.000s)
[439.042727] Starting dynamic subtest: vcs0
[439.044854] Dynamic subtest vcs0: SUCCESS (0.000s)
[439.054345] Starting dynamic subtest: bcs0
[439.056215] Dynamic subtest bcs0: SUCCESS (0.000s)
[439.065488] Starting dynamic subtest: rcs0
[439.067362] Dynamic subtest rcs0: SUCCESS (0.000s)
[439.076831] Starting dynamic subtest: vcs1
[439.078655] Dynamic subtest vcs1: SUCCESS (0.000s)
[439.080507] Subtest invalid: SUCCESS (0.006s)
[439.115906] [073/133] (596s left) gem_exec_big (single)
[439.171336] Starting subtest: single
[440.207767] Subtest single: SUCCESS (1.033s)
[440.348064] [074/133] (595s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[440.466781] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[440.469085] Starting dynamic subtest: pipe-A-eDP-1
[440.528784] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[440.540472] Starting dynamic subtest: pipe-B-eDP-1
[440.599909] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[440.611528] Starting dynamic subtest: pipe-C-eDP-1
[440.670161] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[440.681392] Starting dynamic subtest: pipe-D-eDP-1
[440.738156] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[440.742445] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.008s)
[442.705090] [075/133] (593s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[442.785161] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[443.808246] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.024s)
[443.921753] [076/133] (592s left) kms_chamelium_hpd (dp-hpd-storm)
[444.288833] Subtest dp-hpd-storm: SKIP (0.000s)
[445.101353] [077/133] (591s left) i915_query (query-topology-kernel-writes)
[445.156158] Starting subtest: query-topology-kernel-writes
[445.158524] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[445.243553] [078/133] (590s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[445.319354] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[445.323565] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[445.390486] [079/133] (590s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[445.453839] Starting subtest: invalid-bsd2-flag-on-blt
[445.456282] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[445.567271] [080/133] (590s left) sw_sync (sync_busy_fork)
[445.611734] Starting subtest: sync_busy_fork
[446.624316] Subtest sync_busy_fork: SUCCESS (1.011s)
[446.687390] [081/133] (589s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[446.744185] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[446.746397] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[446.856791] [082/133] (589s left) kms_chamelium_frames (hdmi-crc-fast)
[447.218673] Subtest hdmi-crc-fast: SKIP (0.000s)
[448.031461] [083/133] (588s left) gem_flink_basic (flink-lifetime)
[448.091528] Starting subtest: flink-lifetime
[448.094274] Subtest flink-lifetime: SUCCESS (0.002s)
[448.184344] [084/133] (588s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[448.266052] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[448.270423] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[448.388491] [085/133] (588s left) kms_prop_blob (basic)
[448.452954] Starting subtest: basic
[448.455203] Subtest basic: SUCCESS (0.000s)
[448.541675] [086/133] (587s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[448.601828] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[448.708230] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.105s)
[448.782588] [087/133] (587s left) perf (blocking-parameterized)
[448.915803] Starting subtest: blocking-parameterized
[468.938753] Subtest blocking-parameterized: SUCCESS (20.022s)
[469.031192] [088/133] (567s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[469.097336] Starting subtest: etime-multi-wait-all-submitted-signaled
[469.203835] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[469.325784] [089/133] (567s left) gem_exec_fence (submit-chain)
[469.515996] Starting subtest: submit-chain
[469.546498] Subtest submit-chain: SUCCESS (0.030s)
[471.501336] [FACT gem_exec_fence (submit-chain)] new: kernel.kmod_is_loaded.vgem: true
[471.504475] [090/133] (565s left) gem_exec_params (mmapped)
[471.562820] Starting subtest: mmapped
[471.569185] Subtest mmapped: SUCCESS (0.007s)
[471.680356] [091/133] (565s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[471.755097] Starting subtest: x-tiled-32bpp-rotate-180
[473.245632] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.490s)
[473.395091] [092/133] (563s left) sw_sync (sync_expired_merge)
[473.435591] Starting subtest: sync_expired_merge
[474.511151] Subtest sync_expired_merge: SUCCESS (1.074s)
[474.578120] [093/133] (562s left) prime_vgem (basic-fence-mmap)
[474.648528] Starting subtest: basic-fence-mmap
[474.653430] Subtest basic-fence-mmap: SKIP (0.000s)
[474.753203] [094/133] (562s left) gem_ctx_shared (disjoint-timelines)
[474.810938] Starting subtest: disjoint-timelines
[474.828495] Subtest disjoint-timelines: SUCCESS (0.020s)
[475.050451] [095/133] (561s left) kms_psr (psr-dpms)
[475.126928] Starting subtest: psr-dpms
[475.129280] Starting dynamic subtest: eDP-1
[478.099065] Dynamic subtest eDP-1: SUCCESS (2.972s)
[478.101337] Subtest psr-dpms: SUCCESS (2.973s)
[478.970099] [096/133] (558s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[479.059672] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[486.337433] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.283s)
[486.475245] [097/133] (550s left) gem_softpin (allocator-nopin)
[486.530019] Starting subtest: allocator-nopin
[486.613832] Subtest allocator-nopin: SUCCESS (0.083s)
[486.729636] [098/133] (550s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[486.810198] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[486.812428] Starting dynamic subtest: pipe-A-default-mode
[487.965747] Dynamic subtest pipe-A-default-mode: SKIP (1.153s)
[487.970464] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.154s)
[488.129352] [099/133] (549s left) gem_exec_flush (basic-wb-pro-default)
[488.190230] Starting subtest: basic-wb-pro-default
[493.670666] Subtest basic-wb-pro-default: SUCCESS (5.481s)
[493.860351] [100/133] (543s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[493.946599] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[493.948921] Starting dynamic subtest: pipe-A-eDP-1
[494.045966] Dynamic subtest pipe-A-eDP-1: SKIP (0.103s)
[494.057384] Starting dynamic subtest: pipe-B-eDP-1
[495.215107] Dynamic subtest pipe-B-eDP-1: SKIP (1.166s)
[495.219447] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.272s)
[496.404892] [101/133] (540s left) syncobj_timeline (single-wait-available-submitted)
[496.462274] Starting subtest: single-wait-available-submitted
[496.464209] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[496.578131] [102/133] (540s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[496.639525] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[496.643492] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[496.823190] [103/133] (540s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[496.908289] Starting subtest: psr-rgb101010-draw-pwrite
[504.455836] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.550s)
[504.572166] [104/133] (532s left) perf (gen8-unprivileged-single-ctx-counters)
[504.671376] Starting subtest: gen8-unprivileged-single-ctx-counters
[504.675767] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[504.719051] [105/133] (532s left) api_intel_allocator (standalone)
[504.773004] Starting subtest: standalone
[504.791453] Subtest standalone: SUCCESS (0.021s)
[504.918620] [106/133] (532s left) kms_getfb (getfb-handle-closed)
[504.998556] Starting subtest: getfb-handle-closed
[505.000762] Subtest getfb-handle-closed: SUCCESS (0.000s)
[505.106024] [107/133] (532s left) perf (mi-rpc)
[505.187954] Starting subtest: mi-rpc
[505.192650] Subtest mi-rpc: SKIP (0.000s)
[505.241431] [108/133] (532s left) kms_async_flips (async-flip-dpms)
[505.318463] Starting subtest: async-flip-dpms
[505.691548] Starting dynamic subtest: pipe-A-eDP-1
[509.527858] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.835s)
[509.929142] Starting dynamic subtest: pipe-B-eDP-1
[512.786139] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.864s)
[513.158568] Starting dynamic subtest: pipe-C-eDP-1
[515.949387] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.802s)
[516.315910] Starting dynamic subtest: pipe-D-eDP-1
[519.119664] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.812s)
[519.121886] Subtest async-flip-dpms: SUCCESS (13.803s)
[520.299343] [109/133] (517s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[520.392906] Starting subtest: y-tiled-64bpp-rotate-180
[520.397384] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[520.500344] [110/133] (517s left) gem_close_race (multigpu-basic-process)
[520.561425] Starting subtest: multigpu-basic-process
[520.566207] Subtest multigpu-basic-process: SKIP (0.000s)
[520.789189] [111/133] (516s left) kms_cursor_crc (cursor-offscreen-32x10)
[520.966404] Starting subtest: cursor-offscreen-32x10
[521.012616] Subtest cursor-offscreen-32x10: SKIP (0.042s)
[521.271291] [112/133] (516s left) gem_exec_flush (basic-wb-rw-default)
[521.326667] Starting subtest: basic-wb-rw-default
[526.780928] Subtest basic-wb-rw-default: SUCCESS (5.455s)
[526.973757] [113/133] (510s left) dmabuf_sync_file (export-before-signal)
[527.021107] Starting subtest: export-before-signal
[527.023323] Subtest export-before-signal: SUCCESS (0.000s)
[527.081201] [114/133] (510s left) gem_exec_schedule (preempt-queue-contexts-chain)
[527.160275] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[527.453900] [115/133] (510s left) perf_pmu (semaphore-wait)
[527.497767] Starting subtest: semaphore-wait
[527.500409] Starting dynamic subtest: rcs0
[528.012208] Dynamic subtest rcs0: SUCCESS (0.515s)
[528.026727] Starting dynamic subtest: bcs0
[528.528217] Dynamic subtest bcs0: SUCCESS (0.513s)
[528.542241] Starting dynamic subtest: vcs0
[529.045713] Dynamic subtest vcs0: SUCCESS (0.514s)
[529.059696] Starting dynamic subtest: vcs1
[529.561688] Dynamic subtest vcs1: SUCCESS (0.513s)
[529.575640] Starting dynamic subtest: vecs0
[530.079138] Dynamic subtest vecs0: SUCCESS (0.513s)
[530.093143] Starting dynamic subtest: ccs0
[530.596546] Dynamic subtest ccs0: SUCCESS (0.514s)
[530.599856] Subtest semaphore-wait: SUCCESS (3.102s)
[530.718694] [116/133] (507s left) gem_basic (multigpu-create-close)
[530.768394] Starting subtest: multigpu-create-close
[530.772808] Subtest multigpu-create-close: SKIP (0.000s)
[530.863097] [117/133] (507s left) kms_vblank (query-idle)
[530.947956] Starting subtest: query-idle
[530.950136] Starting dynamic subtest: pipe-A-eDP-1
[532.690825] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.742s)
[532.707098] Starting dynamic subtest: pipe-D-eDP-1
[535.129975] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.436s)
[535.132434] Subtest query-idle: SUCCESS (4.182s)
[535.961147] [118/133] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[536.035764] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[536.039977] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[536.130501] [119/133] (501s left) syncobj_timeline (reset-during-wait-for-submit)
[536.188859] Starting subtest: reset-during-wait-for-submit
[536.290769] Subtest reset-during-wait-for-submit: SUCCESS (0.102s)
[536.380621] [120/133] (501s left) i915_suspend (basic-s3-without-i915)
[536.421285] Starting subtest: basic-s3-without-i915
[538.151059] Subtest basic-s3-without-i915: SKIP (1.729s)
[539.725006] [121/133] (498s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[539.827390] Starting subtest: plane-primary-toggle-with-vblank-wait
[539.829799] Starting dynamic subtest: pipe-A-eDP-1
[539.997171] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.172s)
[540.309150] Starting dynamic subtest: pipe-B-eDP-1
[541.218236] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.919s)
[541.531536] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.704s)
[542.390169] [122/133] (495s left) kms_psr (fbc-psr-primary-blt)
[542.469972] Starting subtest: fbc-psr-primary-blt
[542.472187] Starting dynamic subtest: eDP-1
[542.476394] Dynamic subtest eDP-1: SKIP (0.000s)
[542.480483] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[542.547736] [123/133] (495s left) gem_mmap (bad-offset)
[542.593969] Subtest bad-offset: SKIP (0.000s)
[542.651810] [124/133] (495s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[542.726568] Starting subtest: yf-tiled-8bpp-rotate-0
[542.730922] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[542.814682] [125/133] (495s left) gem_exec_flush (basic-batch-kernel-default-wb)
[542.872218] Starting subtest: basic-batch-kernel-default-wb
[550.392508] Subtest basic-batch-kernel-default-wb: SUCCESS (7.521s)
[550.583368] [126/133] (487s left) perf (invalid-oa-format-id)
[550.704895] Starting subtest: invalid-oa-format-id
[550.718225] Subtest invalid-oa-format-id: SUCCESS (0.011s)
[550.762084] [127/133] (487s left) kms_async_flips (test-time-stamp)
[550.843909] Starting subtest: test-time-stamp
[551.223322] Starting dynamic subtest: pipe-A-eDP-1
[551.992159] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.766s)
[552.363849] Starting dynamic subtest: pipe-B-eDP-1
[553.122179] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[553.491437] Starting dynamic subtest: pipe-C-eDP-1
[554.253509] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.767s)
[554.622399] Starting dynamic subtest: pipe-D-eDP-1
[555.383039] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.767s)
[555.385488] Subtest test-time-stamp: SUCCESS (4.539s)
[556.552843] [128/133] (481s left) kms_content_protection (legacy-hdcp14)
[556.694528] Starting subtest: legacy-hdcp14
[556.699059] Subtest legacy-hdcp14: SKIP (0.000s)
[556.832632] [129/133] (481s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[556.915947] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[556.918277] Starting dynamic subtest: pipe-A-eDP-1
[573.670674] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.476s)
[573.687312] Starting dynamic subtest: pipe-B-eDP-1
[590.623391] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.415s)
[590.635471] Starting dynamic subtest: pipe-C-eDP-1
[607.585516] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.339s)
[607.595651] Starting dynamic subtest: pipe-D-eDP-1
[624.591020] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.396s)
[624.593638] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.631s)
[625.734398] [130/133] (412s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[625.831341] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[632.520432] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.682s)
[632.634209] [131/133] (405s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[632.914665] Starting subtest: cursor-rapid-movement-max-size
[632.921563] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[633.046111] [132/133] (405s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[633.120398] Starting subtest: fbcpsr-suspend
[674.061953] Subtest fbcpsr-suspend: SUCCESS (10.131s)
[674.220802] [133/133] (364s left) api_intel_bb (reset-flags)
[674.299472] Starting subtest: reset-flags
[674.319916] Subtest reset-flags: SUCCESS (0.009s)
[674.429626] Closing watchdogs
results: parsing output: 0/ for test: gem_softpin
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: kms_invalid_mode
results: parsing output: 3/ for test: kms_prop_blob
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_pxp
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: kms_plane_alpha_blend
results: parsing output: 12/ for test: kms_plane
results: parsing output: 13/ for test: gem_mmap_gtt
results: parsing output: 14/ for test: kms_pipe_b_c_ivb
results: parsing output: 15/ for test: gem_vm_create
results: parsing output: 16/ for test: gem_lmem_swapping
results: parsing output: 17/ for test: kms_feature_discovery
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: gem_pxp
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: kms_color_pipeline
results: parsing output: 25/ for test: gem_exec_schedule
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: gem_exec_whisper
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: kms_cursor_legacy
results: parsing output: 30/ for test: gem_exec_params
results: parsing output: 31/ for test: kms_joiner
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: kms_flip
results: parsing output: 34/ for test: gem_eio
results: parsing output: 35/ for test: kms_pm_lpsp
results: parsing output: 36/ for test: i915_drm_fdinfo
results: parsing output: 37/ for test: drm_read
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: gem_ctx_shared
results: parsing output: 41/ for test: gem_sync
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: i915_pm_freq_api
results: parsing output: 44/ for test: kms_atomic_transition
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_getfb
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: drm_read
results: parsing output: 50/ for test: gen9_exec_parse
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_flip_scaled_crc
results: parsing output: 54/ for test: gem_exec_balancer
results: parsing output: 55/ for test: kms_plane_scaling
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: i915_pm_rps
results: parsing output: 58/ for test: kms_plane_cursor
results: parsing output: 59/ for test: kms_sequence
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: gem_flink_basic
results: parsing output: 62/ for test: gem_ctx_isolation
results: parsing output: 63/ for test: kms_cursor_edge_walk
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: gem_eio
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_busy
results: parsing output: 69/ for test: kms_flip_scaled_crc
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: sysfs_preempt_timeout
results: parsing output: 72/ for test: gem_exec_big
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_chamelium_hpd
results: parsing output: 76/ for test: i915_query
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: sw_sync
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_chamelium_frames
results: parsing output: 82/ for test: gem_flink_basic
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_prop_blob
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: perf
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: gem_exec_params
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: sw_sync
results: parsing output: 92/ for test: prime_vgem
results: parsing output: 93/ for test: gem_ctx_shared
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_softpin
results: parsing output: 97/ for test: kms_flip_scaled_crc
results: parsing output: 98/ for test: gem_exec_flush
results: parsing output: 99/ for test: kms_psr2_sf
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: gem_render_copy
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: perf
results: parsing output: 104/ for test: api_intel_allocator
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: perf
results: parsing output: 107/ for test: kms_async_flips
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: gem_close_race
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: gem_exec_flush
results: parsing output: 112/ for test: dmabuf_sync_file
results: parsing output: 113/ for test: gem_exec_schedule
results: parsing output: 114/ for test: perf_pmu
results: parsing output: 115/ for test: gem_basic
results: parsing output: 116/ for test: kms_vblank
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: i915_suspend
results: parsing output: 120/ for test: kms_atomic_transition
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_mmap
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: gem_exec_flush
results: parsing output: 125/ for test: perf
results: parsing output: 126/ for test: kms_async_flips
results: parsing output: 127/ for test: kms_content_protection
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_cursor_crc
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: api_intel_bb
Done.