[63.562232] Initializing watchdogs
[63.562280]   /dev/watchdog0
[63.576809] [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]
[63.581345] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.584226] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.587575] [001/133] (960s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[63.705631] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[63.709613] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[63.788466] [002/133] (960s left) kms_flip (flip-vs-expired-vblank-interruptible)
[63.880350] Starting subtest: flip-vs-expired-vblank-interruptible
[63.882580] Starting dynamic subtest: A-eDP1
[72.536985] Dynamic subtest A-eDP1: SUCCESS (8.654s)
[72.546619] Starting dynamic subtest: B-eDP1
[81.210593] Dynamic subtest B-eDP1: SUCCESS (8.637s)
[81.223511] Starting dynamic subtest: C-eDP1
[89.849295] Dynamic subtest C-eDP1: SUCCESS (8.628s)
[89.858688] Starting dynamic subtest: D-eDP1
[98.475197] Dynamic subtest D-eDP1: SUCCESS (8.623s)
[98.477555] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.551s)
[99.597995] [003/133] (924s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[99.721899] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[100.524254] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.804s)
[100.682440] [004/133] (923s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[100.788448] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[100.792615] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[100.880872] [005/133] (923s left) api_intel_bb (crc32)
[100.931772] Starting subtest: crc32
[100.933797] Starting dynamic subtest: rcs0-smem0
[101.238956] Dynamic subtest rcs0-smem0: SUCCESS (0.307s)
[101.249621] Starting dynamic subtest: bcs0-smem0
[101.564666] Dynamic subtest bcs0-smem0: SUCCESS (0.324s)
[101.575059] Starting dynamic subtest: vcs0-smem0
[101.910462] Dynamic subtest vcs0-smem0: SUCCESS (0.346s)
[101.920707] Starting dynamic subtest: vcs1-smem0
[102.305975] Dynamic subtest vcs1-smem0: SUCCESS (0.392s)
[102.316829] Starting dynamic subtest: vecs0-smem0
[102.660324] Dynamic subtest vecs0-smem0: SUCCESS (0.353s)
[102.670799] Starting dynamic subtest: ccs0-smem0
[102.974902] Dynamic subtest ccs0-smem0: SUCCESS (0.314s)
[102.977909] Subtest crc32: SUCCESS (2.044s)
[103.079879] [006/133] (921s left) gem_userptr_blits (usage-restrictions)
[103.153632] Starting subtest: usage-restrictions
[103.155769] Subtest usage-restrictions: SUCCESS (0.000s)
[103.274727] [007/133] (921s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[103.374664] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[103.376620] Starting dynamic subtest: pipe-A-eDP-1
[119.354228] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.516s)
[119.364526] Starting dynamic subtest: pipe-B-eDP-1
[136.335097] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.426s)
[136.343977] Starting dynamic subtest: pipe-C-eDP-1
[153.301037] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.431s)
[153.311807] Starting dynamic subtest: pipe-D-eDP-1
[170.298866] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.343s)
[170.301672] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (8.720s)
[171.453347] [008/133] (852s left) kms_flip (2x-flip-vs-suspend-interruptible)
[171.562561] Starting subtest: 2x-flip-vs-suspend-interruptible
[171.574876] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.011s)
[171.652126] [009/133] (852s left) kms_sequence (get-busy)
[171.737651] Starting subtest: get-busy
[171.740141] Starting dynamic subtest: pipe-A-eDP-1
[173.682403] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.939s)
[173.691967] Starting dynamic subtest: pipe-B-eDP-1
[176.262051] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.577s)
[176.272331] Starting dynamic subtest: pipe-C-eDP-1
[178.877740] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.611s)
[178.887546] Starting dynamic subtest: pipe-D-eDP-1
[181.509967] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.627s)
[181.512811] Subtest get-busy: SUCCESS (9.757s)
[182.314664] [010/133] (842s left) kms_plane_scaling (planes-scaler-unity-scaling)
[182.408430] Starting subtest: planes-scaler-unity-scaling
[182.410835] Starting dynamic subtest: pipe-A
[182.472272] Dynamic subtest pipe-A: SUCCESS (0.064s)
[182.480212] Starting dynamic subtest: pipe-B
[183.647160] Dynamic subtest pipe-B: SUCCESS (1.173s)
[183.657033] Starting dynamic subtest: pipe-C
[184.761849] Dynamic subtest pipe-C: SUCCESS (1.113s)
[184.773710] Starting dynamic subtest: pipe-D
[185.886352] Dynamic subtest pipe-D: SUCCESS (1.123s)
[185.888690] Subtest planes-scaler-unity-scaling: SUCCESS (3.476s)
[187.044659] [011/133] (837s left) gem_exec_await (wide-all)
[187.272540] Starting subtest: wide-all
[211.010014] Subtest wide-all: SUCCESS (23.713s)
[211.200679] [FACT gem_exec_await (wide-all)] new: kernel.kmod_is_loaded.vgem: true
[211.205753] [012/133] (813s left) kms_plane (pixel-format-linear-modifier)
[211.360038] Starting subtest: pixel-format-linear-modifier
[211.386711] Starting dynamic subtest: pipe-A-plane-0
[214.953312] Dynamic subtest pipe-A-plane-0: SUCCESS (3.567s)
[214.963304] Starting dynamic subtest: pipe-A-plane-3
[216.631248] Dynamic subtest pipe-A-plane-3: SUCCESS (1.676s)
[216.641562] Starting dynamic subtest: pipe-A-plane-5
[216.842739] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[217.930504] Starting dynamic subtest: pipe-B-plane-0
[221.546397] Dynamic subtest pipe-B-plane-0: SUCCESS (3.622s)
[221.555103] Starting dynamic subtest: pipe-B-plane-3
[223.335782] Dynamic subtest pipe-B-plane-3: SUCCESS (1.788s)
[223.344603] Starting dynamic subtest: pipe-B-plane-5
[223.546762] Dynamic subtest pipe-B-plane-5: SUCCESS (0.211s)
[223.858800] Subtest pixel-format-linear-modifier: SUCCESS (12.495s)
[224.700487] [013/133] (799s left) syncobj_eventfd (binary-wait)
[224.762988] Starting subtest: binary-wait
[224.773282] Subtest binary-wait: SUCCESS (0.010s)
[224.854405] [014/133] (799s left) syncobj_timeline (etime-single-wait-submitted)
[224.910043] Starting subtest: etime-single-wait-submitted
[225.015477] Subtest etime-single-wait-submitted: SUCCESS (0.106s)
[225.105954] [015/133] (799s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[225.493008] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[226.313687] [016/133] (798s left) kms_properties (connector-properties-atomic)
[226.403482] Starting subtest: connector-properties-atomic
[226.405824] Starting dynamic subtest: pipe-A-eDP-1
[234.310937] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.900s)
[234.321470] Starting dynamic subtest: pipe-None-DP-1
[234.326789] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[234.335544] Starting dynamic subtest: pipe-None-HDMI-A-1
[234.340072] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[234.348535] Starting dynamic subtest: pipe-None-DP-2
[234.353193] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[234.362073] Starting dynamic subtest: pipe-None-HDMI-A-2
[234.367503] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[234.376519] Starting dynamic subtest: pipe-None-DP-3
[234.381913] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[234.390869] Starting dynamic subtest: pipe-None-DP-4
[234.396926] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[234.399919] Subtest connector-properties-atomic: SUCCESS (7.910s)
[235.182855] [017/133] (789s left) gem_exec_balancer (hang)
[235.445766] Starting subtest: hang
[237.144781] Subtest hang: SUCCESS (1.700s)
[237.261584] [018/133] (787s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[237.392298] Starting subtest: cursorB-vs-flipB-varying-size
[237.396864] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[237.539026] [019/133] (787s left) kms_addfb_basic (bad-pitch-256)
[237.593576] Starting subtest: bad-pitch-256
[237.595901] Subtest bad-pitch-256: SUCCESS (0.000s)
[237.739540] [020/133] (787s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[237.861289] Starting subtest: cursorB-vs-flipA-atomic-transitions
[237.866026] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[238.004721] [021/133] (786s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[238.100237] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[238.102829] Starting dynamic subtest: pipe-A
[238.438066] Dynamic subtest pipe-A: SUCCESS (0.340s)
[238.446899] Starting dynamic subtest: pipe-B
[239.901650] Dynamic subtest pipe-B: SUCCESS (1.463s)
[239.910550] Starting dynamic subtest: pipe-C
[241.346785] Dynamic subtest pipe-C: SUCCESS (1.444s)
[241.356827] Starting dynamic subtest: pipe-D
[242.788160] Dynamic subtest pipe-D: SUCCESS (1.440s)
[242.790939] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.688s)
[243.912511] [022/133] (781s left) vgem_basic (bad-handle)
[243.964908] Starting subtest: bad-handle
[243.967878] Subtest bad-handle: SUCCESS (0.000s)
[244.024928] [023/133] (780s left) gem_ctx_persistence (legacy-engines-persistence)
[244.081917] Starting subtest: legacy-engines-persistence
[244.084792] Starting dynamic subtest: render
[244.895520] Dynamic subtest render: SUCCESS (0.812s)
[244.911034] Starting dynamic subtest: bsd1
[245.641673] Dynamic subtest bsd1: SUCCESS (0.743s)
[245.654240] Starting dynamic subtest: bsd2
[246.435674] Dynamic subtest bsd2: SUCCESS (0.791s)
[246.448101] Starting dynamic subtest: blt
[247.206358] Dynamic subtest blt: SUCCESS (0.768s)
[247.218894] Starting dynamic subtest: vebox
[247.971464] Dynamic subtest vebox: SUCCESS (0.762s)
[247.974302] Subtest legacy-engines-persistence: SUCCESS (3.889s)
[248.068350] [024/133] (776s left) syncobj_wait (multi-wait-for-submit-submitted)
[248.127966] Starting subtest: multi-wait-for-submit-submitted
[248.230038] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s)
[248.356438] [025/133] (776s left) i915_query (query-topology-matches-eu-total)
[248.410877] Starting subtest: query-topology-matches-eu-total
[248.413854] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[248.553568] [026/133] (776s left) kms_pm_rpm (cursor-dpms)
[250.196027] Starting subtest: cursor-dpms
[250.201555] Subtest cursor-dpms: SKIP (0.000s)
[250.843937] [027/133] (774s left) gem_close_race (basic-process)
[250.906945] Starting subtest: basic-process
[250.962611] Subtest basic-process: SUCCESS (0.055s)
[251.147717] [028/133] (774s left) gem_gpgpu_fill (basic)
[251.207037] Starting subtest: basic
[251.209737] Starting dynamic subtest: smem
[251.212022] Dynamic subtest smem: SUCCESS (0.007s)
[251.215035] Subtest basic: SUCCESS (0.008s)
[251.346775] [029/133] (774s left) gem_userptr_blits (forbidden-operations)
[251.406615] Starting subtest: forbidden-operations
[251.411272] Subtest forbidden-operations: SKIP (0.000s)
[251.532771] [030/133] (773s left) gem_exec_reloc (basic-wc-active)
[251.599820] Subtest basic-wc-active: SKIP (0.000s)
[251.675424] [031/133] (773s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[251.772785] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[251.775527] Starting dynamic subtest: pipe-A-eDP-1
[251.798014] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.025s)
[251.808430] Starting dynamic subtest: pipe-B-eDP-1
[251.830740] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[251.841049] Starting dynamic subtest: pipe-C-eDP-1
[251.863803] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[251.873343] Starting dynamic subtest: pipe-D-eDP-1
[251.895958] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[251.898333] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.095s)
[254.109682] [032/133] (771s left) prime_busy (after-wait)
[254.173221] Starting subtest: after-wait
[254.175912] Starting dynamic subtest: rcs0
[254.199851] Dynamic subtest rcs0: SUCCESS (0.024s)
[254.209374] Starting dynamic subtest: bcs0
[254.240094] Dynamic subtest bcs0: SUCCESS (0.039s)
[254.250193] Starting dynamic subtest: vcs0
[254.282877] Dynamic subtest vcs0: SUCCESS (0.042s)
[254.292710] Starting dynamic subtest: vcs1
[254.324405] Dynamic subtest vcs1: SUCCESS (0.041s)
[254.334845] Starting dynamic subtest: vecs0
[254.368031] Dynamic subtest vecs0: SUCCESS (0.043s)
[254.378062] Starting dynamic subtest: ccs0
[254.410569] Dynamic subtest ccs0: SUCCESS (0.042s)
[254.518961] Subtest after-wait: SUCCESS (0.345s)
[254.609822] [033/133] (770s left) gem_eio (throttle)
[254.804917] Starting subtest: throttle
[255.084361] Subtest throttle: SUCCESS (0.277s)
[255.196237] [034/133] (770s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[255.290473] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[255.295498] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[255.376259] [035/133] (770s left) gem_exec_fence (nb-await)
[255.557349] Starting subtest: nb-await
[255.560035] Starting dynamic subtest: rcs0
[255.627929] Dynamic subtest rcs0: SUCCESS (0.070s)
[255.644494] Starting dynamic subtest: bcs0
[255.686798] Dynamic subtest bcs0: SUCCESS (0.057s)
[255.699094] Starting dynamic subtest: vcs0
[255.744412] Dynamic subtest vcs0: SUCCESS (0.056s)
[255.759482] Starting dynamic subtest: vcs1
[255.804099] Dynamic subtest vcs1: SUCCESS (0.058s)
[255.816060] Starting dynamic subtest: vecs0
[255.862719] Dynamic subtest vecs0: SUCCESS (0.057s)
[255.875252] Starting dynamic subtest: ccs0
[255.921483] Dynamic subtest ccs0: SUCCESS (0.056s)
[255.924594] Subtest nb-await: SUCCESS (0.365s)
[257.214713] [036/133] (768s left) kms_psr (psr2-cursor-render)
[257.320857] Starting subtest: psr2-cursor-render
[257.323787] Starting dynamic subtest: eDP-1
[258.995914] Dynamic subtest eDP-1: SUCCESS (1.673s)
[258.998897] Subtest psr2-cursor-render: SUCCESS (1.674s)
[259.884689] [037/133] (765s left) gem_userptr_blits (sync-unmap-after-close)
[259.947734] Starting subtest: sync-unmap-after-close
[259.957474] Subtest sync-unmap-after-close: SUCCESS (0.011s)
[260.088631] [038/133] (765s left) kms_chamelium_color (degamma)
[260.189041] Subtest degamma: SKIP (0.000s)
[260.234615] [039/133] (765s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[260.330769] Starting subtest: x-tiled-16bpp-rotate-180
[261.654603] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.326s)
[261.777146] [040/133] (764s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[261.826330] Starting subtest: multi-wait-available-unsubmitted-signaled
[261.828898] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[261.929817] [041/133] (763s left) gem_workarounds (basic-read)
[261.989073] Starting subtest: basic-read
[262.000335] Subtest basic-read: SUCCESS (0.011s)
[262.111623] [042/133] (763s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[262.216538] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[262.220796] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[262.297534] [043/133] (763s left) gem_exec_whisper (basic-fds-priority)
[262.360564] Starting subtest: basic-fds-priority
[288.998156] Subtest basic-fds-priority: SUCCESS (26.621s)
[289.179395] [044/133] (736s left) kms_cursor_crc (cursor-onscreen-max-size)
[289.552077] Starting subtest: cursor-onscreen-max-size
[289.560043] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[289.669323] [045/133] (736s left) gem_mmap_offset (bad-object)
[289.724967] Starting subtest: bad-object
[289.727817] Subtest bad-object: SUCCESS (0.001s)
[289.828238] [046/133] (736s left) kms_psr (fbc-psr-sprite-plane-onoff)
[289.919870] Starting subtest: fbc-psr-sprite-plane-onoff
[289.922293] Starting dynamic subtest: eDP-1
[289.926872] Dynamic subtest eDP-1: SKIP (0.000s)
[289.931824] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[289.997413] [047/133] (736s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[290.089408] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[291.148427] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.058s)
[291.270268] [048/133] (734s left) kms_cursor_crc (cursor-sliding-256x256)
[291.534425] Starting subtest: cursor-sliding-256x256
[291.540857] Starting dynamic subtest: pipe-A-eDP-1
[294.850450] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.310s)
[294.877858] Starting dynamic subtest: pipe-D-eDP-1
[298.902507] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.031s)
[298.904969] Subtest cursor-sliding-256x256: SUCCESS (7.367s)
[299.731652] [049/133] (726s left) gem_eio (context-create)
[299.952352] Starting subtest: context-create
[300.283744] Subtest context-create: SUCCESS (0.330s)
[300.398977] [050/133] (725s left) sw_sync (sync_merge_invalid)
[300.438468] Starting subtest: sync_merge_invalid
[300.455917] Subtest sync_merge_invalid: SUCCESS (0.017s)
[300.529797] [051/133] (725s left) gem_spin_batch (spin-all-new)
[300.582962] Starting subtest: spin-all-new
[300.598877] Subtest spin-all-new: SUCCESS (0.016s)
[302.162717] [052/133] (724s left) gem_exec_reloc (basic-gtt)
[302.218058] Subtest basic-gtt: SKIP (0.000s)
[302.292728] [053/133] (724s left) gem_exec_schedule (pi-common)
[302.473736] Starting subtest: pi-common
[302.476084] Starting dynamic subtest: rcs0
[302.533080] Dynamic subtest rcs0: SUCCESS (0.062s)
[302.545761] Starting dynamic subtest: bcs0
[302.589178] Dynamic subtest bcs0: SUCCESS (0.055s)
[302.601198] Starting dynamic subtest: vcs0
[302.634456] Dynamic subtest vcs0: SUCCESS (0.044s)
[302.645157] Starting dynamic subtest: vcs1
[302.672063] Dynamic subtest vcs1: SUCCESS (0.037s)
[302.685869] Starting dynamic subtest: vecs0
[302.703922] Dynamic subtest vecs0: SUCCESS (0.031s)
[302.717289] Starting dynamic subtest: ccs0
[302.734150] Dynamic subtest ccs0: SUCCESS (0.030s)
[302.736713] Subtest pi-common: SUCCESS (0.265s)
[302.988041] [054/133] (723s left) gem_exec_fence (syncobj-timeline-unused-fence)
[303.530590] Starting subtest: syncobj-timeline-unused-fence
[303.540557] Subtest syncobj-timeline-unused-fence: SUCCESS (0.015s)
[305.186451] [055/133] (721s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[305.292753] Starting subtest: plane-ctm3x4-lut1d
[305.429101] Starting dynamic subtest: pipe-A-plane-0
[305.629362] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[305.639944] Starting dynamic subtest: pipe-A-plane-1
[305.787336] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[305.798059] Starting dynamic subtest: pipe-A-plane-2
[305.951297] Dynamic subtest pipe-A-plane-2: SUCCESS (0.165s)
[307.154277] Starting dynamic subtest: pipe-B-plane-0
[307.312046] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[307.322921] Starting dynamic subtest: pipe-B-plane-1
[307.479501] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[307.492922] Starting dynamic subtest: pipe-B-plane-2
[307.635279] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[308.865154] Starting dynamic subtest: pipe-C-plane-0
[309.001215] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[309.011698] Starting dynamic subtest: pipe-C-plane-1
[309.168437] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[309.179075] Starting dynamic subtest: pipe-C-plane-2
[309.334855] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[310.496292] Starting dynamic subtest: pipe-D-plane-0
[310.643575] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[310.654452] Starting dynamic subtest: pipe-D-plane-1
[310.788752] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[310.799676] Starting dynamic subtest: pipe-D-plane-2
[310.954834] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[311.244053] Subtest plane-ctm3x4-lut1d: SUCCESS (5.950s)
[312.060383] [056/133] (714s left) kms_cursor_legacy (torture-move)
[312.198478] Starting subtest: torture-move
[312.201031] Starting dynamic subtest: pipe-A
[318.162282] Dynamic subtest pipe-A: SUCCESS (5.960s)
[318.175029] Starting dynamic subtest: pipe-B
[324.859823] Dynamic subtest pipe-B: SUCCESS (6.695s)
[324.878529] Starting dynamic subtest: pipe-C
[331.380342] Dynamic subtest pipe-C: SUCCESS (6.518s)
[331.393050] Starting dynamic subtest: pipe-D
[337.975839] Dynamic subtest pipe-D: SUCCESS (6.592s)
[337.988525] Starting dynamic subtest: all-pipes
[346.318061] Dynamic subtest all-pipes: SUCCESS (8.337s)
[346.321035] Subtest torture-move: SUCCESS (34.112s)
[346.480295] [057/133] (680s left) kms_getfb (getfb2-handle-closed)
[346.580755] Starting subtest: getfb2-handle-closed
[346.583395] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[346.686297] [058/133] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[346.780450] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[346.784820] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[346.871789] [059/133] (679s left) kms_prime (basic-crc-vgem)
[346.953060] Starting subtest: basic-crc-vgem
[346.955787] Starting dynamic subtest: second-to-first
[348.065671] Dynamic subtest second-to-first: SUCCESS (1.116s)
[348.067932] Subtest basic-crc-vgem: SUCCESS (1.118s)
[348.171691] [060/133] (678s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[348.290387] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[348.294940] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[348.388590] [061/133] (678s left) kms_vrr (flipline)
[348.475579] Starting subtest: flipline
[348.481891] Subtest flipline: SKIP (0.000s)
[348.574464] [062/133] (678s left) gem_userptr_blits (probe)
[348.636631] Starting subtest: probe
[348.714518] Subtest probe: SUCCESS (0.077s)
[348.818983] [063/133] (678s left) kms_psr (fbc-pr-cursor-plane-onoff)
[348.917576] Starting subtest: fbc-pr-cursor-plane-onoff
[348.922476] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[348.997498] [064/133] (677s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[349.103273] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[349.107300] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[350.216114] [065/133] (676s left) kms_3d (basic)
[350.271618] Starting subtest: basic
[352.282844] Subtest basic: SUCCESS (2.011s)
[352.768409] [066/133] (674s left) kms_lease (lease-invalid-connector)
[352.857700] Starting subtest: lease-invalid-connector
[352.860275] Starting dynamic subtest: pipe-A-eDP-1
[352.862870] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[352.874052] Starting dynamic subtest: pipe-B-eDP-1
[352.876900] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[352.887972] Starting dynamic subtest: pipe-C-eDP-1
[352.890550] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[352.901717] Starting dynamic subtest: pipe-D-eDP-1
[352.903874] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[352.905989] Subtest lease-invalid-connector: SUCCESS (0.004s)
[352.945954] [067/133] (674s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[353.039909] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[353.042439] Starting dynamic subtest: pipe-A-eDP-1
[353.104706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s)
[354.170483] Starting dynamic subtest: pipe-B-eDP-1
[354.530609] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.370s)
[354.828570] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.788s)
[355.645835] [068/133] (671s left) kms_pipe_stress (stress-xrgb8888-xtiled)
[355.737567] Starting subtest: stress-xrgb8888-xtiled
[361.727426] Subtest stress-xrgb8888-xtiled: SUCCESS (5.988s)
[361.786387] [069/133] (665s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[361.883553] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[361.887878] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[361.979948] [070/133] (665s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[362.097380] Starting subtest: 2x-flip-vs-cursor-atomic
[362.101254] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[362.243406] [071/133] (665s left) kms_cursor_crc (cursor-random-256x256)
[362.485038] Starting subtest: cursor-random-256x256
[362.490049] Starting dynamic subtest: pipe-A-eDP-1
[365.912686] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.421s)
[365.947017] Starting dynamic subtest: pipe-D-eDP-1
[370.046853] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.108s)
[370.049603] Subtest cursor-random-256x256: SUCCESS (7.560s)
[370.856456] [072/133] (656s left) gem_ppgtt (shrink-vs-evict-pinned)
[370.922670] Starting subtest: shrink-vs-evict-pinned
[378.161980] Subtest shrink-vs-evict-pinned: SUCCESS (7.184s)
[378.204204] [073/133] (649s left) syncobj_timeline (invalid-transfer-bad-pad)
[378.265037] Starting subtest: invalid-transfer-bad-pad
[378.267923] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[378.384792] [074/133] (649s left) gem_exec_balancer (sequential)
[378.442816] Starting subtest: sequential
[381.529386] Subtest sequential: SUCCESS (3.085s)
[381.859930] [075/133] (645s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[381.921822] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[381.981398] [076/133] (645s left) kms_flip (2x-flip-vs-panning-interruptible)
[382.079212] Starting subtest: 2x-flip-vs-panning-interruptible
[382.090839] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[382.171081] [077/133] (645s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[382.263852] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[382.268891] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[382.362311] [078/133] (645s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[382.479554] Starting subtest: cursor-vs-flip-toggle
[413.884637] Subtest cursor-vs-flip-toggle: SUCCESS (31.405s)
[414.742372] [079/133] (612s left) gem_flink_race (flink_name)
[414.795962] Starting subtest: flink_name
[420.233738] Subtest flink_name: SUCCESS (5.387s)
[420.321592] [080/133] (607s left) gem_mmap (bad-object)
[420.385631] Subtest bad-object: SKIP (0.000s)
[420.451839] [081/133] (607s left) gem_mmap_gtt (basic-small-copy-xy)
[420.507800] Subtest basic-small-copy-XY: SKIP (0.000s)
[420.566576] [082/133] (607s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[420.667639] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[420.672704] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[420.768532] [083/133] (607s left) kms_vblank (wait-busy)
[420.873791] Starting subtest: wait-busy
[420.876638] Starting dynamic subtest: pipe-A-eDP-1
[422.792888] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.917s)
[422.809449] Starting dynamic subtest: pipe-D-eDP-1
[425.380659] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.585s)
[425.383620] Subtest wait-busy: SUCCESS (4.509s)
[426.239823] [084/133] (601s left) gen7_exec_parse (batch-without-end)
[426.301055] Subtest batch-without-end: SKIP (0.000s)
[426.369686] [085/133] (601s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[426.461631] Starting subtest: 4-tiled-64bpp-rotate-90
[426.482074] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.020s)
[426.600468] [086/133] (601s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[426.684563] Starting subtest: y-tiled-64bpp-rotate-0
[426.689708] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[426.790957] [087/133] (601s left) kms_draw_crc (draw-method-render)
[426.897081] Starting subtest: draw-method-render
[426.899460] Starting dynamic subtest: xrgb8888-untiled
[427.193672] Dynamic subtest xrgb8888-untiled: SUCCESS (0.298s)
[427.203757] Starting dynamic subtest: rgb565-untiled
[427.438295] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[427.448565] Starting dynamic subtest: xrgb2101010-untiled
[427.674873] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.233s)
[427.684338] Starting dynamic subtest: xbgr16161616f-untiled
[427.926939] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.253s)
[427.937321] Starting dynamic subtest: xrgb8888-xtiled
[428.059945] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.131s)
[428.069915] Starting dynamic subtest: rgb565-xtiled
[428.181856] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[428.192306] Starting dynamic subtest: xrgb2101010-xtiled
[428.304438] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[428.314978] Starting dynamic subtest: xbgr16161616f-xtiled
[428.437566] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[428.447829] Starting dynamic subtest: xrgb8888-4tiled
[428.571237] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.132s)
[428.583619] Starting dynamic subtest: rgb565-4tiled
[428.681820] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[428.692382] Starting dynamic subtest: xrgb2101010-4tiled
[428.815380] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[428.826069] Starting dynamic subtest: xbgr16161616f-4tiled
[428.948388] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[428.950508] Subtest draw-method-render: SUCCESS (2.055s)
[429.045120] [088/133] (599s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[429.180283] Starting subtest: verify-pxp-stale-buf-optout-execution
[430.250073] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.070s)
[430.377325] [089/133] (597s left) gem_ringfill (engines-basic)
[436.070935] Starting subtest: engines-basic
[436.074057] Starting dynamic subtest: rcs0
[436.138041] Dynamic subtest rcs0: SUCCESS (0.066s)
[436.151675] Starting dynamic subtest: bcs0
[436.185510] Dynamic subtest bcs0: SUCCESS (0.045s)
[436.196853] Starting dynamic subtest: vcs0
[436.228970] Dynamic subtest vcs0: SUCCESS (0.042s)
[436.240079] Starting dynamic subtest: vcs1
[436.274797] Dynamic subtest vcs1: SUCCESS (0.043s)
[436.288947] Starting dynamic subtest: vecs0
[436.319021] Dynamic subtest vecs0: SUCCESS (0.042s)
[436.330192] Starting dynamic subtest: ccs0
[436.366923] Dynamic subtest ccs0: SUCCESS (0.046s)
[436.369762] Subtest engines-basic: SUCCESS (0.297s)
[436.447249] [090/133] (591s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[436.549720] Starting subtest: psr-2p-primscrn-cur-indfb-move
[436.554821] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[436.653634] [091/133] (591s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[436.758348] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[436.760700] Starting dynamic subtest: pipe-A-eDP-1
[447.768745] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.013s)
[447.780269] Starting dynamic subtest: pipe-B-eDP-1
[459.528084] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.755s)
[459.530475] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.771s)
[460.339705] [092/133] (567s left) gen9_exec_parse (unaligned-access)
[460.404958] Subtest unaligned-access: SKIP (0.000s)
[460.485644] [093/133] (567s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[460.590870] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[464.484988] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.899s)
[465.665279] [094/133] (562s left) kms_joiner (invalid-modeset-big-joiner)
[465.769439] Starting subtest: invalid-modeset-big-joiner
[465.774050] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[465.840941] [095/133] (562s left) kms_plane_cursor (primary)
[465.932367] Starting subtest: primary
[465.937066] Starting dynamic subtest: pipe-A-eDP-1-size-64
[468.605566] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.669s)
[468.629622] Starting dynamic subtest: pipe-A-eDP-1-size-128
[471.272280] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.650s)
[471.297756] Starting dynamic subtest: pipe-A-eDP-1-size-256
[473.895728] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.605s)
[474.211587] Starting dynamic subtest: pipe-B-eDP-1-size-64
[477.494292] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.295s)
[477.519061] Starting dynamic subtest: pipe-B-eDP-1-size-128
[480.094589] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.583s)
[480.120599] Starting dynamic subtest: pipe-B-eDP-1-size-256
[482.674848] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.561s)
[482.995144] Starting dynamic subtest: pipe-C-eDP-1-size-64
[485.957391] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.974s)
[485.984414] Starting dynamic subtest: pipe-C-eDP-1-size-128
[488.212072] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.236s)
[488.239949] Starting dynamic subtest: pipe-C-eDP-1-size-256
[490.446432] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.214s)
[490.766072] Starting dynamic subtest: pipe-D-eDP-1-size-64
[493.646233] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.894s)
[493.675542] Starting dynamic subtest: pipe-D-eDP-1-size-128
[495.858788] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[495.885926] Starting dynamic subtest: pipe-D-eDP-1-size-256
[498.081298] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.203s)
[498.389869] Subtest primary: SUCCESS (32.451s)
[499.235228] [096/133] (529s left) gem_busy (busy)
[499.311465] Starting subtest: busy
[499.314239] Starting dynamic subtest: all-engines
[499.347257] Dynamic subtest all-engines: SUCCESS (0.039s)
[499.358091] Starting dynamic subtest: rcs0
[501.098311] Dynamic subtest rcs0: SUCCESS (1.750s)
[501.110944] Starting dynamic subtest: bcs0
[501.139047] Dynamic subtest bcs0: SUCCESS (0.038s)
[501.149535] Starting dynamic subtest: vcs0
[501.180838] Dynamic subtest vcs0: SUCCESS (0.040s)
[501.191922] Starting dynamic subtest: vcs1
[503.080216] Dynamic subtest vcs1: SUCCESS (1.897s)
[503.092951] Starting dynamic subtest: vecs0
[505.190028] Dynamic subtest vecs0: SUCCESS (2.046s)
[505.203581] Starting dynamic subtest: ccs0
[507.113353] Dynamic subtest ccs0: SUCCESS (1.984s)
[507.115853] Subtest busy: SUCCESS (7.803s)
[507.479309] [097/133] (520s left) gem_exec_reloc (basic-wc-read)
[507.536364] Subtest basic-wc-read: SKIP (0.000s)
[507.610432] [098/133] (520s left) kms_flip (flip-vs-fences)
[507.709678] Starting subtest: flip-vs-fences
[507.715161] Subtest flip-vs-fences: SKIP (0.000s)
[507.810375] [099/133] (520s left) gem_mmap_gtt (basic-copy)
[507.870063] Subtest basic-copy: SKIP (0.000s)
[507.927872] [100/133] (520s left) gem_exec_schedule (u-semaphore-codependency)
[508.003353] Starting subtest: u-semaphore-codependency
[508.009834] Subtest u-semaphore-codependency: SUCCESS (0.015s)
[508.355404] [101/133] (520s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[508.450702] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[508.452654] Starting dynamic subtest: pipe-A-eDP-1
[508.593747] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.134s)
[508.616047] Starting dynamic subtest: pipe-B-eDP-1
[508.640850] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[508.651989] Starting dynamic subtest: pipe-C-eDP-1
[508.673947] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[508.684035] Starting dynamic subtest: pipe-D-eDP-1
[508.706814] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[508.709469] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.144s)
[510.828735] [102/133] (517s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[510.954987] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[510.959713] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[511.101547] [103/133] (517s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[511.191053] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[511.193372] Starting dynamic subtest: pipe-A-eDP-1
[511.218630] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[511.230074] Starting dynamic subtest: pipe-B-eDP-1
[511.256985] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[511.267309] Starting dynamic subtest: pipe-C-eDP-1
[511.294488] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[511.307041] Starting dynamic subtest: pipe-D-eDP-1
[511.335776] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[511.340947] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[513.452856] [104/133] (515s left) i915_pm_rc6_residency (rc6-idle)
[513.506700] Starting subtest: rc6-idle
[513.523021] Starting dynamic subtest: gt0-rcs0
[537.875813] Dynamic subtest gt0-rcs0: SUCCESS (24.349s)
[537.888944] Starting dynamic subtest: gt0-bcs0
[562.240481] Dynamic subtest gt0-bcs0: SUCCESS (24.358s)
[562.252969] Starting dynamic subtest: gt0-ccs0
[586.519699] Dynamic subtest gt0-ccs0: SUCCESS (24.272s)
[586.531929] Starting dynamic subtest: gt1-vcs0
[592.748733] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[592.763083] Starting dynamic subtest: gt1-vecs0
[598.919279] Dynamic subtest gt1-vecs0: SUCCESS (6.168s)
[598.922235] Subtest rc6-idle: SUCCESS (85.347s)
[598.966448] [105/133] (429s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[599.076947] Starting subtest: primary-y-tiled-reflect-x-0
[599.136987] Subtest primary-y-tiled-reflect-x-0: SKIP (0.060s)
[599.245063] [106/133] (429s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[599.360425] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[599.365803] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[599.508321] [107/133] (429s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[599.608817] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[599.613833] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[599.713563] [108/133] (429s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[599.810969] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[599.815871] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[599.912071] [109/133] (429s left) kms_psr (pr-cursor-plane-move)
[600.008757] Starting subtest: pr-cursor-plane-move
[600.012769] Subtest pr-cursor-plane-move: SKIP (0.000s)
[600.083167] [110/133] (428s left) gem_create (create-ext-set-pat)
[600.241081] Starting subtest: create-ext-set-pat
[600.243718] Subtest create-ext-set-pat: SUCCESS (0.001s)
[600.337229] [111/133] (428s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[600.434977] Starting subtest: psr-rgb565-draw-mmap-wc
[607.190250] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.756s)
[607.292088] [112/133] (421s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[607.393585] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[611.046974] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.654s)
[612.259601] [113/133] (416s left) kms_psr (psr-suspend)
[612.354535] Starting subtest: psr-suspend
[612.356782] Starting dynamic subtest: eDP-1
[630.577261] Dynamic subtest eDP-1: SUCCESS (2.967s)
[630.579523] Subtest psr-suspend: SUCCESS (2.969s)
[631.384093] [114/133] (397s left) kms_atomic_transition (plane-all-transition-nonblocking)
[631.488462] Starting subtest: plane-all-transition-nonblocking
[631.490663] Starting dynamic subtest: pipe-A-eDP-1
[633.989462] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[634.299596] Starting dynamic subtest: pipe-B-eDP-1
[637.501224] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.204s)
[637.797329] Subtest plane-all-transition-nonblocking: SUCCESS (6.296s)
[638.593980] [115/133] (390s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[638.701780] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[638.705965] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[638.808051] [116/133] (390s left) i915_drm_fdinfo (virtual-busy-hang-all)
[638.848462] Starting subtest: virtual-busy-hang-all
[638.852770] Subtest virtual-busy-hang-all: SKIP (0.000s)
[638.911627] [117/133] (390s left) syncobj_timeline (single-wait-all-available-submitted)
[638.972537] Starting subtest: single-wait-all-available-submitted
[638.974845] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[639.089980] [118/133] (390s left) gem_exec_flush (basic-uc-pro-default)
[639.150586] Starting subtest: basic-uc-pro-default
[644.649929] Subtest basic-uc-pro-default: SUCCESS (5.493s)
[644.856047] [119/133] (384s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[644.951736] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[644.955857] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[645.047918] [120/133] (384s left) kms_prop_blob (blob-prop-lifetime)
[645.100792] Starting subtest: blob-prop-lifetime
[645.102742] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[645.203486] [121/133] (384s left) perf_pmu (most-busy-idle-check-all)
[645.241128] Starting subtest: most-busy-idle-check-all
[645.243582] Starting dynamic subtest: rcs0
[645.953988] Dynamic subtest rcs0: FAIL (0.649s)
[645.968245] Starting dynamic subtest: bcs0
[648.314414] Dynamic subtest bcs0: SUCCESS (2.420s)
[648.327412] Starting dynamic subtest: vcs0
[650.363007] Dynamic subtest vcs0: SUCCESS (2.043s)
[650.376913] Starting dynamic subtest: vcs1
[652.347025] Dynamic subtest vcs1: SUCCESS (1.978s)
[652.360197] Starting dynamic subtest: vecs0
[654.330010] Dynamic subtest vecs0: SUCCESS (1.978s)
[654.342892] Starting dynamic subtest: ccs0
[656.378597] Dynamic subtest ccs0: SUCCESS (2.044s)
[656.381579] Subtest most-busy-idle-check-all: FAIL (11.130s)
[656.477136] [122/133] (373s left) gem_ctx_freq (sysfs)
[656.548841] Starting subtest: sysfs
[656.551185] Starting dynamic subtest: gt0
[662.066566] Dynamic subtest gt0: SUCCESS (5.513s)
[662.078369] Starting dynamic subtest: gt1
[703.353337] Dynamic subtest gt1: SUCCESS (41.283s)
[703.356310] Subtest sysfs: SUCCESS (46.800s)
[703.497949] [123/133] (326s left) kms_getfb (getfb-handle-closed)
[703.593981] Starting subtest: getfb-handle-closed
[703.596251] Subtest getfb-handle-closed: SUCCESS (0.000s)
[703.679863] [124/133] (325s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[703.785643] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[703.789744] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[703.894355] [125/133] (325s left) api_intel_bb (object-reloc-keep-cache)
[703.945757] Starting subtest: object-reloc-keep-cache
[703.950362] Subtest object-reloc-keep-cache: SKIP (0.003s)
[704.041712] [126/133] (325s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[704.143442] Starting subtest: 4-tiled-16bpp-rotate-0
[705.422838] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.279s)
[705.557461] [127/133] (324s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[705.652215] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[712.656474] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.005s)
[712.771446] [128/133] (317s left) i915_suspend (basic-s3-without-i915)
[712.809469] Starting subtest: basic-s3-without-i915
[714.616525] Subtest basic-s3-without-i915: SKIP (1.800s)
[716.233813] [129/133] (313s left) dmabuf_sync_file (export-basic)
[716.287015] Starting subtest: export-basic
[716.289287] Subtest export-basic: SUCCESS (0.001s)
[716.342047] [130/133] (313s left) kms_content_protection (legacy-hdcp14)
[716.503997] Starting subtest: legacy-hdcp14
[716.508650] Subtest legacy-hdcp14: SKIP (0.000s)
[716.624931] [131/133] (313s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[716.723726] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[723.759385] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.036s)
[723.895463] [132/133] (306s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[724.004720] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[724.009136] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[724.108104] [133/133] (305s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[724.191750] Starting subtest: fbc-pr-sprite-mmap-cpu
[724.196198] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[724.265561] Closing watchdogs
results: parsing output: 0/ for test: kms_flip_scaled_crc
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: api_intel_bb
results: parsing output: 5/ for test: gem_userptr_blits
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: kms_sequence
results: parsing output: 9/ for test: kms_plane_scaling
results: parsing output: 10/ for test: gem_exec_await
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: syncobj_eventfd
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: kms_chamelium_hpd
results: parsing output: 15/ for test: kms_properties
results: parsing output: 16/ for test: gem_exec_balancer
results: parsing output: 17/ for test: kms_cursor_legacy
results: parsing output: 18/ for test: kms_addfb_basic
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: vgem_basic
results: parsing output: 22/ for test: gem_ctx_persistence
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: i915_query
results: parsing output: 25/ for test: kms_pm_rpm
results: parsing output: 26/ for test: gem_close_race
results: parsing output: 27/ for test: gem_gpgpu_fill
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: gem_exec_reloc
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: prime_busy
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_fence
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: gem_userptr_blits
results: parsing output: 37/ for test: kms_chamelium_color
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: gem_workarounds
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_exec_whisper
results: parsing output: 43/ for test: kms_cursor_crc
results: parsing output: 44/ for test: gem_mmap_offset
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: gem_eio
results: parsing output: 49/ for test: sw_sync
results: parsing output: 50/ for test: gem_spin_batch
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: gem_exec_schedule
results: parsing output: 53/ for test: gem_exec_fence
results: parsing output: 54/ for test: kms_color_pipeline
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_getfb
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_prime
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_vrr
results: parsing output: 61/ for test: gem_userptr_blits
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_3d
results: parsing output: 65/ for test: kms_lease
results: parsing output: 66/ for test: kms_atomic_transition
results: parsing output: 67/ for test: kms_pipe_stress
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: gem_ppgtt
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: gem_exec_balancer
results: parsing output: 74/ for test: gem_mmap_gtt
results: parsing output: 75/ for test: kms_flip
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: gem_flink_race
results: parsing output: 79/ for test: gem_mmap
results: parsing output: 80/ for test: gem_mmap_gtt
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_vblank
results: parsing output: 83/ for test: gen7_exec_parse
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_draw_crc
results: parsing output: 87/ for test: gem_pxp
results: parsing output: 88/ for test: gem_ringfill
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_psr2_sf
results: parsing output: 91/ for test: gen9_exec_parse
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_joiner
results: parsing output: 94/ for test: kms_plane_cursor
results: parsing output: 95/ for test: gem_busy
results: parsing output: 96/ for test: gem_exec_reloc
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: gem_mmap_gtt
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: kms_ccs
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: i915_pm_rc6_residency
results: parsing output: 104/ for test: kms_rotation_crc
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: gem_create
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: kms_atomic_transition
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: i915_drm_fdinfo
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: gem_exec_flush
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_prop_blob
results: parsing output: 120/ for test: perf_pmu
results: parsing output: 121/ for test: gem_ctx_freq
results: parsing output: 122/ for test: kms_getfb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: api_intel_bb
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: i915_suspend
results: parsing output: 128/ for test: dmabuf_sync_file
results: parsing output: 129/ for test: kms_content_protection
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_psr
Done.