[57.280234] Initializing watchdogs
[57.280278]   /dev/watchdog0
[57.294278] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.298497] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.300837] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.305078] [001/133] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[57.437364] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[57.441681] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[57.539592] [002/133] (960s left) i915_pm_rps (engine-order)
[57.608733] Starting subtest: engine-order
[62.622036] Subtest engine-order: SUCCESS (5.009s)
[62.746067] [003/133] (955s left) gem_mmap_offset (clear-via-pagefault)
[62.796421] Starting subtest: clear-via-pagefault
[62.798775] Starting dynamic subtest: smem0
[99.326300] Dynamic subtest smem0: SUCCESS (36.529s)
[99.328507] Subtest clear-via-pagefault: SUCCESS (36.530s)
[99.429663] [004/133] (918s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[99.837797] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[100.600301] [005/133] (917s left) kms_flip (basic-flip-vs-dpms)
[100.693403] Starting subtest: basic-flip-vs-dpms
[100.695734] Starting dynamic subtest: A-eDP1
[103.325311] Dynamic subtest A-eDP1: SUCCESS (2.631s)
[103.332936] Starting dynamic subtest: B-eDP1
[105.498674] Dynamic subtest B-eDP1: SUCCESS (2.174s)
[105.506842] Starting dynamic subtest: C-eDP1
[107.573193] Dynamic subtest C-eDP1: SUCCESS (2.075s)
[107.587612] Starting dynamic subtest: D-eDP1
[109.668700] Dynamic subtest D-eDP1: SUCCESS (2.092s)
[109.671416] Subtest basic-flip-vs-dpms: SUCCESS (8.981s)
[110.483582] [006/133] (907s left) drm_buddy (drm_buddy)
[110.519122] Starting subtest: drm_buddy
[110.523777] Subtest drm_buddy: SKIP (0.000s)
[110.570107] [007/133] (907s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[110.674629] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[117.393735] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.722s)
[117.502853] [008/133] (900s left) kms_addfb_basic (size-max)
[117.594857] Starting subtest: size-max
[117.597208] Subtest size-max: SUCCESS (0.000s)
[117.708599] [009/133] (900s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[117.815909] Starting subtest: x-tiled-16bpp-rotate-90
[117.834931] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s)
[117.921074] [010/133] (900s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[118.295369] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[119.108473] [011/133] (899s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[119.216909] Starting subtest: yf-tiled-32bpp-rotate-270
[119.221037] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[119.325820] [012/133] (898s left) kms_psr (fbc-psr-primary-render)
[119.416919] Starting subtest: fbc-psr-primary-render
[119.419251] Starting dynamic subtest: eDP-1
[119.423418] Dynamic subtest eDP-1: SKIP (0.000s)
[119.427609] Subtest fbc-psr-primary-render: SKIP (0.000s)
[119.485743] [013/133] (898s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[119.595749] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[123.276454] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.683s)
[124.445744] [014/133] (893s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[124.545924] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[124.550732] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[124.628532] [015/133] (893s left) gem_spin_batch (resubmit)
[124.690047] Starting subtest: resubmit
[124.692111] Starting dynamic subtest: rcs0
[124.694609] Dynamic subtest rcs0: SUCCESS (0.006s)
[124.707990] Starting dynamic subtest: bcs0
[124.710462] Dynamic subtest bcs0: SUCCESS (0.003s)
[124.717915] Starting dynamic subtest: vcs0
[124.719859] Dynamic subtest vcs0: SUCCESS (0.002s)
[124.727059] Starting dynamic subtest: vcs1
[124.728979] Dynamic subtest vcs1: SUCCESS (0.002s)
[124.735883] Starting dynamic subtest: vecs0
[124.737927] Dynamic subtest vecs0: SUCCESS (0.002s)
[124.744827] Starting dynamic subtest: ccs0
[124.746755] Dynamic subtest ccs0: SUCCESS (0.003s)
[124.748643] Subtest resubmit: SUCCESS (0.020s)
[124.934656] [016/133] (893s left) kms_atomic (atomic-invalid-params)
[125.029759] Starting subtest: atomic-invalid-params
[125.032135] Starting dynamic subtest: pipe-A-eDP-1
[125.431698] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.401s)
[125.433801] Subtest atomic-invalid-params: SUCCESS (0.402s)
[126.272707] [017/133] (892s left) gem_exec_schedule (u-submit-late-slice)
[126.346962] Starting subtest: u-submit-late-slice
[126.349466] Starting dynamic subtest: rcs0
[126.386979] Dynamic subtest rcs0: SUCCESS (0.048s)
[126.399148] Starting dynamic subtest: bcs0
[126.419252] Dynamic subtest bcs0: SUCCESS (0.032s)
[126.427861] Starting dynamic subtest: vcs0
[126.445869] Dynamic subtest vcs0: SUCCESS (0.026s)
[126.453720] Starting dynamic subtest: vcs1
[126.473010] Dynamic subtest vcs1: SUCCESS (0.027s)
[126.484872] Starting dynamic subtest: vecs0
[126.513866] Dynamic subtest vecs0: SUCCESS (0.040s)
[126.524262] Starting dynamic subtest: ccs0
[126.547943] Dynamic subtest ccs0: SUCCESS (0.034s)
[126.550361] Subtest u-submit-late-slice: SUCCESS (0.210s)
[127.980169] [018/133] (890s left) vgem_basic (mmap)
[128.054707] Starting subtest: mmap
[128.059392] Subtest mmap: SUCCESS (0.006s)
[128.109847] [FACT vgem_basic (mmap)] new: kernel.kmod_is_loaded.vgem: true
[128.112879] [019/133] (890s left) gem_exec_suspend (basic-s3)
[128.167998] Starting subtest: basic-S3
[128.170421] Starting dynamic subtest: smem
[148.970480] Dynamic subtest smem: SUCCESS (7.724s)
[148.973530] Subtest basic-S3: SUCCESS (7.725s)
[149.155288] [020/133] (869s left) syncobj_timeline (wait-any-snapshot)
[149.225924] Starting subtest: wait-any-snapshot
[149.308530] Subtest wait-any-snapshot: SUCCESS (0.083s)
[149.387855] [021/133] (869s left) kms_universal_plane (universal-plane-sanity)
[149.479595] Starting subtest: universal-plane-sanity
[149.481691] Starting dynamic subtest: pipe-A-eDP-1
[150.087497] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.607s)
[150.097586] Starting dynamic subtest: pipe-B-eDP-1
[151.306005] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.217s)
[151.316650] Starting dynamic subtest: pipe-C-eDP-1
[152.507999] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.201s)
[152.518913] Starting dynamic subtest: pipe-D-eDP-1
[153.743730] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[153.746163] Subtest universal-plane-sanity: SUCCESS (4.265s)
[154.595074] [022/133] (864s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[154.686751] Starting subtest: y-tiled-32bpp-rotate-90
[154.691223] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[154.766752] [023/133] (863s left) kms_lease (simple-lease)
[154.846895] Starting subtest: simple-lease
[154.849229] Starting dynamic subtest: pipe-A-eDP-1
[155.347681] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.501s)
[155.356843] Starting dynamic subtest: pipe-B-eDP-1
[156.835348] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.486s)
[156.844801] Starting dynamic subtest: pipe-C-eDP-1
[158.232608] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.395s)
[158.242467] Starting dynamic subtest: pipe-D-eDP-1
[159.643009] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.408s)
[159.645262] Subtest simple-lease: SUCCESS (4.797s)
[160.449372] [024/133] (858s left) kms_big_fb (linear-64bpp-rotate-90)
[160.547768] Starting subtest: linear-64bpp-rotate-90
[160.567237] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[160.670633] [025/133] (858s left) kms_properties (connector-properties-atomic)
[160.763841] Starting subtest: connector-properties-atomic
[160.766432] Starting dynamic subtest: pipe-A-eDP-1
[168.564830] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.799s)
[168.576211] Starting dynamic subtest: pipe-None-DP-1
[168.580056] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[168.588078] Starting dynamic subtest: pipe-None-HDMI-A-1
[168.592479] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[168.600999] Starting dynamic subtest: pipe-None-DP-2
[168.605136] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[168.614804] Starting dynamic subtest: pipe-None-HDMI-A-2
[168.620725] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[168.629735] Starting dynamic subtest: pipe-None-DP-3
[168.634968] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[168.645388] Starting dynamic subtest: pipe-None-DP-4
[168.649476] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[168.651353] Subtest connector-properties-atomic: SUCCESS (7.809s)
[169.424569] [026/133] (849s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[169.519856] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[169.522102] Starting dynamic subtest: pipe-A-eDP-1
[175.411588] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.895s)
[175.420826] Starting dynamic subtest: pipe-B-eDP-1
[182.065407] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.651s)
[182.067732] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.549s)
[182.943645] [027/133] (835s left) kms_vblank (crtc-id)
[183.030298] Starting subtest: crtc-id
[183.032478] Starting dynamic subtest: pipe-A-eDP-1
[183.495800] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.463s)
[183.509720] Starting dynamic subtest: pipe-D-eDP-1
[184.662863] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.165s)
[184.664938] Subtest crtc-id: SUCCESS (1.631s)
[185.516121] [028/133] (833s left) kms_cursor_crc (cursor-sliding-max-size)
[185.824915] Starting subtest: cursor-sliding-max-size
[185.832757] Subtest cursor-sliding-max-size: SKIP (0.000s)
[185.955239] [029/133] (832s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[186.066860] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[186.071262] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[186.138203] [030/133] (832s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[186.192959] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[186.195270] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[186.304284] [031/133] (832s left) gem_render_copy (y-tiled-ccs-to-linear)
[186.368179] Starting subtest: y-tiled-ccs-to-linear
[186.372658] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[186.550620] [032/133] (832s left) gem_sync (basic-store-each)
[186.634893] Starting subtest: basic-store-each
[189.162053] Subtest basic-store-each: SUCCESS (2.532s)
[189.351597] [033/133] (829s left) gem_exercise_blt (fast-copy)
[189.417803] Starting subtest: fast-copy
[189.420184] Starting dynamic subtest: linear-smem-smem
[189.446459] Dynamic subtest linear-smem-smem: SUCCESS (0.029s)
[189.458414] Starting dynamic subtest: xmajor-smem-smem
[189.466326] Dynamic subtest xmajor-smem-smem: SUCCESS (0.020s)
[189.475090] Starting dynamic subtest: tile4-smem-smem
[189.486617] Dynamic subtest tile4-smem-smem: SUCCESS (0.020s)
[189.494850] Starting dynamic subtest: tile64-smem-smem
[189.506608] Dynamic subtest tile64-smem-smem: SUCCESS (0.020s)
[189.508992] Subtest fast-copy: SUCCESS (0.089s)
[189.643933] [034/133] (829s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[189.744293] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[189.746552] Starting dynamic subtest: pipe-A-eDP-1
[189.771530] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[189.781651] Starting dynamic subtest: pipe-B-eDP-1
[189.805234] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[189.813408] Starting dynamic subtest: pipe-C-eDP-1
[189.836884] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[189.845074] Starting dynamic subtest: pipe-D-eDP-1
[189.868764] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[189.873124] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[192.058746] [035/133] (827s left) prime_busy (hang)
[192.107190] Starting subtest: hang
[192.109568] Starting dynamic subtest: rcs0
[218.503606] Dynamic subtest rcs0: SUCCESS (26.393s)
[218.512282] Starting dynamic subtest: bcs0
[225.658263] Dynamic subtest bcs0: SUCCESS (7.151s)
[225.669272] Starting dynamic subtest: vcs0
[232.626420] Dynamic subtest vcs0: SUCCESS (6.972s)
[232.635246] Starting dynamic subtest: vcs1
[239.607016] Dynamic subtest vcs1: SUCCESS (6.978s)
[239.615207] Starting dynamic subtest: vecs0
[246.640371] Dynamic subtest vecs0: SUCCESS (7.035s)
[246.649249] Starting dynamic subtest: ccs0
[260.483975] Dynamic subtest ccs0: SUCCESS (13.839s)
[260.486829] Subtest hang: SUCCESS (68.373s)
[260.613265] [036/133] (758s left) kms_dp_link_training (uhbr-sst)
[260.722608] Starting subtest: uhbr-sst
[260.730763] Subtest uhbr-sst: SKIP (0.000s)
[260.796931] [037/133] (758s left) fbdev (pan)
[260.839071] Starting subtest: pan
[260.867366] Subtest pan: SUCCESS (0.030s)
[260.950992] [038/133] (758s left) kms_content_protection (legacy)
[261.083591] Starting subtest: legacy
[261.088083] Subtest legacy: SKIP (0.000s)
[261.210947] [039/133] (758s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[261.316202] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[261.320302] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[261.383570] [040/133] (757s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[261.491734] Starting subtest: fbc-farfromfence-mmap-gtt
[261.495218] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[261.589742] [041/133] (757s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[261.690333] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[261.692116] Starting dynamic subtest: pipe-A-eDP-1
[261.767108] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[261.777407] Starting dynamic subtest: pipe-B-eDP-1
[262.933018] Dynamic subtest pipe-B-eDP-1: SKIP (1.165s)
[262.937187] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.244s)
[264.112728] [042/133] (755s left) kms_pm_dc (dc3co-vpb-simulation)
[265.212771] Starting subtest: dc3co-vpb-simulation
[274.615721] Subtest dc3co-vpb-simulation: SKIP (9.399s)
[274.749494] [043/133] (744s left) api_intel_bb (destroy-bb)
[274.815436] Starting subtest: destroy-bb
[274.820648] Subtest destroy-bb: SUCCESS (0.008s)
[274.947792] [044/133] (744s left) gem_ctx_exec (basic-norecovery)
[274.997723] Starting subtest: basic-norecovery
[275.249286] Subtest basic-norecovery: SUCCESS (0.250s)
[275.369137] [045/133] (744s left) gem_ctx_param (set-priority-invalid-size)
[275.427901] Starting subtest: set-priority-invalid-size
[275.430303] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[275.503688] [046/133] (744s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[275.593107] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[275.595434] Starting dynamic subtest: pipe-A-eDP-1
[275.649759] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[275.658257] Starting dynamic subtest: pipe-B-eDP-1
[275.705413] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[275.714203] Starting dynamic subtest: pipe-C-eDP-1
[275.761052] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[275.769832] Starting dynamic subtest: pipe-D-eDP-1
[275.814195] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[275.817641] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.008s)
[277.875308] [047/133] (741s left) gem_ctx_shared (q-in-order)
[277.947892] Starting subtest: Q-in-order
[277.950278] Starting dynamic subtest: rcs0
[278.052462] Dynamic subtest rcs0: SUCCESS (0.106s)
[278.064686] Starting dynamic subtest: vcs0
[278.146994] Dynamic subtest vcs0: SUCCESS (0.094s)
[278.164276] Starting dynamic subtest: vcs1
[278.233391] Dynamic subtest vcs1: SUCCESS (0.085s)
[278.250252] Starting dynamic subtest: vecs0
[278.318485] Dynamic subtest vecs0: SUCCESS (0.084s)
[278.335646] Starting dynamic subtest: ccs0
[278.412894] Dynamic subtest ccs0: SUCCESS (0.093s)
[278.415789] Subtest Q-in-order: SUCCESS (0.468s)
[280.059160] [048/133] (739s left) kms_flip (2x-modeset-vs-vblank-race)
[280.161525] Starting subtest: 2x-modeset-vs-vblank-race
[280.170150] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[280.271083] [049/133] (739s left) kms_flip (flip-vs-panning-interruptible)
[280.373441] Starting subtest: flip-vs-panning-interruptible
[280.375834] Starting dynamic subtest: A-eDP1
[289.070727] Dynamic subtest A-eDP1: SUCCESS (8.696s)
[289.084219] Starting dynamic subtest: B-eDP1
[297.802510] Dynamic subtest B-eDP1: SUCCESS (8.729s)
[297.812171] Starting dynamic subtest: C-eDP1
[306.523601] Dynamic subtest C-eDP1: SUCCESS (8.720s)
[306.534442] Starting dynamic subtest: D-eDP1
[315.252683] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[315.257070] Subtest flip-vs-panning-interruptible: SUCCESS (34.882s)
[316.414365] [050/133] (703s left) gem_mmap_gtt (cpuset-big-copy-xy)
[316.465759] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[316.532904] [051/133] (703s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[316.636053] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[316.640271] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[316.728956] [052/133] (703s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[316.789452] Starting subtest: etime-single-wait-all-available-unsubmitted
[316.895236] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.104s)
[316.971920] [053/133] (702s left) syncobj_timeline (invalid-wait-illegal-handle)
[317.035387] Starting subtest: invalid-wait-illegal-handle
[317.037718] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[317.148349] [054/133] (702s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[317.239498] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[324.089609] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.852s)
[324.227076] [055/133] (695s left) kms_psr (pr-cursor-mmap-gtt)
[324.323692] Starting subtest: pr-cursor-mmap-gtt
[324.327911] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[324.399962] [056/133] (695s left) perf_pmu (busy-idle-no-semaphores)
[324.445458] Starting subtest: busy-idle-no-semaphores
[324.447603] Starting dynamic subtest: rcs0
[325.095968] Dynamic subtest rcs0: SUCCESS (0.650s)
[325.108162] Starting dynamic subtest: bcs0
[325.748574] Dynamic subtest bcs0: SUCCESS (0.650s)
[325.760827] Starting dynamic subtest: vcs0
[326.400396] Dynamic subtest vcs0: SUCCESS (0.649s)
[326.412785] Starting dynamic subtest: vcs1
[327.571277] Dynamic subtest vcs1: SUCCESS (1.168s)
[327.583548] Starting dynamic subtest: vecs0
[329.618152] Dynamic subtest vecs0: SUCCESS (2.044s)
[329.630236] Starting dynamic subtest: ccs0
[331.605894] Dynamic subtest ccs0: SUCCESS (1.986s)
[331.608965] Subtest busy-idle-no-semaphores: SUCCESS (7.162s)
[331.726578] [057/133] (688s left) syncobj_timeline (wait-for-submit-snapshot)
[331.787358] Starting subtest: wait-for-submit-snapshot
[331.868446] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[331.946590] [058/133] (688s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[332.042702] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[332.046718] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[332.151159] [059/133] (687s left) perf (mi-rpc)
[332.278359] Starting subtest: mi-rpc
[332.282779] Subtest mi-rpc: SKIP (0.000s)
[332.324291] [060/133] (687s left) gem_create (create-invalid-size)
[332.384502] Starting subtest: create-invalid-size
[332.386848] Subtest create-invalid-size: SUCCESS (0.000s)
[332.568164] [061/133] (687s left) kms_vblank (wait-idle)
[332.656892] Starting subtest: wait-idle
[332.659083] Starting dynamic subtest: pipe-A-eDP-1
[334.407909] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.752s)
[334.421987] Starting dynamic subtest: pipe-D-eDP-1
[336.891178] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.478s)
[336.893424] Subtest wait-idle: SUCCESS (4.234s)
[337.727492] [062/133] (682s left) gem_exec_schedule (semaphore-codependency)
[337.792188] Starting subtest: semaphore-codependency
[337.798682] Subtest semaphore-codependency: SUCCESS (0.012s)
[338.140807] [063/133] (682s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[338.240011] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[338.244249] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[338.345394] [064/133] (682s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[338.431292] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[338.442511] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.010s)
[338.551994] [065/133] (681s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[338.654387] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[345.355353] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.703s)
[345.479088] [066/133] (674s left) gem_exec_balancer (full)
[345.554492] Starting subtest: full
[349.820782] Subtest full: SUCCESS (4.268s)
[350.143038] [067/133] (670s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[350.251861] Starting subtest: nonblocking-crc-frame-sequence
[350.254120] Starting dynamic subtest: pipe-A-eDP-1
[350.983448] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.730s)
[350.995603] Starting dynamic subtest: pipe-B-eDP-1
[352.354006] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.371s)
[352.364022] Starting dynamic subtest: pipe-C-eDP-1
[353.745256] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.389s)
[353.756349] Starting dynamic subtest: pipe-D-eDP-1
[355.152997] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.404s)
[355.155163] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.900s)
[355.959723] [068/133] (664s left) gen9_exec_parse (secure-batches)
[356.036880] Subtest secure-batches: SKIP (0.000s)
[356.095164] [069/133] (664s left) kms_plane_lowres (tiling-none)
[356.186650] Starting subtest: tiling-none
[356.188809] Starting dynamic subtest: pipe-A-eDP-1
[356.193154] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[356.479081] Starting dynamic subtest: pipe-B-eDP-1
[356.484761] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[356.499732] Starting dynamic subtest: pipe-C-eDP-1
[356.504573] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[356.517522] Starting dynamic subtest: pipe-D-eDP-1
[356.521711] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[356.525833] Subtest tiling-none: SKIP (0.292s)
[357.287367] [070/133] (663s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[357.392078] Starting subtest: pixel-format-y-tiled-ccs-modifier
[357.396071] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[357.479295] [071/133] (663s left) gem_fence_thrash (bo-copy)
[357.512824] Starting subtest: bo-copy
[357.531843] Subtest bo-copy: SKIP (0.016s)
[357.595894] [072/133] (663s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[357.697532] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[364.777823] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.084s)
[364.902628] [073/133] (655s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[365.002003] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[365.006404] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[365.089280] [074/133] (655s left) kms_async_flips (crc)
[365.175595] Starting subtest: crc
[365.548898] Starting dynamic subtest: pipe-A-eDP-1
[368.439484] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.890s)
[368.816211] Starting dynamic subtest: pipe-B-eDP-1
[371.779378] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.972s)
[372.153691] Starting dynamic subtest: pipe-C-eDP-1
[375.043233] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.898s)
[375.408663] Starting dynamic subtest: pipe-D-eDP-1
[378.273566] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.873s)
[378.275791] Subtest crc: SUCCESS (13.101s)
[379.452141] [075/133] (641s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[379.566381] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[379.570544] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[379.655211] [076/133] (641s left) perf_pmu (busy-accuracy-2)
[379.693985] Starting subtest: busy-accuracy-2
[379.696669] Starting dynamic subtest: rcs0
[382.736233] Dynamic subtest rcs0: SUCCESS (3.042s)
[382.748995] Starting dynamic subtest: bcs0
[385.793557] Dynamic subtest bcs0: SUCCESS (3.055s)
[385.804425] Starting dynamic subtest: vcs0
[388.841991] Dynamic subtest vcs0: SUCCESS (3.046s)
[388.852880] Starting dynamic subtest: vcs1
[391.891042] Dynamic subtest vcs1: SUCCESS (3.046s)
[391.902200] Starting dynamic subtest: vecs0
[394.935973] Dynamic subtest vecs0: SUCCESS (3.044s)
[394.948275] Starting dynamic subtest: ccs0
[397.994479] Dynamic subtest ccs0: SUCCESS (3.056s)
[397.996703] Subtest busy-accuracy-2: SUCCESS (18.302s)
[398.111444] [077/133] (622s left) perf (short-reads)
[398.212518] Starting subtest: short-reads
[398.230995] Subtest short-reads: SUCCESS (0.018s)
[398.275400] [078/133] (622s left) gem_exec_schedule (preempt-queue-contexts)
[398.346608] Subtest preempt-queue-contexts: SKIP (0.000s)
[398.635021] [079/133] (622s left) i915_query (engine-info-invalid)
[398.691898] Starting subtest: engine-info-invalid
[398.693973] Subtest engine-info-invalid: SUCCESS (0.000s)
[398.798300] [080/133] (622s left) gem_pxp (reject-modify-context-protection-off-3)
[398.914830] Starting subtest: reject-modify-context-protection-off-3
[398.917398] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[399.024875] [081/133] (622s left) gem_exec_params (invalid-batch-start-offset)
[399.075364] Starting subtest: invalid-batch-start-offset
[399.080172] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[399.194080] [082/133] (621s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[399.285205] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[406.579888] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.297s)
[406.718722] [083/133] (614s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[406.806142] Starting subtest: primary-yf-tiled-reflect-x-270
[406.810446] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[406.879745] [084/133] (614s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[406.968797] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[406.971175] Starting dynamic subtest: pipe-A-default-mode
[409.686645] Dynamic subtest pipe-A-default-mode: SUCCESS (2.717s)
[409.688983] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.718s)
[410.552124] [085/133] (610s left) perf_pmu (frequency)
[410.600548] Starting subtest: frequency
[410.602412] Starting dynamic subtest: gt0
[412.739870] Dynamic subtest gt0: SUCCESS (2.132s)
[412.751102] Starting dynamic subtest: idle-gt0
[413.246028] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[413.257733] Starting dynamic subtest: gt1
[417.937583] Dynamic subtest gt1: SUCCESS (4.684s)
[417.948598] Starting dynamic subtest: idle-gt1
[418.480818] Dynamic subtest idle-gt1: SUCCESS (0.543s)
[418.483010] Subtest frequency: SUCCESS (7.883s)
[418.574835] [086/133] (602s left) kms_getfb (getfb2-accept-nv12)
[418.669175] Starting subtest: getfb2-accept-nv12
[418.671456] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[418.782848] [087/133] (602s left) gem_ctx_persistence (legacy-engines-hostile)
[418.837772] Starting subtest: legacy-engines-hostile
[418.840540] Starting dynamic subtest: render
[418.916361] Dynamic subtest render: SUCCESS (0.078s)
[418.928684] Starting dynamic subtest: bsd1
[419.026311] Dynamic subtest bsd1: SUCCESS (0.108s)
[419.036313] Starting dynamic subtest: bsd2
[419.137612] Dynamic subtest bsd2: SUCCESS (0.109s)
[419.153036] Starting dynamic subtest: blt
[419.242890] Dynamic subtest blt: SUCCESS (0.102s)
[419.254719] Starting dynamic subtest: vebox
[419.343617] Dynamic subtest vebox: SUCCESS (0.098s)
[419.346072] Subtest legacy-engines-hostile: SUCCESS (0.508s)
[419.423073] [088/133] (601s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[419.511288] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[419.513112] Starting dynamic subtest: pipe-A
[419.532719] Dynamic subtest pipe-A: SKIP (0.017s)
[419.547108] Starting dynamic subtest: pipe-B
[419.563234] Dynamic subtest pipe-B: SKIP (0.030s)
[419.575823] Starting dynamic subtest: pipe-C
[419.592436] Dynamic subtest pipe-C: SKIP (0.029s)
[419.601392] Starting dynamic subtest: pipe-D
[419.621250] Dynamic subtest pipe-D: SKIP (0.029s)
[419.624380] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.108s)
[419.734561] [089/133] (601s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[419.802269] Starting subtest: y-tiled-to-vebox-x-tiled
[419.806281] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[419.989369] [090/133] (601s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[420.286209] Starting subtest: cursor-rapid-movement-512x170
[420.290663] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[420.419324] [091/133] (601s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[420.520938] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[420.523086] Starting dynamic subtest: pipe-A-eDP-1
[422.695453] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.175s)
[422.705439] Starting dynamic subtest: pipe-B-eDP-1
[424.845782] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.151s)
[424.855225] Starting dynamic subtest: pipe-C-eDP-1
[426.931969] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.084s)
[426.944641] Starting dynamic subtest: pipe-D-eDP-1
[428.951105] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.016s)
[428.953485] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.430s)
[430.185055] [092/133] (591s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[430.285820] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[430.290366] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[430.390273] [093/133] (591s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[430.495223] Starting subtest: 4-tiled-64bpp-rotate-90
[430.514368] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.019s)
[430.629891] [094/133] (590s left) prime_vgem (basic-fence-flip)
[430.692438] Starting subtest: basic-fence-flip
[430.985442] Subtest basic-fence-flip: SUCCESS (0.293s)
[431.117540] [095/133] (590s left) gem_exec_suspend (basic)
[431.172486] Starting subtest: basic
[431.174906] Starting dynamic subtest: smem
[432.938549] Dynamic subtest smem: SUCCESS (1.764s)
[432.941479] Subtest basic: SUCCESS (1.766s)
[433.131317] [096/133] (588s left) i915_drm_fdinfo (busy-hang)
[433.179275] Starting subtest: busy-hang
[433.181455] Starting dynamic subtest: rcs0
[433.205467] Dynamic subtest rcs0: SKIP (0.027s)
[433.217136] Starting dynamic subtest: bcs0
[433.221644] Dynamic subtest bcs0: SKIP (0.001s)
[433.230596] Starting dynamic subtest: vcs0
[433.234642] Dynamic subtest vcs0: SKIP (0.001s)
[433.243630] Starting dynamic subtest: vcs1
[433.247994] Dynamic subtest vcs1: SKIP (0.001s)
[433.256678] Starting dynamic subtest: vecs0
[433.260988] Dynamic subtest vecs0: SKIP (0.001s)
[433.269588] Starting dynamic subtest: ccs0
[433.273907] Dynamic subtest ccs0: SKIP (0.001s)
[433.278203] Subtest busy-hang: SKIP (0.038s)
[433.315968] [097/133] (588s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[433.374091] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[433.478493] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[433.555592] [098/133] (588s left) kms_color_pipeline (plane-lut1d)
[433.651791] Starting subtest: plane-lut1d
[433.775650] Starting dynamic subtest: pipe-A-plane-0
[434.126434] Dynamic subtest pipe-A-plane-0: FAIL (0.163s)
[434.140439] Starting dynamic subtest: pipe-A-plane-1
[434.348974] Dynamic subtest pipe-A-plane-1: FAIL (0.155s)
[434.359909] Starting dynamic subtest: pipe-A-plane-2
[434.586856] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[435.477050] Starting dynamic subtest: pipe-B-plane-0
[435.874125] Dynamic subtest pipe-B-plane-0: FAIL (0.140s)
[435.883772] Starting dynamic subtest: pipe-B-plane-1
[436.184378] Dynamic subtest pipe-B-plane-1: FAIL (0.155s)
[436.193697] Starting dynamic subtest: pipe-B-plane-2
[436.495524] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[437.103903] Starting dynamic subtest: pipe-C-plane-0
[437.581701] Dynamic subtest pipe-C-plane-0: FAIL (0.139s)
[437.592560] Starting dynamic subtest: pipe-C-plane-1
[437.977400] Dynamic subtest pipe-C-plane-1: FAIL (0.133s)
[437.989742] Starting dynamic subtest: pipe-C-plane-2
[438.339154] Dynamic subtest pipe-C-plane-2: FAIL (0.602s)
[439.135359] Starting dynamic subtest: pipe-D-plane-0
[439.709303] Dynamic subtest pipe-D-plane-0: FAIL (0.151s)
[439.719331] Starting dynamic subtest: pipe-D-plane-1
[440.151707] Dynamic subtest pipe-D-plane-1: FAIL (0.593s)
[440.162897] Starting dynamic subtest: pipe-D-plane-2
[440.577694] Dynamic subtest pipe-D-plane-2: FAIL (0.534s)
[440.705687] Subtest plane-lut1d: FAIL (7.053s)
[441.501768] [099/133] (580s left) kms_pm_rpm (modeset-lpsp-stress)
[443.137403] Starting subtest: modeset-lpsp-stress
[459.388310] Subtest modeset-lpsp-stress: SUCCESS (16.251s)
[459.483523] [100/133] (562s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[460.037554] Starting subtest: syncobj-timeline-invalid-flags
[460.040355] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[461.050966] [101/133] (560s left) syncobj_wait (single-wait-all-submitted)
[461.132251] Starting subtest: single-wait-all-submitted
[461.238889] Subtest single-wait-all-submitted: SUCCESS (0.105s)
[461.317448] [102/133] (560s left) i915_hangman (detector)
[461.386062] Starting subtest: detector
[461.388367] Starting dynamic subtest: rcs0
[472.968899] Dynamic subtest rcs0: SUCCESS (11.580s)
[472.981731] Starting dynamic subtest: bcs0
[484.938252] Dynamic subtest bcs0: SUCCESS (11.966s)
[484.950478] Starting dynamic subtest: vcs0
[496.906984] Dynamic subtest vcs0: SUCCESS (11.965s)
[496.918978] Starting dynamic subtest: vcs1
[507.913350] Dynamic subtest vcs1: SUCCESS (11.003s)
[507.924794] Starting dynamic subtest: vecs0
[518.921700] Dynamic subtest vecs0: SUCCESS (11.005s)
[518.933621] Starting dynamic subtest: ccs0
[530.953661] Dynamic subtest ccs0: SUCCESS (12.029s)
[530.956733] Subtest detector: SUCCESS (69.565s)
[531.096726] [103/133] (490s left) gem_eio (reset-stress)
[531.280125] Starting subtest: reset-stress
[531.282500] Starting dynamic subtest: render
[535.291590] Dynamic subtest render: SUCCESS (4.007s)
[535.306765] Starting dynamic subtest: bsd1
[553.422353] Dynamic subtest bsd1: SUCCESS (18.131s)
[553.437825] Starting dynamic subtest: bsd2
[571.434460] Dynamic subtest bsd2: SUCCESS (18.010s)
[571.451025] Starting dynamic subtest: blt
[571.455156] Dynamic subtest blt: SKIP (0.000s)
[571.464841] Starting dynamic subtest: vebox
[589.407598] Dynamic subtest vebox: SUCCESS (17.969s)
[589.410160] Subtest reset-stress: SUCCESS (58.124s)
[591.070428] [104/133] (430s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[591.190287] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[591.194559] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[591.287350] [105/133] (430s left) gem_create (create-size-update)
[591.331049] Starting subtest: create-size-update
[591.333218] Subtest create-size-update: SUCCESS (0.000s)
[591.531505] [106/133] (430s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[591.631542] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[591.635989] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[591.726515] [107/133] (430s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[591.809888] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[591.812137] Starting dynamic subtest: pipe-A-eDP-1
[591.834751] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[591.846801] Starting dynamic subtest: pipe-B-eDP-1
[591.868307] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[591.881003] Starting dynamic subtest: pipe-C-eDP-1
[591.902901] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[591.912513] Starting dynamic subtest: pipe-D-eDP-1
[591.934108] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[591.938084] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[594.019630] [108/133] (428s left) api_intel_bb (blit-noreloc-purge-cache)
[594.075982] Starting subtest: blit-noreloc-purge-cache
[594.083887] Subtest blit-noreloc-purge-cache: SUCCESS (0.010s)
[594.209311] [109/133] (428s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[594.269059] Starting subtest: basic-batch-kernel-default-cmd
[594.273240] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[594.488182] [110/133] (427s left) gem_exec_balancer (smoke)
[594.549830] Starting subtest: smoke
[617.111362] Subtest smoke: SUCCESS (22.558s)
[617.701764] [111/133] (404s left) kms_properties (crtc-properties-legacy)
[617.798134] Starting subtest: crtc-properties-legacy
[617.800459] Starting dynamic subtest: pipe-A-eDP-1
[618.233289] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.434s)
[618.244195] Starting dynamic subtest: pipe-B-eDP-1
[619.358894] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.124s)
[619.369661] Starting dynamic subtest: pipe-C-eDP-1
[620.484557] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[620.495432] Starting dynamic subtest: pipe-D-eDP-1
[621.613924] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[621.616056] Subtest crtc-properties-legacy: SUCCESS (3.815s)
[622.420698] [112/133] (399s left) gem_basic (bad-close)
[622.493196] Starting subtest: bad-close
[622.497652] Subtest bad-close: SUCCESS (0.000s)
[622.595770] [113/133] (399s left) kms_psr (psr-primary-blt)
[622.684678] Starting subtest: psr-primary-blt
[622.686804] Starting dynamic subtest: eDP-1
[624.419262] Dynamic subtest eDP-1: SUCCESS (1.735s)
[624.421649] Subtest psr-primary-blt: SUCCESS (1.736s)
[625.282822] [114/133] (397s left) kms_cursor_crc (cursor-onscreen-512x170)
[625.583429] Starting subtest: cursor-onscreen-512x170
[625.588008] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[625.760359] [115/133] (396s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[625.869496] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[625.873579] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[625.966137] [116/133] (396s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[626.072822] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[626.075085] Starting dynamic subtest: pipe-A-eDP-1
[626.083602] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[626.094531] Starting dynamic subtest: pipe-B-eDP-1
[626.103177] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[626.113400] Starting dynamic subtest: pipe-C-eDP-1
[626.122018] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[626.131061] Starting dynamic subtest: pipe-D-eDP-1
[626.139287] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[626.143509] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[628.394731] [117/133] (394s left) kms_flip_event_leak (basic)
[628.497216] Starting subtest: basic
[628.499083] Starting dynamic subtest: pipe-A-eDP-1
[628.919905] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[628.932551] Starting dynamic subtest: pipe-B-eDP-1
[630.082015] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.163s)
[630.092318] Starting dynamic subtest: pipe-C-eDP-1
[631.178332] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.095s)
[631.191439] Starting dynamic subtest: pipe-D-eDP-1
[632.302136] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[632.304485] Subtest basic: SUCCESS (3.805s)
[633.175803] [118/133] (389s left) gem_userptr_blits (stress-purge)
[633.243413] Starting subtest: stress-purge
[638.637265] Subtest stress-purge: SUCCESS (5.397s)
[638.742214] [119/133] (384s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[638.866211] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[639.007625] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.142s)
[639.138011] [120/133] (383s left) kms_psr (fbc-psr2-cursor-blt)
[639.235900] Starting subtest: fbc-psr2-cursor-blt
[639.237704] Starting dynamic subtest: eDP-1
[639.241130] Dynamic subtest eDP-1: SKIP (0.000s)
[639.244370] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[639.317095] [121/133] (383s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[639.423572] Starting subtest: fbcpsr-2p-rte
[639.426988] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[639.528558] [122/133] (383s left) kms_flip (basic-plain-flip)
[639.620435] Starting subtest: basic-plain-flip
[639.622417] Starting dynamic subtest: A-eDP1
[641.271228] Dynamic subtest A-eDP1: SUCCESS (1.649s)
[641.282488] Starting dynamic subtest: B-eDP1
[642.889299] Dynamic subtest B-eDP1: SUCCESS (1.616s)
[642.901729] Starting dynamic subtest: C-eDP1
[644.523734] Dynamic subtest C-eDP1: SUCCESS (1.632s)
[644.542177] Starting dynamic subtest: D-eDP1
[646.134510] Dynamic subtest D-eDP1: SUCCESS (1.607s)
[646.140545] Subtest basic-plain-flip: SUCCESS (6.519s)
[647.297822] [123/133] (375s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[647.397791] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[647.402215] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[647.529629] [124/133] (375s left) kms_psr (psr2-sprite-render)
[647.626821] Starting subtest: psr2-sprite-render
[647.629067] Starting dynamic subtest: eDP-1
[649.306612] Dynamic subtest eDP-1: SUCCESS (1.680s)
[649.308973] Subtest psr2-sprite-render: SUCCESS (1.681s)
[650.173313] [125/133] (372s left) gem_exec_params (batch-first)
[650.238797] Starting subtest: batch-first
[650.245630] Subtest batch-first: SUCCESS (0.006s)
[650.374780] [126/133] (372s left) kms_flip (flip-vs-absolute-wf_vblank)
[650.458339] Starting subtest: flip-vs-absolute-wf_vblank
[650.460540] Starting dynamic subtest: A-eDP1
[659.410657] Dynamic subtest A-eDP1: SUCCESS (8.953s)
[659.420285] Starting dynamic subtest: B-eDP1
[668.348259] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[668.360148] Starting dynamic subtest: C-eDP1
[677.272348] Dynamic subtest C-eDP1: SUCCESS (8.917s)
[677.284469] Starting dynamic subtest: D-eDP1
[686.205684] Dynamic subtest D-eDP1: SUCCESS (8.935s)
[686.208229] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.751s)
[687.428561] [127/133] (335s left) syncobj_wait (single-wait-all-signaled)
[687.499127] Starting subtest: single-wait-all-signaled
[687.501502] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[687.573564] [128/133] (335s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[687.661900] Starting subtest: fbc-dirtyfb-ioctl
[687.664219] Starting dynamic subtest: A-eDP-1
[690.296560] Dynamic subtest A-eDP-1: SUCCESS (2.634s)
[690.299008] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.636s)
[691.113878] [129/133] (332s left) syncobj_basic (bad-flags-handle-to-fd)
[691.165973] Starting subtest: bad-flags-handle-to-fd
[691.167880] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[691.266333] [130/133] (331s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[691.324889] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[691.431299] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.105s)
[691.509247] [131/133] (331s left) kms_addfb_basic (bad-pitch-65536)
[691.565715] Starting subtest: bad-pitch-65536
[691.567841] Subtest bad-pitch-65536: SUCCESS (0.000s)
[691.698631] [132/133] (331s left) prime_mmap (test_aperture_limit)
[691.758200] Starting subtest: test_aperture_limit
[691.760403] Starting dynamic subtest: test_aperture_limit-smem
[691.768184] Dynamic subtest test_aperture_limit-smem: SKIP (0.010s)
[691.771977] Subtest test_aperture_limit: SKIP (0.010s)
[691.839776] [133/133] (331s left) kms_joiner (basic-force-ultra-joiner)
[691.934323] Starting subtest: basic-force-ultra-joiner
[691.938594] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[691.997046] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: i915_pm_rps
results: parsing output: 2/ for test: gem_mmap_offset
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: drm_buddy
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_addfb_basic
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_chamelium_frames
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_spin_batch
results: parsing output: 15/ for test: kms_atomic
results: parsing output: 16/ for test: gem_exec_schedule
results: parsing output: 17/ for test: vgem_basic
results: parsing output: 18/ for test: gem_exec_suspend
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_universal_plane
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_lease
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_properties
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: kms_vblank
results: parsing output: 27/ for test: kms_cursor_crc
results: parsing output: 28/ for test: kms_plane
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: gem_sync
results: parsing output: 32/ for test: gem_exercise_blt
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: prime_busy
results: parsing output: 35/ for test: kms_dp_link_training
results: parsing output: 36/ for test: fbdev
results: parsing output: 37/ for test: kms_content_protection
results: parsing output: 38/ for test: kms_psr2_sf
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_psr2_sf
results: parsing output: 41/ for test: kms_pm_dc
results: parsing output: 42/ for test: api_intel_bb
results: parsing output: 43/ for test: gem_ctx_exec
results: parsing output: 44/ for test: gem_ctx_param
results: parsing output: 45/ for test: kms_ccs
results: parsing output: 46/ for test: gem_ctx_shared
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: perf_pmu
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: gem_create
results: parsing output: 60/ for test: kms_vblank
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_balancer
results: parsing output: 66/ for test: kms_pipe_crc_basic
results: parsing output: 67/ for test: gen9_exec_parse
results: parsing output: 68/ for test: kms_plane_lowres
results: parsing output: 69/ for test: kms_plane
results: parsing output: 70/ for test: gem_fence_thrash
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: kms_async_flips
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: perf
results: parsing output: 77/ for test: gem_exec_schedule
results: parsing output: 78/ for test: i915_query
results: parsing output: 79/ for test: gem_pxp
results: parsing output: 80/ for test: gem_exec_params
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_rotation_crc
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: perf_pmu
results: parsing output: 85/ for test: kms_getfb
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: gem_render_copy
results: parsing output: 89/ for test: kms_cursor_crc
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: prime_vgem
results: parsing output: 94/ for test: gem_exec_suspend
results: parsing output: 95/ for test: i915_drm_fdinfo
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_color_pipeline
results: parsing output: 98/ for test: kms_pm_rpm
results: parsing output: 99/ for test: gem_exec_fence
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: i915_hangman
results: parsing output: 102/ for test: gem_eio
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_create
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: gem_exec_flush
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: kms_properties
results: parsing output: 111/ for test: gem_basic
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: kms_cursor_crc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_ccs
results: parsing output: 116/ for test: kms_flip_event_leak
results: parsing output: 117/ for test: gem_userptr_blits
results: parsing output: 118/ for test: gem_pxp
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: kms_dirtyfb
results: parsing output: 128/ for test: syncobj_basic
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: kms_addfb_basic
results: parsing output: 131/ for test: prime_mmap
results: parsing output: 132/ for test: kms_joiner
Done.