[57.190822] Initializing watchdogs
[57.190868]   /dev/watchdog0
[57.205507] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.209487] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.211456] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.214806] [001/133] (960s left) syncobj_wait (multi-wait-submitted-signaled)
[57.297928] Starting subtest: multi-wait-submitted-signaled
[57.300294] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[57.425499] [002/133] (960s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[57.509789] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[57.513542] Starting dynamic subtest: pipe-A-eDP-1
[57.535154] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.544221] Starting dynamic subtest: pipe-B-eDP-1
[57.565122] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.571617] Starting dynamic subtest: pipe-C-eDP-1
[57.592215] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.598712] Starting dynamic subtest: pipe-D-eDP-1
[57.620116] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.624188] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[59.840795] [003/133] (957s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[59.917075] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[59.921629] Starting dynamic subtest: pipe-A
[60.251240] Dynamic subtest pipe-A: SUCCESS (0.339s)
[60.258544] Starting dynamic subtest: pipe-B
[61.620229] Dynamic subtest pipe-B: SUCCESS (1.368s)
[61.629090] Starting dynamic subtest: pipe-C
[63.041896] Dynamic subtest pipe-C: SUCCESS (1.421s)
[63.050298] Starting dynamic subtest: pipe-D
[64.471753] Dynamic subtest pipe-D: SUCCESS (1.430s)
[64.474045] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.560s)
[65.654426] [004/133] (952s left) gem_ccs (block-copy-compressed)
[65.732464] Starting subtest: block-copy-compressed
[65.737295] Subtest block-copy-compressed: SKIP (0.000s)
[65.851351] [005/133] (952s left) i915_query (query-topology-coherent-slice-mask)
[65.909968] Starting subtest: query-topology-coherent-slice-mask
[65.914517] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[66.013748] [006/133] (951s left) syncobj_timeline (invalid-transfer-bad-pad)
[66.068939] Starting subtest: invalid-transfer-bad-pad
[66.071240] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[66.165364] [007/133] (951s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[66.241756] Starting subtest: primary-x-tiled-reflect-x-180
[73.288225] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.049s)
[74.495530] [008/133] (943s left) kms_vrr (max-min)
[74.578698] Starting subtest: max-min
[74.584846] Subtest max-min: SKIP (0.000s)
[74.656794] [009/133] (943s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[74.749353] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[81.311860] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.569s)
[81.455275] [010/133] (936s left) kms_psr (pr-cursor-mmap-gtt)
[81.534033] Starting subtest: pr-cursor-mmap-gtt
[81.538474] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[81.673621] [011/133] (936s left) gem_sync (basic-all)
[81.746533] Starting subtest: basic-all
[84.989875] Subtest basic-all: SUCCESS (3.253s)
[85.183243] [012/133] (932s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[85.256323] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[85.260863] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[85.452267] [013/133] (932s left) kms_atomic_interruptible (atomic-setmode)
[85.535995] Starting subtest: atomic-setmode
[85.538216] Starting dynamic subtest: pipe-A-eDP-1
[92.437933] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.902s)
[92.440426] Subtest atomic-setmode: SUCCESS (6.903s)
[93.241683] [014/133] (924s left) gem_pxp (reject-modify-context-protection-off-3)
[93.379669] Starting subtest: reject-modify-context-protection-off-3
[93.382263] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[93.515722] [015/133] (924s left) kms_pm_dc (dc5-dpms)
[94.604396] Starting subtest: dc5-dpms
[96.719710] Subtest dc5-dpms: SUCCESS (2.114s)
[96.804927] [016/133] (921s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[96.885921] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[96.888271] Starting dynamic subtest: pipe-A
[97.259415] Dynamic subtest pipe-A: SUCCESS (0.375s)
[97.268946] Starting dynamic subtest: pipe-B
[98.657551] Dynamic subtest pipe-B: SUCCESS (1.398s)
[98.665754] Starting dynamic subtest: pipe-C
[100.085857] Dynamic subtest pipe-C: SUCCESS (1.428s)
[100.094828] Starting dynamic subtest: pipe-D
[101.521334] Dynamic subtest pipe-D: SUCCESS (1.435s)
[101.523698] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.637s)
[102.702251] [017/133] (915s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[102.798223] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[102.802823] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[102.932218] [018/133] (915s left) gem_exec_reloc (basic-wc-read)
[103.001400] Subtest basic-wc-read: SKIP (0.000s)
[103.097979] [019/133] (915s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[103.176648] Starting subtest: fbc-rgb101010-draw-render
[107.813601] Subtest fbc-rgb101010-draw-render: SUCCESS (4.643s)
[108.994314] [020/133] (909s left) gem_exec_schedule (semaphore-user)
[109.075201] Starting subtest: semaphore-user
[109.123947] Subtest semaphore-user: SUCCESS (0.056s)
[110.918993] [021/133] (907s left) kms_pm_rpm (universal-planes-dpms)
[113.041703] Starting subtest: universal-planes-dpms
[113.044263] Starting dynamic subtest: plane-34
[121.358742] Dynamic subtest plane-34: SUCCESS (8.319s)
[121.369728] Starting dynamic subtest: plane-64
[129.650532] Dynamic subtest plane-64: SUCCESS (8.290s)
[129.660574] Starting dynamic subtest: plane-94
[137.922481] Dynamic subtest plane-94: SUCCESS (8.270s)
[137.933175] Starting dynamic subtest: plane-124
[146.209155] Dynamic subtest plane-124: SUCCESS (8.285s)
[146.218621] Starting dynamic subtest: plane-134
[154.487823] Dynamic subtest plane-134: SUCCESS (8.275s)
[154.498728] Starting dynamic subtest: plane-144
[162.760324] Dynamic subtest plane-144: SUCCESS (8.269s)
[162.763209] Subtest universal-planes-dpms: SUCCESS (49.720s)
[163.127914] [022/133] (855s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[163.216117] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[163.220356] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[163.318158] [023/133] (855s left) syncobj_wait (reset-multiple-signaled)
[163.371188] Starting subtest: reset-multiple-signaled
[163.373571] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[163.498076] [024/133] (855s left) gem_ctx_param (set-priority-range)
[163.555524] Starting subtest: set-priority-range
[163.568117] Subtest set-priority-range: SUCCESS (0.013s)
[163.693792] [025/133] (854s left) gem_exec_whisper (basic-queues-forked)
[163.768802] Starting subtest: basic-queues-forked
[186.963597] Subtest basic-queues-forked: SUCCESS (23.190s)
[187.153056] [026/133] (831s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[187.248299] Starting subtest: basic-max-pipe-crc-check
[187.252588] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[187.293471] [027/133] (831s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[187.373954] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[187.401971] Starting dynamic subtest: pipe-A-plane-0
[187.690709] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[187.701374] Starting dynamic subtest: pipe-A-plane-3
[187.935520] Dynamic subtest pipe-A-plane-3: SUCCESS (0.244s)
[187.943839] Starting dynamic subtest: pipe-A-plane-5
[187.948271] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[189.006086] Starting dynamic subtest: pipe-B-plane-0
[189.299444] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[189.310067] Starting dynamic subtest: pipe-B-plane-3
[189.521636] Dynamic subtest pipe-B-plane-3: SUCCESS (0.222s)
[189.530582] Starting dynamic subtest: pipe-B-plane-5
[189.534818] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[189.832531] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.457s)
[190.692186] [028/133] (828s left) gem_wait (write-busy)
[190.761275] Starting subtest: write-busy
[190.763653] Starting dynamic subtest: all-engines
[191.425027] Dynamic subtest all-engines: SUCCESS (0.663s)
[191.441088] Starting dynamic subtest: rcs0
[191.974402] Dynamic subtest rcs0: SUCCESS (0.547s)
[191.989115] Starting dynamic subtest: bcs0
[192.522424] Dynamic subtest bcs0: SUCCESS (0.547s)
[192.537918] Starting dynamic subtest: vcs0
[193.818617] Dynamic subtest vcs0: SUCCESS (1.293s)
[193.834464] Starting dynamic subtest: vcs1
[195.865509] Dynamic subtest vcs1: SUCCESS (2.044s)
[195.881975] Starting dynamic subtest: vecs0
[197.849642] Dynamic subtest vecs0: SUCCESS (1.982s)
[197.864261] Starting dynamic subtest: ccs0
[198.399773] Dynamic subtest ccs0: SUCCESS (0.550s)
[198.402624] Subtest write-busy: SUCCESS (7.638s)
[198.605442] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[198.609287] [029/133] (820s left) kms_addfb_basic (bad-pitch-65536)
[198.668462] Starting subtest: bad-pitch-65536
[198.670777] Subtest bad-pitch-65536: SUCCESS (0.000s)
[198.809492] [030/133] (819s left) kms_flip (plain-flip-ts-check)
[198.884155] Starting subtest: plain-flip-ts-check
[198.886393] Starting dynamic subtest: A-eDP1
[207.765660] Dynamic subtest A-eDP1: SUCCESS (8.881s)
[207.775640] Starting dynamic subtest: B-eDP1
[216.584536] Dynamic subtest B-eDP1: SUCCESS (8.818s)
[216.594656] Starting dynamic subtest: C-eDP1
[225.404840] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[225.413427] Starting dynamic subtest: D-eDP1
[234.239803] Dynamic subtest D-eDP1: SUCCESS (8.834s)
[234.245059] Subtest plain-flip-ts-check: SUCCESS (35.361s)
[235.434384] [031/133] (783s left) gem_userptr_blits (unsync-unmap-cycles)
[235.504045] Subtest unsync-unmap-cycles: SKIP (0.000s)
[235.659378] [032/133] (783s left) gem_mmap_offset (basic-uaf)
[235.724339] Starting subtest: basic-uaf
[235.726528] Subtest basic-uaf: SUCCESS (0.001s)
[235.863906] [033/133] (783s left) core_getversion (all-cards)
[235.898215] Starting subtest: all-cards
[235.905400] Subtest all-cards: SUCCESS (0.011s)
[235.946654] [034/133] (783s left) gem_exec_whisper (basic-contexts)
[236.014292] Starting subtest: basic-contexts
[241.325988] Subtest basic-contexts: SUCCESS (5.308s)
[241.522141] [035/133] (777s left) kms_feature_discovery (display)
[241.596097] Starting subtest: display
[241.598365] Subtest display: SUCCESS (0.000s)
[241.680034] [036/133] (777s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[241.781688] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[248.671396] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (6.902s)
[248.836306] [037/133] (770s left) gem_mmap_wc (bad-offset)
[248.891658] Subtest bad-offset: SKIP (0.000s)
[248.959780] [038/133] (770s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[249.060319] Starting subtest: cursorB-vs-flipB-varying-size
[249.064775] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[249.218298] [039/133] (769s left) perf_pmu (module-unload)
[249.316830] Starting subtest: module-unload
[257.580233] Subtest module-unload: FAIL (8.159s)
[257.611285] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[257.611968] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[257.615050] [040/133] (761s left) i915_hangman (detector)
[259.173441] Starting subtest: detector
[259.175743] Starting dynamic subtest: rcs0
[270.842319] Dynamic subtest rcs0: SUCCESS (11.666s)
[270.853320] Starting dynamic subtest: bcs0
[282.809533] Dynamic subtest bcs0: SUCCESS (11.963s)
[282.820351] Starting dynamic subtest: vcs0
[293.816194] Dynamic subtest vcs0: SUCCESS (11.004s)
[293.828823] Starting dynamic subtest: vcs1
[305.783892] Dynamic subtest vcs1: SUCCESS (11.964s)
[305.794490] Starting dynamic subtest: vecs0
[316.791848] Dynamic subtest vecs0: SUCCESS (11.004s)
[316.803214] Starting dynamic subtest: ccs0
[327.799265] Dynamic subtest ccs0: SUCCESS (11.004s)
[327.802049] Subtest detector: SUCCESS (68.622s)
[327.856811] [FACT i915_hangman (detector)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[327.857856] [FACT i915_hangman (detector)] new: kernel.kmod_is_loaded.i915: true
[327.861377] [041/133] (691s left) syncobj_basic (create-signaled)
[327.924502] Starting subtest: create-signaled
[327.926675] Subtest create-signaled: SUCCESS (0.000s)
[328.058383] [042/133] (691s left) gem_exec_fence (submit3)
[328.243939] Starting subtest: submit3
[328.248847] Subtest submit3: SKIP (0.000s)
[329.955328] [043/133] (689s left) kms_chamelium_edid (hdmi-edid-read)
[330.341948] Subtest hdmi-edid-read: SKIP (0.000s)
[331.117918] [044/133] (688s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[331.192209] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[331.194538] Starting dynamic subtest: pipe-A-eDP-1
[331.217837] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[331.228206] Starting dynamic subtest: pipe-B-eDP-1
[331.249635] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[331.258099] Starting dynamic subtest: pipe-C-eDP-1
[331.280105] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[331.289459] Starting dynamic subtest: pipe-D-eDP-1
[331.311284] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[331.315608] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[333.447793] [045/133] (685s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[333.519268] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[340.082824] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.565s)
[340.216427] [046/133] (679s left) syncobj_timeline (etime-single-wait-all-submitted)
[340.275052] Starting subtest: etime-single-wait-all-submitted
[340.381888] Subtest etime-single-wait-all-submitted: SUCCESS (0.105s)
[340.500524] [047/133] (679s left) gem_exec_capture (pi)
[340.556526] Starting subtest: pi
[340.558524] Starting dynamic subtest: rcs0
[344.915671] Dynamic subtest rcs0: SUCCESS (4.361s)
[344.927331] Starting dynamic subtest: bcs0
[348.940912] Dynamic subtest bcs0: SUCCESS (4.024s)
[348.952007] Starting dynamic subtest: vcs0
[353.004250] Dynamic subtest vcs0: SUCCESS (4.061s)
[353.015594] Starting dynamic subtest: vcs1
[357.051222] Dynamic subtest vcs1: SUCCESS (4.045s)
[357.062938] Starting dynamic subtest: vecs0
[361.166277] Dynamic subtest vecs0: SUCCESS (4.113s)
[361.177480] Starting dynamic subtest: ccs0
[365.280044] Dynamic subtest ccs0: SUCCESS (4.112s)
[365.282421] Subtest pi: SUCCESS (24.726s)
[365.372369] [048/133] (654s left) kms_flip (nonblocking-read)
[365.505236] Starting subtest: nonblocking-read
[365.507376] Subtest nonblocking-read: SUCCESS (0.000s)
[365.627873] [049/133] (653s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[365.713553] Starting subtest: yf-tiled-16bpp-rotate-90
[365.717899] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[365.827739] [050/133] (653s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[365.907090] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[365.911261] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[366.050511] [051/133] (653s left) gem_softpin (full)
[366.119899] Starting subtest: full
[366.128969] Subtest full: SUCCESS (0.009s)
[366.273044] [052/133] (653s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[366.640224] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[367.414029] [053/133] (652s left) perf (rc6-disable)
[367.565575] Starting subtest: rc6-disable
[367.748328] Subtest rc6-disable: SUCCESS (0.184s)
[367.789894] [054/133] (652s left) gem_partial_pwrite_pread (reads-snoop)
[367.851303] Subtest reads-snoop: SKIP (0.000s)
[367.930448] [055/133] (651s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[368.189749] Starting subtest: cursor-rapid-movement-512x512
[368.194791] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[368.358175] [056/133] (651s left) gem_mmap_gtt (cpuset-big-copy)
[368.406213] Subtest cpuset-big-copy: SKIP (0.000s)
[368.468566] [057/133] (651s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[368.575944] Starting subtest: modeset-atomic-cursor-hotspot
[368.579608] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[368.733730] [058/133] (651s left) kms_psr (psr-sprite-mmap-cpu)
[368.807447] Starting subtest: psr-sprite-mmap-cpu
[368.809362] Starting dynamic subtest: eDP-1
[370.554047] Dynamic subtest eDP-1: SUCCESS (1.745s)
[370.556569] Subtest psr-sprite-mmap-cpu: SUCCESS (1.746s)
[371.406373] [059/133] (648s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[371.490717] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[371.494720] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[371.622895] [060/133] (648s left) gem_exec_reloc (basic-wc-cpu-active)
[371.681743] Subtest basic-wc-cpu-active: SKIP (0.000s)
[371.744538] [061/133] (648s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[371.814534] Starting subtest: basic-modeset-with-all-modifiers-formats
[372.188766] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[372.977764] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.789s)
[372.986313] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[372.999804] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[373.008082] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[373.022081] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[373.030275] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[373.188802] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.166s)
[373.198106] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[373.210654] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[373.219569] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[373.233140] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[373.242668] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[373.255238] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.021s)
[373.263732] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[373.277596] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[373.286274] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[373.299549] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.020s)
[373.308454] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[373.322123] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[373.330901] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[373.344308] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[373.353443] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[373.366589] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[373.375399] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[373.533471] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.166s)
[373.544721] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[373.555452] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[373.564142] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[373.577686] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[373.586475] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[373.600049] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[373.610314] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[373.622157] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[373.631776] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[373.644283] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[373.653420] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[373.666764] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[373.675107] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[373.688777] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.020s)
[373.696813] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[373.710616] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[373.720934] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[373.866583] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.154s)
[373.875941] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[373.888778] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[373.897174] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[373.910992] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[373.919437] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[373.933283] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[373.941754] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[373.955491] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[373.963972] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[373.977732] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[373.986313] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[373.999935] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[374.008389] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[374.022102] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[374.032354] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[374.044295] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[374.437209] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.622s)
[375.281245] [062/133] (644s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[375.357950] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[375.362461] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[376.501845] [063/133] (643s left) kms_flip (flip-vs-dpms-on-nop)
[376.591122] Starting subtest: flip-vs-dpms-on-nop
[376.593456] Starting dynamic subtest: A-eDP1
[378.482658] Dynamic subtest A-eDP1: SUCCESS (1.891s)
[378.494745] Starting dynamic subtest: B-eDP1
[380.310742] Dynamic subtest B-eDP1: SUCCESS (1.826s)
[380.319841] Starting dynamic subtest: C-eDP1
[382.133639] Dynamic subtest C-eDP1: SUCCESS (1.821s)
[382.143205] Starting dynamic subtest: D-eDP1
[383.968510] Dynamic subtest D-eDP1: SUCCESS (1.834s)
[383.970937] Subtest flip-vs-dpms-on-nop: SUCCESS (7.382s)
[385.145374] [064/133] (635s left) vgem_basic (unload)
[385.192912] Starting subtest: unload
[414.408542] Subtest unload: SUCCESS (29.214s)
[414.530275] [065/133] (605s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[414.613166] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[414.615462] Starting dynamic subtest: pipe-A-default-mode
[415.792346] Dynamic subtest pipe-A-default-mode: SKIP (1.178s)
[415.796736] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.180s)
[415.980935] [066/133] (604s left) syncobj_wait (multi-wait-signaled)
[416.046495] Starting subtest: multi-wait-signaled
[416.048411] Subtest multi-wait-signaled: SUCCESS (0.000s)
[416.153714] [067/133] (604s left) kms_properties (get_properties-sanity-atomic)
[416.220300] Starting subtest: get_properties-sanity-atomic
[416.222494] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[416.304461] [068/133] (604s left) sysfs_heartbeat_interval (nopreempt)
[416.374572] Starting subtest: nopreempt
[416.376938] Starting dynamic subtest: vecs0
[425.839099] Dynamic subtest vecs0: SUCCESS (9.461s)
[425.850903] Starting dynamic subtest: ccs0
[429.846048] Dynamic subtest ccs0: SUCCESS (4.005s)
[429.856884] Starting dynamic subtest: vcs0
[439.854457] Dynamic subtest vcs0: SUCCESS (10.004s)
[439.865239] Starting dynamic subtest: bcs0
[443.860090] Dynamic subtest bcs0: SUCCESS (4.007s)
[443.874884] Starting dynamic subtest: rcs0
[447.975999] Dynamic subtest rcs0: SUCCESS (4.113s)
[447.989292] Starting dynamic subtest: vcs1
[457.812272] Dynamic subtest vcs1: SUCCESS (9.833s)
[457.814951] Subtest nopreempt: SUCCESS (41.435s)
[457.905096] [069/133] (562s left) kms_async_flips (test-time-stamp-atomic)
[457.984240] Starting subtest: test-time-stamp-atomic
[458.370158] Starting dynamic subtest: pipe-A-eDP-1
[459.169909] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.797s)
[459.537612] Starting dynamic subtest: pipe-B-eDP-1
[460.294618] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[460.677733] Starting dynamic subtest: pipe-C-eDP-1
[461.431442] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.757s)
[461.801902] Starting dynamic subtest: pipe-D-eDP-1
[462.560987] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.764s)
[462.563450] Subtest test-time-stamp-atomic: SUCCESS (4.577s)
[463.729531] [070/133] (556s left) kms_busy (extended-pageflip-hang-newfb)
[463.821073] Starting subtest: extended-pageflip-hang-newfb
[463.823317] Starting dynamic subtest: pipe-A
[490.617586] Dynamic subtest pipe-A: SUCCESS (26.793s)
[490.627307] Starting dynamic subtest: pipe-D
[515.684493] Dynamic subtest pipe-D: SUCCESS (25.064s)
[515.686995] Subtest extended-pageflip-hang-newfb: SUCCESS (51.863s)
[516.508215] [071/133] (503s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[516.600458] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[516.604786] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[516.678956] [072/133] (503s left) i915_pm_sseu (full-enable)
[516.742013] Subtest full-enable: SKIP (0.000s)
[516.853828] [073/133] (503s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[516.950789] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[516.955106] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[517.083255] [074/133] (503s left) kms_vblank (query-busy)
[517.161770] Starting subtest: query-busy
[517.163683] Starting dynamic subtest: pipe-A-eDP-1
[519.039972] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[519.054732] Starting dynamic subtest: pipe-D-eDP-1
[521.656482] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.613s)
[521.658704] Subtest query-busy: SUCCESS (4.492s)
[522.461199] [075/133] (498s left) kms_psr (fbc-psr2-primary-blt)
[522.539184] Starting subtest: fbc-psr2-primary-blt
[522.541636] Starting dynamic subtest: eDP-1
[522.545864] Dynamic subtest eDP-1: SKIP (0.000s)
[522.550122] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[522.624908] [076/133] (498s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[522.701518] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[522.706066] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[522.798765] [077/133] (497s left) gem_ctx_param (vm)
[522.858924] Starting subtest: vm
[522.907648] Subtest vm: SUCCESS (0.049s)
[523.086094] [078/133] (497s left) kms_big_fb (linear-64bpp-rotate-0)
[523.192240] Starting subtest: linear-64bpp-rotate-0
[524.803892] Subtest linear-64bpp-rotate-0: SUCCESS (1.612s)
[524.996084] [079/133] (495s left) gem_exec_parallel (engines)
[525.066826] Starting subtest: engines
[525.069089] Starting dynamic subtest: basic
[526.811465] Dynamic subtest basic: SUCCESS (1.742s)
[526.823963] Starting dynamic subtest: contexts
[531.312891] Dynamic subtest contexts: SUCCESS (4.499s)
[531.325567] Starting dynamic subtest: fds
[535.175723] Dynamic subtest fds: SUCCESS (3.861s)
[535.186509] Starting dynamic subtest: userptr
[536.885071] Dynamic subtest userptr: SUCCESS (1.706s)
[536.887675] Subtest engines: SUCCESS (11.817s)
[537.105106] [080/133] (483s left) perf (invalid-create-userspace-config)
[537.206535] Starting subtest: invalid-create-userspace-config
[537.208467] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[537.255852] [081/133] (483s left) gem_exec_balancer (individual)
[537.310087] Starting subtest: individual
[538.863416] Subtest individual: SUCCESS (1.551s)
[539.204435] [082/133] (481s left) api_intel_allocator (execbuf-with-allocator)
[539.266588] Starting subtest: execbuf-with-allocator
[539.272735] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[539.441435] [083/133] (481s left) kms_cursor_edge_walk (64x64-top-edge)
[539.524157] Starting subtest: 64x64-top-edge
[539.526475] Starting dynamic subtest: pipe-A-eDP-1
[543.016395] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.493s)
[543.025280] Starting dynamic subtest: pipe-D-eDP-1
[546.144914] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[546.146887] Subtest 64x64-top-edge: SUCCESS (6.620s)
[546.956390] [084/133] (474s left) kms_psr (fbc-psr-cursor-plane-onoff)
[547.028963] Starting subtest: fbc-psr-cursor-plane-onoff
[547.031185] Starting dynamic subtest: eDP-1
[547.035455] Dynamic subtest eDP-1: SKIP (0.000s)
[547.039697] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[547.136901] [085/133] (474s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[547.220105] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[547.224131] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[547.341359] [086/133] (473s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[547.396937] Starting subtest: y-tiled-ccs-to-y-tiled
[547.401271] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[547.589933] [087/133] (473s left) gem_mmap_wc (close)
[547.655744] Subtest close: SKIP (0.000s)
[547.729966] [088/133] (473s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[547.822211] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[547.850195] Starting dynamic subtest: pipe-A-plane-0
[547.854593] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[547.864306] Starting dynamic subtest: pipe-A-plane-3
[547.868890] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[547.879749] Starting dynamic subtest: pipe-A-plane-5
[547.884542] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[548.926933] Starting dynamic subtest: pipe-B-plane-0
[548.931178] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[548.939670] Starting dynamic subtest: pipe-B-plane-3
[548.944087] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[548.952492] Starting dynamic subtest: pipe-B-plane-5
[548.956577] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[549.242818] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (1.418s)
[550.113866] [089/133] (471s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[550.215777] Starting subtest: x-tiled-16bpp-rotate-270
[550.236191] Subtest x-tiled-16bpp-rotate-270: SKIP (0.020s)
[550.397341] [090/133] (470s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[550.490268] Starting subtest: yf-tiled-addfb-size-offset-overflow
[550.494443] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[550.613588] [091/133] (470s left) sw_sync (sync_expired_merge)
[550.653772] Starting subtest: sync_expired_merge
[551.727986] Subtest sync_expired_merge: SUCCESS (1.074s)
[551.799818] [092/133] (469s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[551.883483] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[551.887401] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[551.962102] [093/133] (469s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[552.055500] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[559.022896] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.971s)
[559.185512] [094/133] (462s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[559.268471] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[559.270575] Starting dynamic subtest: pipe-A-eDP-1
[565.190786] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.924s)
[565.200961] Starting dynamic subtest: pipe-B-eDP-1
[571.836165] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.644s)
[571.838534] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.572s)
[572.731133] [095/133] (448s left) gem_create (create-ext-cpu-access-big)
[572.897900] Starting subtest: create-ext-cpu-access-big
[572.902757] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[572.983673] [096/133] (448s left) gem_ctx_persistence (saturated-hostile)
[573.061552] Starting subtest: saturated-hostile
[573.063731] Starting dynamic subtest: rcs0
[573.912235] Dynamic subtest rcs0: SUCCESS (0.853s)
[573.924948] Starting dynamic subtest: bcs0
[574.731210] Dynamic subtest bcs0: SUCCESS (0.813s)
[574.741958] Starting dynamic subtest: vcs0
[575.542962] Dynamic subtest vcs0: SUCCESS (0.813s)
[575.555267] Starting dynamic subtest: vcs1
[576.344944] Dynamic subtest vcs1: SUCCESS (0.800s)
[576.356988] Starting dynamic subtest: vecs0
[577.155721] Dynamic subtest vecs0: SUCCESS (0.809s)
[577.168411] Starting dynamic subtest: ccs0
[578.024657] Dynamic subtest ccs0: SUCCESS (0.867s)
[578.027091] Subtest saturated-hostile: SUCCESS (4.967s)
[578.122327] [097/133] (443s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[578.189590] Starting subtest: wait-all-for-submit-delayed-submit
[578.292027] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[578.385574] [098/133] (443s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[578.488900] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[578.493323] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[578.619206] [099/133] (443s left) kms_content_protection (atomic-hdcp14)
[578.740458] Starting subtest: atomic-hdcp14
[578.744764] Subtest atomic-hdcp14: SKIP (0.000s)
[578.908788] [100/133] (442s left) gem_tiled_blits (normal)
[578.973529] Subtest normal: SKIP (0.000s)
[579.054916] [101/133] (442s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[579.141626] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[579.145603] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[579.279956] [102/133] (442s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[579.358912] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[579.362579] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[579.472756] [103/133] (442s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[579.534130] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[579.535913] Starting dynamic subtest: pipe-A-eDP-1
[580.732510] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.198s)
[580.742499] Starting dynamic subtest: pipe-B-eDP-1
[582.999525] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.266s)
[583.009038] Starting dynamic subtest: pipe-C-eDP-1
[585.225037] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.224s)
[585.234090] Starting dynamic subtest: pipe-D-eDP-1
[587.484906] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.258s)
[587.487084] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (7.952s)
[588.724289] [104/133] (433s left) gem_exec_schedule (implicit-write-read)
[588.807555] Starting subtest: implicit-write-read
[588.809595] Starting dynamic subtest: rcs0
[588.898065] Dynamic subtest rcs0: SUCCESS (0.100s)
[588.908798] Starting dynamic subtest: vcs0
[588.993154] Dynamic subtest vcs0: SUCCESS (0.094s)
[589.005987] Starting dynamic subtest: vcs1
[589.068745] Dynamic subtest vcs1: SUCCESS (0.074s)
[589.081048] Starting dynamic subtest: vecs0
[589.141545] Dynamic subtest vecs0: SUCCESS (0.073s)
[589.153653] Starting dynamic subtest: ccs0
[589.236340] Dynamic subtest ccs0: SUCCESS (0.094s)
[589.239202] Subtest implicit-write-read: SUCCESS (0.439s)
[591.020867] [105/133] (430s left) core_auth (getclient-simple)
[591.060839] Starting subtest: getclient-simple
[591.084294] Subtest getclient-simple: SUCCESS (0.021s)
[591.198413] [106/133] (430s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[591.290161] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[591.925340] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.637s)
[592.116751] [107/133] (429s left) i915_drm_fdinfo (basics)
[592.154308] Starting subtest: basics
[592.156508] Subtest basics: SUCCESS (0.000s)
[592.231146] [108/133] (429s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[592.305726] Starting subtest: fbcpsr-rgb101010-draw-blt
[599.057452] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (6.756s)
[599.183517] [109/133] (422s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[599.283301] Starting subtest: short-flip-before-cursor-toggle
[600.217899] Subtest short-flip-before-cursor-toggle: SUCCESS (0.934s)
[601.088716] [110/133] (421s left) kms_color (invalid-degamma-lut-sizes)
[601.169679] Starting subtest: invalid-degamma-lut-sizes
[601.172046] Starting dynamic subtest: pipe-A
[601.464484] Dynamic subtest pipe-A: SUCCESS (0.294s)
[601.474414] Starting dynamic subtest: pipe-B
[601.476778] Dynamic subtest pipe-B: SUCCESS (0.000s)
[601.486091] Starting dynamic subtest: pipe-C
[601.488454] Dynamic subtest pipe-C: SUCCESS (0.000s)
[601.497690] Starting dynamic subtest: pipe-D
[601.499644] Dynamic subtest pipe-D: SUCCESS (0.000s)
[601.501574] Subtest invalid-degamma-lut-sizes: SUCCESS (0.297s)
[602.301222] [111/133] (419s left) kms_invalid_mode (bad-vsync-start)
[602.384467] Starting subtest: bad-vsync-start
[602.386756] Starting dynamic subtest: pipe-A-eDP-1
[602.669923] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[602.679508] Starting dynamic subtest: pipe-B-eDP-1
[602.681669] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[602.690648] Starting dynamic subtest: pipe-C-eDP-1
[602.692719] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[602.701211] Starting dynamic subtest: pipe-D-eDP-1
[602.703605] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[602.705858] Subtest bad-vsync-start: SUCCESS (0.291s)
[603.530402] [112/133] (418s left) kms_vblank (wait-forked)
[603.601699] Starting subtest: wait-forked
[603.603842] Starting dynamic subtest: pipe-A-eDP-1
[605.400273] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.797s)
[605.414820] Starting dynamic subtest: pipe-D-eDP-1
[607.902611] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.498s)
[607.904820] Subtest wait-forked: SUCCESS (4.299s)
[608.787887] [113/133] (413s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[608.864734] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[608.868903] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[608.973892] [114/133] (413s left) kms_fence_pin_leak
[609.136869] [115/133] (413s left) gem_userptr_blits (usage-restrictions)
[609.201721] Starting subtest: usage-restrictions
[609.203544] Subtest usage-restrictions: SUCCESS (0.000s)
[609.342020] [116/133] (413s left) kms_lease (lease-unleased-crtc)
[609.405703] Starting subtest: lease-unleased-crtc
[609.407874] Starting dynamic subtest: pipe-A-eDP-1
[609.696813] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[609.706608] Starting dynamic subtest: pipe-B-eDP-1
[610.419512] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.719s)
[610.429887] Starting dynamic subtest: pipe-C-eDP-1
[610.432153] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[610.440784] Starting dynamic subtest: pipe-D-eDP-1
[610.442953] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.005s)
[610.445153] Subtest lease-unleased-crtc: SUCCESS (1.027s)
[610.821997] [117/133] (411s left) gem_caching (writes)
[610.891267] Subtest writes: SKIP (0.000s)
[610.950188] [118/133] (411s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[611.032382] Starting subtest: pr-overlay-plane-update-continuous-sf
[611.036622] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[611.114591] [119/133] (411s left) kms_flip (2x-plain-flip-fb-recreate)
[611.190906] Starting subtest: 2x-plain-flip-fb-recreate
[611.197393] Subtest 2x-plain-flip-fb-recreate: SKIP (0.008s)
[611.305190] [120/133] (411s left) gem_tiled_partial_pwrite_pread (reads)
[611.369724] Subtest reads: SKIP (0.000s)
[611.442859] [121/133] (411s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[611.527899] Starting subtest: psr-indfb-scaledprimary
[617.275264] Subtest psr-indfb-scaledprimary: SUCCESS (5.752s)
[617.433886] [122/133] (405s left) syncobj_timeline (multi-wait-available-signaled)
[617.493713] Starting subtest: multi-wait-available-signaled
[617.496334] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[617.624158] [123/133] (405s left) gem_exec_reloc (basic-cpu-gtt)
[617.695941] Subtest basic-cpu-gtt: SKIP (0.000s)
[617.777902] [124/133] (404s left) gem_shrink (reclaim)
[617.843793] Starting subtest: reclaim
[620.267593] Subtest reclaim: SUCCESS (2.425s)
[620.424797] [125/133] (402s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[620.513444] Starting subtest: fbc-rgb101010-draw-blt
[625.162743] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.652s)
[626.343888] [126/133] (396s left) gem_ctx_sseu (engines)
[626.408338] Subtest engines: SKIP (0.000s)
[626.491425] [127/133] (396s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[626.545303] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[626.653025] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.108s)
[626.740623] [128/133] (396s left) gen9_exec_parse (bb-start-far)
[626.805631] Subtest bb-start-far: SKIP (0.000s)
[626.871552] [129/133] (396s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[626.955416] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[626.957607] Starting dynamic subtest: pipe-A-eDP-1
[628.998965] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.043s)
[629.009042] Starting dynamic subtest: pipe-B-eDP-1
[631.112463] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.114s)
[631.121514] Starting dynamic subtest: pipe-C-eDP-1
[633.217637] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.103s)
[633.226332] Starting dynamic subtest: pipe-D-eDP-1
[635.293546] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.075s)
[635.295735] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.341s)
[636.531924] [130/133] (386s left) prime_vgem (sync)
[636.620207] Starting subtest: sync
[636.622200] Starting dynamic subtest: rcs0
[636.669466] Dynamic subtest rcs0: SUCCESS (0.051s)
[636.678733] Starting dynamic subtest: bcs0
[636.736466] Dynamic subtest bcs0: SUCCESS (0.066s)
[636.747697] Starting dynamic subtest: vcs0
[636.785599] Dynamic subtest vcs0: SUCCESS (0.048s)
[636.794827] Starting dynamic subtest: vcs1
[636.837346] Dynamic subtest vcs1: SUCCESS (0.051s)
[636.846651] Starting dynamic subtest: vecs0
[636.884409] Dynamic subtest vecs0: SUCCESS (0.046s)
[636.893167] Starting dynamic subtest: ccs0
[636.931182] Dynamic subtest ccs0: SUCCESS (0.046s)
[636.933311] Subtest sync: SUCCESS (0.314s)
[637.107929] [131/133] (385s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[639.015813] Starting subtest: gem-execbuf-stress-pc8
[639.058034] Subtest gem-execbuf-stress-pc8: SKIP (0.042s)
[639.418570] [132/133] (383s left) kms_cursor_crc (cursor-onscreen-128x128)
[639.620564] Starting subtest: cursor-onscreen-128x128
[639.625484] Starting dynamic subtest: pipe-A-eDP-1
[641.737057] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.113s)
[641.768623] Starting dynamic subtest: pipe-D-eDP-1
[644.505681] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.745s)
[644.507983] Subtest cursor-onscreen-128x128: SUCCESS (4.883s)
[645.393816] [133/133] (377s left) kms_psr (psr2-sprite-blt)
[645.471620] Starting subtest: psr2-sprite-blt
[645.474035] Starting dynamic subtest: eDP-1
[647.174633] Dynamic subtest eDP-1: SUCCESS (1.705s)
[647.176940] Subtest psr2-sprite-blt: SUCCESS (1.706s)
[648.053133] Closing watchdogs
results: parsing output: 0/ for test: syncobj_wait
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_plane_scaling
results: parsing output: 3/ for test: gem_ccs
results: parsing output: 4/ for test: i915_query
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_rotation_crc
results: parsing output: 7/ for test: kms_vrr
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: gem_sync
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: kms_atomic_interruptible
results: parsing output: 13/ for test: gem_pxp
results: parsing output: 14/ for test: kms_pm_dc
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_exec_reloc
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_schedule
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: syncobj_wait
results: parsing output: 23/ for test: gem_ctx_param
results: parsing output: 24/ for test: gem_exec_whisper
results: parsing output: 25/ for test: kms_multipipe_modeset
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: gem_wait
results: parsing output: 28/ for test: kms_addfb_basic
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: gem_userptr_blits
results: parsing output: 31/ for test: gem_mmap_offset
results: parsing output: 32/ for test: core_getversion
results: parsing output: 33/ for test: gem_exec_whisper
results: parsing output: 34/ for test: kms_feature_discovery
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_mmap_wc
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: i915_hangman
results: parsing output: 40/ for test: syncobj_basic
results: parsing output: 41/ for test: gem_exec_fence
results: parsing output: 42/ for test: kms_chamelium_edid
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_exec_capture
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_softpin
results: parsing output: 51/ for test: kms_chamelium_hpd
results: parsing output: 52/ for test: perf
results: parsing output: 53/ for test: gem_partial_pwrite_pread
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_exec_reloc
results: parsing output: 60/ for test: kms_async_flips
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: vgem_basic
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_properties
results: parsing output: 67/ for test: sysfs_heartbeat_interval
results: parsing output: 68/ for test: kms_async_flips
results: parsing output: 69/ for test: kms_busy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: i915_pm_sseu
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_parallel
results: parsing output: 79/ for test: perf
results: parsing output: 80/ for test: gem_exec_balancer
results: parsing output: 81/ for test: api_intel_allocator
results: parsing output: 82/ for test: kms_cursor_edge_walk
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_render_copy
results: parsing output: 86/ for test: gem_mmap_wc
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: sw_sync
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: gem_create
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_content_protection
results: parsing output: 99/ for test: gem_tiled_blits
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: core_auth
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: i915_drm_fdinfo
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_color
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_vblank
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_fence_pin_leak
results: parsing output: 114/ for test: gem_userptr_blits
results: parsing output: 115/ for test: kms_lease
results: parsing output: 116/ for test: gem_caching
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: gem_shrink
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_ctx_sseu
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: gen9_exec_parse
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: prime_vgem
results: parsing output: 130/ for test: i915_pm_rpm
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: kms_psr
Done.