[58.897555] Initializing watchdogs
[58.897601]   /dev/watchdog0
[58.911590] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.915727] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.917941] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.921906] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-plflip-blt)
[59.059551] Starting subtest: fbchdr-2p-scndscrn-shrfb-plflip-blt
[59.063772] Subtest fbchdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[59.165356] [002/156] (960s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[59.267655] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[59.271887] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[59.361505] [003/156] (960s left) gem_readwrite (beyond-eob)
[59.421080] Subtest beyond-EOB: SKIP (0.000s)
[59.476305] [004/156] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[59.573430] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[59.577511] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[59.681513] [005/156] (959s left) syncobj_timeline (multi-wait-submitted-signaled)
[59.740172] Starting subtest: multi-wait-submitted-signaled
[59.742602] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[59.857300] [006/156] (959s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[59.911517] Starting subtest: invalid-single-wait-unsubmitted
[59.913561] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[60.007172] [007/156] (959s left) gem_exec_whisper (basic-normal)
[60.085831] Starting subtest: basic-normal
[64.076663] Subtest basic-normal: SUCCESS (4.028s)
[64.254830] [008/156] (955s left) i915_drm_fdinfo (virtual-busy-hang)
[64.291850] Starting subtest: virtual-busy-hang
[64.296353] Subtest virtual-busy-hang: SKIP (0.000s)
[64.353686] [009/156] (955s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[64.408056] Starting subtest: etime-single-wait-all-available-unsubmitted
[64.515448] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.108s)
[64.587067] [010/156] (955s left) kms_feature_discovery (display-4x)
[64.679996] Starting subtest: display-4x
[64.684635] Subtest display-4x: SKIP (0.000s)
[64.758581] [011/156] (955s left) gem_eio (context-create)
[65.002061] Starting subtest: context-create
[65.345907] Subtest context-create: SUCCESS (0.345s)
[65.460867] [012/156] (954s left) gem_readwrite (read-write)
[65.515603] Subtest read-write: SKIP (0.000s)
[65.578785] [013/156] (954s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[65.678872] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[65.682232] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[65.772522] [014/156] (954s left) gem_softpin (evict-snoop-interruptible)
[65.840221] Starting subtest: evict-snoop-interruptible
[65.843984] Subtest evict-snoop-interruptible: SKIP (0.000s)
[65.951803] [015/156] (953s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[66.048309] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[66.050646] Starting dynamic subtest: pipe-A
[66.069920] Dynamic subtest pipe-A: SKIP (0.019s)
[66.078943] Starting dynamic subtest: pipe-B
[66.104852] Dynamic subtest pipe-B: SKIP (0.036s)
[66.113147] Starting dynamic subtest: pipe-C
[66.142372] Dynamic subtest pipe-C: SKIP (0.037s)
[66.150472] Starting dynamic subtest: pipe-D
[66.178427] Dynamic subtest pipe-D: SKIP (0.036s)
[66.181899] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.129s)
[66.304572] [016/156] (953s left) api_intel_bb (misplaced-blitter)
[66.364099] Starting subtest: misplaced-blitter
[66.370896] Subtest misplaced-blitter: SUCCESS (0.009s)
[66.518182] [017/156] (953s left) kms_flip (blocking-absolute-wf_vblank)
[66.607826] Starting subtest: blocking-absolute-wf_vblank
[66.609433] Starting dynamic subtest: A-eDP1
[75.338631] Dynamic subtest A-eDP1: SUCCESS (8.758s)
[75.351335] Starting dynamic subtest: B-eDP1
[84.058908] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[84.068689] Starting dynamic subtest: C-eDP1
[92.783829] Dynamic subtest C-eDP1: SUCCESS (8.722s)
[92.795989] Starting dynamic subtest: D-eDP1
[101.512695] Dynamic subtest D-eDP1: SUCCESS (8.726s)
[101.514992] Subtest blocking-absolute-wf_vblank: SUCCESS (34.942s)
[102.943774] [018/156] (917s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[103.047681] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[103.056623] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.010s)
[103.170012] [019/156] (916s left) gem_exec_await (wide-contexts)
[103.377636] Starting subtest: wide-contexts
[127.166967] Subtest wide-contexts: SUCCESS (23.785s)
[127.361272] [FACT gem_exec_await (wide-contexts)] new: kernel.kmod_is_loaded.vgem: true
[127.365912] [020/156] (892s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[127.509554] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[127.511871] Starting dynamic subtest: pipe-A-eDP-1
[133.431053] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.927s)
[133.442964] Starting dynamic subtest: pipe-B-eDP-1
[140.063589] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.631s)
[140.065982] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.560s)
[140.934832] [021/156] (879s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[141.044296] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[141.048785] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[141.143871] [022/156] (879s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[141.274188] Starting subtest: basic-flip-before-cursor-legacy
[142.210283] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.937s)
[143.070398] [023/156] (877s left) fbdev (write)
[143.113357] Starting subtest: write
[143.473962] Subtest write: SUCCESS (0.359s)
[143.536206] [024/156] (876s left) gem_exec_flush (basic-wb-rw-default)
[143.590211] Starting subtest: basic-wb-rw-default
[149.045235] Subtest basic-wb-rw-default: SUCCESS (5.455s)
[149.241363] [025/156] (871s left) gem_spin_batch (engines)
[149.298262] Starting subtest: engines
[149.300735] Starting dynamic subtest: rcs0
[152.544493] Dynamic subtest rcs0: SUCCESS (3.241s)
[152.556619] Starting dynamic subtest: bcs0
[155.782412] Dynamic subtest bcs0: SUCCESS (3.237s)
[155.793426] Starting dynamic subtest: vcs0
[159.022610] Dynamic subtest vcs0: SUCCESS (3.237s)
[159.034605] Starting dynamic subtest: vcs1
[162.257808] Dynamic subtest vcs1: SUCCESS (3.235s)
[162.269649] Starting dynamic subtest: vecs0
[165.492815] Dynamic subtest vecs0: SUCCESS (3.234s)
[165.504837] Starting dynamic subtest: ccs0
[168.730107] Dynamic subtest ccs0: SUCCESS (3.236s)
[168.732626] Subtest engines: SUCCESS (19.427s)
[168.959102] [026/156] (851s left) kms_dp_link_training (uhbr-sst)
[169.054017] Starting subtest: uhbr-sst
[169.062681] Subtest uhbr-sst: SKIP (0.000s)
[169.130403] [027/156] (851s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[169.236145] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[169.244835] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[169.356400] [028/156] (851s left) kms_sequence (queue-idle)
[169.444109] Starting subtest: queue-idle
[169.446289] Starting dynamic subtest: pipe-A-eDP-1
[171.244208] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.800s)
[171.254780] Starting dynamic subtest: pipe-B-eDP-1
[173.743143] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.496s)
[173.753929] Starting dynamic subtest: pipe-C-eDP-1
[176.171331] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.429s)
[176.181862] Starting dynamic subtest: pipe-D-eDP-1
[178.602619] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.430s)
[178.604779] Subtest queue-idle: SUCCESS (9.159s)
[179.402670] [029/156] (841s left) perf (non-zero-reason)
[179.558881] Starting subtest: non-zero-reason
[179.561113] Starting dynamic subtest: 0-rcs0
[185.476652] Dynamic subtest 0-rcs0: SUCCESS (5.916s)
[185.490873] Starting dynamic subtest: 1-vcs0
[189.621749] Dynamic subtest 1-vcs0: SUCCESS (4.145s)
[189.624644] Subtest non-zero-reason: SUCCESS (10.062s)
[189.671373] [030/156] (830s left) gem_exec_balancer (full-late-pulse)
[189.724245] Starting subtest: full-late-pulse
[194.167135] Subtest full-late-pulse: SUCCESS (4.442s)
[194.465963] [031/156] (826s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[194.564924] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[194.567180] Starting dynamic subtest: pipe-A-eDP-1
[194.660827] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[194.673484] Starting dynamic subtest: pipe-B-eDP-1
[195.776180] Dynamic subtest pipe-B-eDP-1: SKIP (1.114s)
[195.780993] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.216s)
[196.969062] [032/156] (823s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-cpu)
[197.066159] Starting subtest: psrhdr-rgb565-draw-mmap-cpu
[197.070224] Subtest psrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[197.173051] [033/156] (823s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[197.274243] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[204.948161] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.681s)
[205.074612] [034/156] (815s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[205.165720] Starting subtest: plane-lut1d-ctm3x4-lut1d
[205.313696] Starting dynamic subtest: pipe-A-plane-0
[205.481708] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s)
[205.492876] Starting dynamic subtest: pipe-A-plane-1
[205.636455] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[205.645995] Starting dynamic subtest: pipe-A-plane-2
[205.802704] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[207.025360] Starting dynamic subtest: pipe-B-plane-0
[207.182740] Dynamic subtest pipe-B-plane-0: SUCCESS (0.167s)
[207.193612] Starting dynamic subtest: pipe-B-plane-1
[207.349273] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[207.358992] Starting dynamic subtest: pipe-B-plane-2
[207.504482] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[208.703674] Starting dynamic subtest: pipe-C-plane-0
[208.852248] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[208.865165] Starting dynamic subtest: pipe-C-plane-1
[209.006382] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[209.015755] Starting dynamic subtest: pipe-C-plane-2
[209.151032] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[210.355985] Starting dynamic subtest: pipe-D-plane-0
[210.502423] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[210.513339] Starting dynamic subtest: pipe-D-plane-1
[210.647550] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[210.658674] Starting dynamic subtest: pipe-D-plane-2
[210.804273] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[211.093463] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.926s)
[211.917191] [035/156] (808s left) gem_lmem_swapping (heavy-verify-random)
[211.962448] Subtest heavy-verify-random: SKIP (0.000s)
[212.002530] [036/156] (808s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[212.094189] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[212.098365] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[212.216766] [037/156] (808s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[212.311321] Starting subtest: y-tiled-32bpp-rotate-90
[212.316077] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[212.406760] [038/156] (808s left) syncobj_wait (single-wait-all-submitted)
[212.453117] Starting subtest: single-wait-all-submitted
[212.555115] Subtest single-wait-all-submitted: SUCCESS (0.101s)
[212.628805] [039/156] (808s left) kms_pm_rpm (pm-caching)
[214.454338] Starting subtest: pm-caching
[214.459900] Subtest pm-caching: SKIP (0.000s)
[214.862825] [040/156] (806s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[214.961142] Starting subtest: x-tiled-64bpp-rotate-180
[216.574821] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.613s)
[216.737304] [041/156] (804s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[216.852594] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[216.856205] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[216.960798] [042/156] (804s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[217.063382] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[217.066820] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[217.181396] [043/156] (803s left) kms_vblank (ts-continuation-dpms-suspend)
[217.274107] Starting subtest: ts-continuation-dpms-suspend
[217.275910] Starting dynamic subtest: pipe-A-eDP-1
[234.952411] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.181s)
[234.969019] Starting dynamic subtest: pipe-D-eDP-1
[252.734869] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.556s)
[252.736768] Subtest ts-continuation-dpms-suspend: SUCCESS (4.741s)
[253.604429] [044/156] (767s left) kms_big_fb (linear-16bpp-rotate-180)
[253.703025] Starting subtest: linear-16bpp-rotate-180
[254.345615] Subtest linear-16bpp-rotate-180: FAIL (0.053s)
[254.388924] [045/156] (766s left) gen9_exec_parse (batch-invalid-length)
[254.438923] Subtest batch-invalid-length: SKIP (0.000s)
[254.515368] [046/156] (766s left) gem_exec_capture (pi)
[254.568238] Starting subtest: pi
[254.570139] Starting dynamic subtest: rcs0
[258.577710] Dynamic subtest rcs0: SUCCESS (4.090s)
[258.587985] Starting dynamic subtest: bcs0
[262.559684] Dynamic subtest bcs0: SUCCESS (4.027s)
[262.571567] Starting dynamic subtest: vcs0
[266.536260] Dynamic subtest vcs0: SUCCESS (4.004s)
[266.549081] Starting dynamic subtest: vcs1
[270.526784] Dynamic subtest vcs1: SUCCESS (4.007s)
[270.537958] Starting dynamic subtest: vecs0
[274.523441] Dynamic subtest vecs0: SUCCESS (4.006s)
[274.535401] Starting dynamic subtest: ccs0
[278.552187] Dynamic subtest ccs0: SUCCESS (4.036s)
[278.554593] Subtest pi: SUCCESS (24.177s)
[278.649777] [047/156] (742s left) gem_mmap_gtt (big-copy-xy)
[278.762526] Subtest big-copy-XY: SKIP (0.000s)
[278.827710] [048/156] (742s left) kms_hdr (bpc-switch)
[278.919677] Starting subtest: bpc-switch
[278.921969] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[283.448289] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (4.532s)
[283.460842] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[288.051209] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (4.597s)
[288.053683] Subtest bpc-switch: SUCCESS (9.132s)
[288.152512] [049/156] (733s left) gem_ctx_param (invalid-param-set)
[288.202141] Starting subtest: invalid-param-set
[288.204491] Subtest invalid-param-set: SUCCESS (0.000s)
[288.278496] [050/156] (733s left) kms_addfb_basic (too-high)
[288.380686] Starting subtest: too-high
[288.382928] Subtest too-high: SUCCESS (0.000s)
[288.472445] [051/156] (732s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[288.567619] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[288.569849] Starting dynamic subtest: pipe-A-eDP-1
[289.424448] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.848s)
[289.436335] Starting dynamic subtest: pipe-B-eDP-1
[291.003468] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.576s)
[291.016234] Starting dynamic subtest: pipe-C-eDP-1
[292.599700] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.594s)
[292.613666] Starting dynamic subtest: pipe-D-eDP-1
[294.196030] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.593s)
[294.198297] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.616s)
[295.428274] [052/156] (726s left) kms_flip (basic-flip-vs-wf_vblank)
[295.524473] Starting subtest: basic-flip-vs-wf_vblank
[295.526750] Starting dynamic subtest: A-eDP1
[297.477602] Dynamic subtest A-eDP1: SUCCESS (1.954s)
[297.490667] Starting dynamic subtest: B-eDP1
[299.421124] Dynamic subtest B-eDP1: SUCCESS (1.941s)
[299.435300] Starting dynamic subtest: C-eDP1
[301.350128] Dynamic subtest C-eDP1: SUCCESS (1.927s)
[301.364445] Starting dynamic subtest: D-eDP1
[303.280841] Dynamic subtest D-eDP1: SUCCESS (1.927s)
[303.283276] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.759s)
[304.741109] [053/156] (716s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[304.874312] Starting subtest: cursorB-vs-flipA-varying-size
[304.878919] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[305.025799] [054/156] (716s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[305.126745] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[305.129035] Starting dynamic subtest: pipe-A-default-mode
[307.908268] Dynamic subtest pipe-A-default-mode: SUCCESS (2.780s)
[307.910322] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.781s)
[308.738377] [055/156] (712s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[309.038488] Starting subtest: cursor-rapid-movement-max-size
[309.045305] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[309.164416] [056/156] (712s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[309.226685] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[309.231374] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[309.412087] [057/156] (712s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[309.507750] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[309.511717] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[309.599085] [058/156] (712s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[309.692343] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[309.694664] Starting dynamic subtest: pipe-A
[310.041908] Dynamic subtest pipe-A: SUCCESS (0.351s)
[310.053429] Starting dynamic subtest: pipe-B
[311.430769] Dynamic subtest pipe-B: SUCCESS (1.388s)
[311.442600] Starting dynamic subtest: pipe-C
[312.875591] Dynamic subtest pipe-C: SUCCESS (1.444s)
[312.885921] Starting dynamic subtest: pipe-D
[314.322287] Dynamic subtest pipe-D: SUCCESS (1.446s)
[314.324578] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.633s)
[315.494071] [059/156] (706s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[315.595523] Starting subtest: async-flip-with-page-flip-events-linear
[315.969845] Starting dynamic subtest: pipe-A-eDP-1
[318.762589] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.793s)
[319.126075] Starting dynamic subtest: pipe-B-eDP-1
[321.887397] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.769s)
[322.283239] Starting dynamic subtest: pipe-C-eDP-1
[325.017716] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.743s)
[325.392811] Starting dynamic subtest: pipe-D-eDP-1
[328.143558] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.759s)
[328.146662] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.551s)
[329.328615] [060/156] (692s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[329.417082] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[329.421095] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[329.522874] [061/156] (692s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[329.595513] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[329.705598] [062/156] (692s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[329.811918] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[329.816109] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[329.936129] [063/156] (692s left) vgem_basic (mmap)
[329.997233] Starting subtest: mmap
[330.009239] Subtest mmap: SUCCESS (0.014s)
[330.076121] [064/156] (691s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[330.165014] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[332.626069] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.466s)
[333.834257] [065/156] (688s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[333.939594] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[333.944190] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[334.040620] [066/156] (688s left) kms_pipe_stress (stress-xrgb8888-yftiled)
[334.132947] Starting subtest: stress-xrgb8888-yftiled
[334.137297] Subtest stress-xrgb8888-yftiled: SKIP (0.000s)
[334.206073] [067/156] (687s left) prime_busy (hang)
[334.261395] Starting subtest: hang
[334.263252] Starting dynamic subtest: rcs0
[360.610191] Dynamic subtest rcs0: SUCCESS (26.346s)
[360.620682] Starting dynamic subtest: bcs0
[367.764312] Dynamic subtest bcs0: SUCCESS (7.151s)
[367.774260] Starting dynamic subtest: vcs0
[374.739588] Dynamic subtest vcs0: SUCCESS (6.976s)
[374.752589] Starting dynamic subtest: vcs1
[381.716246] Dynamic subtest vcs1: SUCCESS (6.974s)
[381.726653] Starting dynamic subtest: vecs0
[388.750971] Dynamic subtest vecs0: SUCCESS (7.030s)
[388.761224] Starting dynamic subtest: ccs0
[402.594001] Dynamic subtest ccs0: SUCCESS (13.842s)
[402.596425] Subtest hang: SUCCESS (68.327s)
[402.752611] [068/156] (619s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[402.840602] Starting subtest: 2x-flip-vs-modeset-vs-hang
[402.844896] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.002s)
[402.930922] [069/156] (619s left) sysfs_defaults (readonly)
[402.987521] Starting subtest: readonly
[402.989939] Starting dynamic subtest: vecs0
[402.992320] Dynamic subtest vecs0: SUCCESS (0.000s)
[403.004834] Starting dynamic subtest: ccs0
[403.006692] Dynamic subtest ccs0: SUCCESS (0.000s)
[403.016619] Starting dynamic subtest: vcs0
[403.018556] Dynamic subtest vcs0: SUCCESS (0.000s)
[403.028311] Starting dynamic subtest: bcs0
[403.030231] Dynamic subtest bcs0: SUCCESS (0.000s)
[403.043342] Starting dynamic subtest: rcs0
[403.045580] Dynamic subtest rcs0: SUCCESS (0.000s)
[403.055891] Starting dynamic subtest: vcs1
[403.057754] Dynamic subtest vcs1: SUCCESS (0.000s)
[403.059709] Subtest readonly: SUCCESS (0.006s)
[403.099610] [070/156] (619s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[403.202752] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[403.207129] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[403.309559] [071/156] (619s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[403.406585] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[410.298277] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.897s)
[410.438855] [072/156] (611s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[410.823687] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[411.688199] [073/156] (610s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[411.784831] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[411.788740] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[411.901642] [074/156] (610s left) gem_unfence_active_buffers
[412.074060] [075/156] (610s left) kms_frontbuffer_tracking (fbchdr-suspend)
[412.166725] Starting subtest: fbchdr-suspend
[412.170845] Subtest fbchdr-suspend: SKIP (0.000s)
[412.273437] [076/156] (610s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[412.391898] Starting subtest: cursorA-vs-flipB-atomic
[412.396078] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[412.535772] [077/156] (610s left) gem_exec_fence (submit67)
[412.723833] Starting subtest: submit67
[412.727756] Subtest submit67: SKIP (0.000s)
[414.161006] [078/156] (608s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-render)
[414.259679] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-render
[414.264031] Subtest hdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[414.362619] [079/156] (608s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[414.463679] Starting subtest: modeset-transition-nonblocking-fencing
[414.466006] Starting dynamic subtest: 1x-outputs
[425.769435] Dynamic subtest 1x-outputs: SUCCESS (11.308s)
[425.772319] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.309s)
[426.631373] [080/156] (596s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[426.741838] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[426.745785] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[426.841047] [081/156] (595s left) gem_exec_nop (basic-sequential)
[426.904413] Starting subtest: basic-sequential
[441.582387] Subtest basic-sequential: SUCCESS (14.688s)
[441.811879] [082/156] (580s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[441.910849] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[441.913186] Starting dynamic subtest: pipe-A-eDP-1
[441.936651] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[441.949181] Starting dynamic subtest: pipe-B-eDP-1
[441.970957] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[441.981478] Starting dynamic subtest: pipe-C-eDP-1
[442.003237] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[442.013589] Starting dynamic subtest: pipe-D-eDP-1
[442.035503] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[442.039870] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[444.192494] [083/156] (578s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[444.291233] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[444.293504] Starting dynamic subtest: pipe-A-eDP-1
[444.389540] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[444.401422] Starting dynamic subtest: pipe-B-eDP-1
[445.572936] Dynamic subtest pipe-B-eDP-1: SKIP (1.179s)
[445.577696] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.281s)
[446.758390] [084/156] (576s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[446.816849] Starting subtest: invalid-multi-wait-all-unsubmitted
[446.819177] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[446.942210] [085/156] (575s left) core_hotunplug (unbind-rebind)
[447.045715] Starting subtest: unbind-rebind
[453.035237] Subtest unbind-rebind: SUCCESS (5.991s)
[453.075984] [086/156] (569s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-indfb-pgflip-blt)
[454.113480] Starting subtest: psrhdr-2p-primscrn-indfb-pgflip-blt
[454.117933] Subtest psrhdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[454.215918] [087/156] (568s left) kms_cursor_crc (cursor-alpha-opaque)
[454.308729] Starting subtest: cursor-alpha-opaque
[454.310932] Starting dynamic subtest: pipe-A-eDP-1
[454.943361] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.635s)
[454.954388] Starting dynamic subtest: pipe-D-eDP-1
[456.213752] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.266s)
[456.215820] Subtest cursor-alpha-opaque: SUCCESS (1.904s)
[457.099761] [088/156] (565s left) gem_ccs (suspend-resume)
[457.167196] Starting subtest: suspend-resume
[457.171614] Subtest suspend-resume: SKIP (0.000s)
[457.318207] [089/156] (565s left) i915_pm_freq_api (freq-suspend)
[457.388290] Starting subtest: freq-suspend
[457.390304] Starting dynamic subtest: gt0
[474.388295] Dynamic subtest gt0: SUCCESS (2.236s)
[474.402342] Starting dynamic subtest: gt1
[490.157552] Dynamic subtest gt1: SUCCESS (1.222s)
[490.160063] Subtest freq-suspend: SUCCESS (3.461s)
[490.269173] [090/156] (532s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[490.380890] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[490.385006] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[490.487552] [091/156] (532s left) kms_psr (psr-sprite-plane-move)
[490.576053] Starting subtest: psr-sprite-plane-move
[490.578284] Starting dynamic subtest: eDP-1
[492.292373] Dynamic subtest eDP-1: SUCCESS (1.766s)
[492.294537] Subtest psr-sprite-plane-move: SUCCESS (1.767s)
[493.115594] [092/156] (530s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[493.214968] Starting subtest: bad-aux-stride-yf-tiled-ccs
[493.217250] Starting dynamic subtest: pipe-A-eDP-1
[493.240529] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[493.254236] Starting dynamic subtest: pipe-B-eDP-1
[493.276014] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[493.288222] Starting dynamic subtest: pipe-C-eDP-1
[493.308387] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[493.318539] Starting dynamic subtest: pipe-D-eDP-1
[493.339595] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[493.343472] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[495.454902] [093/156] (527s left) gem_mmap_wc (bad-offset)
[495.514903] Subtest bad-offset: SKIP (0.000s)
[495.584301] [094/156] (527s left) i915_query (query-garbage)
[495.631735] Starting subtest: query-garbage
[495.633596] Subtest query-garbage: SUCCESS (0.000s)
[495.735081] [095/156] (527s left) gem_ctx_param (root-set)
[495.776116] Starting subtest: root-set
[495.778400] Subtest root-set: SUCCESS (0.001s)
[495.889520] [096/156] (527s left) gem_mmap_offset (partial-unmap)
[495.943268] Starting subtest: partial-unmap
[495.945530] Starting dynamic subtest: smem0
[496.425574] Dynamic subtest smem0: SUCCESS (0.491s)
[496.428017] Subtest partial-unmap: SUCCESS (0.492s)
[496.539523] [097/156] (526s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[496.630152] Starting subtest: fbcpsr-rgb565-draw-render
[503.747721] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.191s)
[503.895712] [098/156] (519s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[503.989361] Starting subtest: primary-yf-tiled-reflect-x-90
[503.993810] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[504.064838] [099/156] (519s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[504.157587] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[504.161484] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[504.250944] [100/156] (519s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[504.342002] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[504.346411] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[504.450176] [101/156] (519s left) kms_psr (fbc-psr-sprite-plane-onoff)
[504.545038] Starting subtest: fbc-psr-sprite-plane-onoff
[504.547237] Starting dynamic subtest: eDP-1
[504.551456] Dynamic subtest eDP-1: SKIP (0.000s)
[504.555591] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[504.619864] [102/156] (519s left) gem_exec_schedule (pi-common)
[504.806136] Starting subtest: pi-common
[504.808589] Starting dynamic subtest: rcs0
[504.831272] Dynamic subtest rcs0: SUCCESS (0.029s)
[504.848119] Starting dynamic subtest: bcs0
[504.859452] Dynamic subtest bcs0: SUCCESS (0.028s)
[504.876634] Starting dynamic subtest: vcs0
[504.887580] Dynamic subtest vcs0: SUCCESS (0.027s)
[504.903505] Starting dynamic subtest: vcs1
[504.915242] Dynamic subtest vcs1: SUCCESS (0.026s)
[504.931177] Starting dynamic subtest: vecs0
[504.941977] Dynamic subtest vecs0: SUCCESS (0.027s)
[504.960193] Starting dynamic subtest: ccs0
[504.969689] Dynamic subtest ccs0: SUCCESS (0.027s)
[504.971776] Subtest pi-common: SUCCESS (0.169s)
[505.215685] [103/156] (518s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[505.315488] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[505.936329] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.626s)
[506.097473] [104/156] (517s left) kms_chamelium_frames (dp-crc-multiple)
[506.490673] Subtest dp-crc-multiple: SKIP (0.000s)
[507.303139] [105/156] (516s left) gem_exec_reloc (basic-cpu-read-noreloc)
[507.348618] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[507.416438] [106/156] (516s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[507.506283] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[507.510379] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[507.582955] [107/156] (516s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[507.684945] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[507.689042] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[507.787969] [108/156] (516s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[507.842382] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[507.945378] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[508.023260] [109/156] (516s left) gem_exec_gttfill (basic)
[508.076722] Starting subtest: basic
[510.502396] Subtest basic: SUCCESS (2.434s)
[510.706258] [110/156] (513s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[510.815391] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[510.819957] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[511.009309] [111/156] (513s left) kms_psr (psr-cursor-plane-move)
[511.114617] Starting subtest: psr-cursor-plane-move
[511.117204] Starting dynamic subtest: eDP-1
[512.799366] Dynamic subtest eDP-1: SUCCESS (1.689s)
[512.802099] Subtest psr-cursor-plane-move: SUCCESS (1.690s)
[513.660639] [112/156] (510s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[513.763460] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[513.765821] Starting dynamic subtest: pipe-A-eDP-1
[513.789704] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[513.804561] Starting dynamic subtest: pipe-B-eDP-1
[513.826877] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[513.838413] Starting dynamic subtest: pipe-C-eDP-1
[513.860083] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[513.871171] Starting dynamic subtest: pipe-D-eDP-1
[513.893261] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[513.897384] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[516.027043] [113/156] (508s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[516.088149] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[516.090522] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[516.186376] [114/156] (508s left) i915_pm_rps (thresholds-park)
[516.245919] Starting subtest: thresholds-park
[516.250703] Subtest thresholds-park: SKIP (0.001s)
[516.354066] [115/156] (507s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-blt)
[516.445944] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-blt
[516.450418] Subtest fbchdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[516.556959] [116/156] (507s left) gem_ctx_persistence (userptr)
[516.618623] Starting subtest: userptr
[516.788094] Subtest userptr: SUCCESS (0.172s)
[516.879854] [117/156] (507s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[516.974783] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[524.408737] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.444s)
[524.560299] [118/156] (499s left) kms_psr (psr2-cursor-mmap-cpu)
[524.649388] Starting subtest: psr2-cursor-mmap-cpu
[524.651684] Starting dynamic subtest: eDP-1
[526.342978] Dynamic subtest eDP-1: SUCCESS (1.690s)
[526.344971] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.691s)
[527.242636] [119/156] (497s left) gem_ctx_isolation (nonpriv-switch)
[527.306398] Starting subtest: nonpriv-switch
[527.308666] Starting dynamic subtest: rcs0
[527.573208] Dynamic subtest rcs0: SUCCESS (0.269s)
[527.585417] Starting dynamic subtest: bcs0
[527.846163] Dynamic subtest bcs0: SUCCESS (0.272s)
[527.860234] Starting dynamic subtest: vcs0
[528.124686] Dynamic subtest vcs0: SUCCESS (0.277s)
[528.138558] Starting dynamic subtest: vcs1
[529.665540] Dynamic subtest vcs1: SUCCESS (1.538s)
[529.677571] Starting dynamic subtest: vecs0
[531.704115] Dynamic subtest vecs0: SUCCESS (2.035s)
[531.718616] Starting dynamic subtest: ccs0
[533.718175] Dynamic subtest ccs0: SUCCESS (2.013s)
[533.720634] Subtest nonpriv-switch: SUCCESS (6.409s)
[533.955910] [120/156] (490s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen)
[534.055261] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen
[534.059745] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[534.155505] [121/156] (490s left) kms_cursor_crc (cursor-sliding-512x170)
[534.440514] Starting subtest: cursor-sliding-512x170
[534.445066] Subtest cursor-sliding-512x170: SKIP (0.001s)
[534.583698] [122/156] (490s left) api_intel_allocator (standalone)
[534.642223] Starting subtest: standalone
[534.663620] Subtest standalone: SUCCESS (0.024s)
[534.805052] [123/156] (489s left) kms_psr (fbc-psr2-cursor-blt)
[534.900261] Starting subtest: fbc-psr2-cursor-blt
[534.902127] Starting dynamic subtest: eDP-1
[534.905482] Dynamic subtest eDP-1: SKIP (0.000s)
[534.908786] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[534.988457] [124/156] (489s left) kms_atomic_interruptible (legacy-setmode)
[535.081469] Starting subtest: legacy-setmode
[535.083344] Starting dynamic subtest: pipe-A-eDP-1
[541.987756] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.902s)
[541.990245] Subtest legacy-setmode: SUCCESS (6.903s)
[542.791173] [125/156] (481s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[542.878585] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[542.880927] Starting dynamic subtest: pipe-A-eDP-1
[542.909258] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[542.921001] Starting dynamic subtest: pipe-B-eDP-1
[542.943478] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[542.954402] Starting dynamic subtest: pipe-C-eDP-1
[542.976053] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[542.987131] Starting dynamic subtest: pipe-D-eDP-1
[543.010575] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[543.015067] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[545.152609] [126/156] (479s left) gem_exec_schedule (preempt-user)
[545.220218] Starting subtest: preempt-user
[545.222606] Starting dynamic subtest: rcs0
[545.235307] Dynamic subtest rcs0: SUCCESS (0.023s)
[545.250683] Starting dynamic subtest: bcs0
[545.256910] Dynamic subtest bcs0: SUCCESS (0.021s)
[545.273817] Starting dynamic subtest: vcs0
[545.276943] Dynamic subtest vcs0: SUCCESS (0.016s)
[545.289435] Starting dynamic subtest: vcs1
[545.296357] Dynamic subtest vcs1: SUCCESS (0.021s)
[545.310127] Starting dynamic subtest: vecs0
[545.319118] Dynamic subtest vecs0: SUCCESS (0.021s)
[545.334530] Starting dynamic subtest: ccs0
[545.339619] Dynamic subtest ccs0: SUCCESS (0.020s)
[545.341951] Subtest preempt-user: SUCCESS (0.128s)
[546.483767] [127/156] (478s left) perf_pmu (multi-client)
[546.522737] Starting subtest: multi-client
[546.524964] Starting dynamic subtest: rcs0
[547.571360] Dynamic subtest rcs0: SUCCESS (1.047s)
[547.588719] Starting dynamic subtest: bcs0
[548.623705] Dynamic subtest bcs0: SUCCESS (1.045s)
[548.636088] Starting dynamic subtest: vcs0
[549.675925] Dynamic subtest vcs0: SUCCESS (1.051s)
[549.691699] Starting dynamic subtest: vcs1
[551.444006] Dynamic subtest vcs1: SUCCESS (1.765s)
[551.458386] Starting dynamic subtest: vecs0
[553.456861] Dynamic subtest vecs0: SUCCESS (1.984s)
[553.471737] Starting dynamic subtest: ccs0
[555.412744] Dynamic subtest ccs0: SUCCESS (1.981s)
[555.415942] Subtest multi-client: SUCCESS (8.890s)
[555.542162] [128/156] (469s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[555.643591] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[555.647600] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[555.759239] [129/156] (469s left) core_auth (many-magics)
[555.822699] Starting subtest: many-magics
[556.695786] Subtest many-magics: SUCCESS (0.874s)
[556.918748] [130/156] (468s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[557.025691] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[557.030218] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[557.128694] [131/156] (467s left) gem_exec_reloc (basic-gtt-read-noreloc)
[557.185217] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[557.261905] [132/156] (467s left) kms_joiner (basic-max-non-joiner)
[557.365627] Starting subtest: basic-max-non-joiner
[557.369747] Subtest basic-max-non-joiner: SKIP (0.000s)
[557.438939] [133/156] (467s left) i915_pm_rpm (gem-idle)
[559.590256] Starting subtest: gem-idle
[565.792102] Subtest gem-idle: SUCCESS (6.204s)
[566.137769] [134/156] (458s left) kms_cursor_crc (cursor-offscreen-512x512)
[567.562608] Starting subtest: cursor-offscreen-512x512
[567.566985] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[567.735043] [135/156] (457s left) kms_pm_dc (dc6-psr)
[568.837412] Starting subtest: dc6-psr
[571.718215] Subtest dc6-psr: SUCCESS (2.880s)
[572.576290] [136/156] (452s left) syncobj_timeline (wait-any-snapshot)
[572.635465] Starting subtest: wait-any-snapshot
[572.716250] Subtest wait-any-snapshot: SUCCESS (0.081s)
[572.803076] [137/156] (452s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[572.922559] Starting subtest: 2x-long-flip-vs-cursor-atomic
[572.926999] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[573.078336] [138/156] (452s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[573.170379] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[573.175019] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[573.252700] [139/156] (452s left) gem_lmem_swapping (verify)
[573.287656] Subtest verify: SKIP (0.000s)
[573.339042] [140/156] (452s left) gem_exec_schedule (fifo)
[573.414706] Starting subtest: fifo
[573.416985] Starting dynamic subtest: rcs0
[573.500910] Dynamic subtest rcs0: SUCCESS (0.098s)
[573.513374] Starting dynamic subtest: vcs0
[573.588357] Dynamic subtest vcs0: SUCCESS (0.086s)
[573.604087] Starting dynamic subtest: vcs1
[573.677104] Dynamic subtest vcs1: SUCCESS (0.087s)
[573.697177] Starting dynamic subtest: vecs0
[573.752443] Dynamic subtest vecs0: SUCCESS (0.073s)
[573.768334] Starting dynamic subtest: ccs0
[573.827364] Dynamic subtest ccs0: SUCCESS (0.075s)
[573.830373] Subtest fifo: SUCCESS (0.425s)
[575.551198] [141/156] (449s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[575.649694] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[575.654124] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[575.723043] [142/156] (449s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[575.819847] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[580.040636] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.226s)
[581.184917] [143/156] (444s left) gem_userptr_blits (stress-purge)
[581.249175] Starting subtest: stress-purge
[586.669473] Subtest stress-purge: SUCCESS (5.422s)
[586.804236] [144/156] (438s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[586.961447] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc
[586.965024] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[587.058581] [145/156] (438s left) kms_lease (setcrtc-implicit-plane)
[587.149563] Starting subtest: setcrtc-implicit-plane
[587.151422] Starting dynamic subtest: pipe-A-eDP-1
[587.623948] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.473s)
[587.638090] Starting dynamic subtest: pipe-B-eDP-1
[588.810358] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.186s)
[588.823118] Starting dynamic subtest: pipe-C-eDP-1
[589.920916] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.106s)
[589.934754] Starting dynamic subtest: pipe-D-eDP-1
[591.055996] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[591.058395] Subtest setcrtc-implicit-plane: SUCCESS (3.904s)
[591.891894] [146/156] (433s left) kms_chamelium_audio (dp-audio-edid)
[592.298906] Subtest dp-audio-edid: SKIP (0.000s)
[593.109398] [147/156] (432s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[593.200594] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[593.204972] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[593.318354] [148/156] (432s left) perf (non-sampling-read-error)
[593.477909] Starting subtest: non-sampling-read-error
[593.482961] Subtest non-sampling-read-error: SUCCESS (0.006s)
[593.534110] [149/156] (432s left) gem_userptr_blits (readonly-pwrite-unsync)
[593.594004] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[593.718228] [150/156] (432s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[593.809133] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[593.813067] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[593.910829] [151/156] (431s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[594.010624] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[594.635399] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.626s)
[594.798756] [152/156] (431s left) gem_softpin (evict-active)
[594.856896] Starting subtest: evict-active
[594.859130] Subtest evict-active: SUCCESS (0.005s)
[595.001398] [153/156] (430s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[595.101648] Starting subtest: psr-rgb101010-draw-mmap-cpu
[602.223516] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.125s)
[602.341949] [154/156] (423s left) kms_plane (pixel-format-x-tiled-modifier)
[602.443567] Starting subtest: pixel-format-x-tiled-modifier
[602.469396] Starting dynamic subtest: pipe-A-plane-0
[602.868988] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s)
[602.884283] Starting dynamic subtest: pipe-A-plane-3
[603.168870] Dynamic subtest pipe-A-plane-3: SUCCESS (0.299s)
[603.180768] Starting dynamic subtest: pipe-A-plane-5
[603.184632] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[604.252617] Starting dynamic subtest: pipe-B-plane-0
[604.628861] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[604.642558] Starting dynamic subtest: pipe-B-plane-3
[604.940121] Dynamic subtest pipe-B-plane-3: SUCCESS (0.310s)
[604.954118] Starting dynamic subtest: pipe-B-plane-5
[604.958801] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[605.264212] Subtest pixel-format-x-tiled-modifier: SUCCESS (2.821s)
[606.132442] [155/156] (419s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[606.228780] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[606.233197] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[606.316483] [156/156] (419s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[606.416375] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[606.420358] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[606.503664] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_readwrite
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: gem_exec_whisper
results: parsing output: 7/ for test: i915_drm_fdinfo
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_feature_discovery
results: parsing output: 10/ for test: gem_eio
results: parsing output: 11/ for test: gem_readwrite
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_softpin
results: parsing output: 14/ for test: kms_plane_scaling
results: parsing output: 15/ for test: api_intel_bb
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: gem_exec_await
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: fbdev
results: parsing output: 23/ for test: gem_exec_flush
results: parsing output: 24/ for test: gem_spin_batch
results: parsing output: 25/ for test: kms_dp_link_training
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: kms_sequence
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: gem_exec_balancer
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_color_pipeline
results: parsing output: 34/ for test: gem_lmem_swapping
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: kms_pm_rpm
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_vblank
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gen9_exec_parse
results: parsing output: 45/ for test: gem_exec_capture
results: parsing output: 46/ for test: gem_mmap_gtt
results: parsing output: 47/ for test: kms_hdr
results: parsing output: 48/ for test: gem_ctx_param
results: parsing output: 49/ for test: kms_addfb_basic
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_flip_scaled_crc
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: gem_render_copy
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_plane_scaling
results: parsing output: 58/ for test: kms_async_flips
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: vgem_basic
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_pipe_stress
results: parsing output: 66/ for test: prime_busy
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: sysfs_defaults
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_chamelium_hpd
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_unfence_active_buffers
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: gem_exec_fence
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_atomic_transition
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_exec_nop
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: core_hotunplug
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: gem_ccs
results: parsing output: 88/ for test: i915_pm_freq_api
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: gem_mmap_wc
results: parsing output: 93/ for test: i915_query
results: parsing output: 94/ for test: gem_ctx_param
results: parsing output: 95/ for test: gem_mmap_offset
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_rotation_crc
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: gem_exec_schedule
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_chamelium_frames
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: kms_plane
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: gem_exec_gttfill
results: parsing output: 109/ for test: gem_render_copy
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: i915_pm_rps
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: gem_ctx_isolation
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_cursor_crc
results: parsing output: 121/ for test: api_intel_allocator
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_atomic_interruptible
results: parsing output: 124/ for test: kms_ccs
results: parsing output: 125/ for test: gem_exec_schedule
results: parsing output: 126/ for test: perf_pmu
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: core_auth
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_reloc
results: parsing output: 131/ for test: kms_joiner
results: parsing output: 132/ for test: i915_pm_rpm
results: parsing output: 133/ for test: kms_cursor_crc
results: parsing output: 134/ for test: kms_pm_dc
results: parsing output: 135/ for test: syncobj_timeline
results: parsing output: 136/ for test: kms_cursor_legacy
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_lmem_swapping
results: parsing output: 139/ for test: gem_exec_schedule
results: parsing output: 140/ for test: kms_flip_scaled_crc
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: gem_userptr_blits
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_lease
results: parsing output: 145/ for test: kms_chamelium_audio
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: perf
results: parsing output: 148/ for test: gem_userptr_blits
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_big_fb
results: parsing output: 151/ for test: gem_softpin
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_plane
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.