[61.609861] Initializing watchdogs
[61.609910]   /dev/watchdog0
[61.624114] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[61.628104] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.630258] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.633485] [001/133] (960s left) gem_exec_params (dr4-dirt)
[61.709089] Starting subtest: DR4-dirt
[61.711442] Subtest DR4-dirt: SUCCESS (0.000s)
[61.810583] [002/133] (960s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[61.892073] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[61.894353] Starting dynamic subtest: pipe-A
[62.134811] Dynamic subtest pipe-A: SUCCESS (0.244s)
[62.141586] Starting dynamic subtest: pipe-B
[63.424388] Dynamic subtest pipe-B: SUCCESS (1.284s)
[63.433151] Starting dynamic subtest: pipe-C
[64.761416] Dynamic subtest pipe-C: SUCCESS (1.333s)
[64.769903] Starting dynamic subtest: pipe-D
[66.088420] Dynamic subtest pipe-D: SUCCESS (1.323s)
[66.090838] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.185s)
[67.299602] [003/133] (954s left) kms_psr (psr2-cursor-blt)
[67.440181] Starting subtest: psr2-cursor-blt
[67.442644] Starting dynamic subtest: eDP-1
[69.165920] Dynamic subtest eDP-1: SUCCESS (1.723s)
[69.168281] Subtest psr2-cursor-blt: SUCCESS (1.724s)
[70.047462] [004/133] (952s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[70.115060] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[70.141761] Starting dynamic subtest: pipe-A-plane-0
[70.575835] Dynamic subtest pipe-A-plane-0: SUCCESS (0.433s)
[70.584663] Starting dynamic subtest: pipe-A-plane-3
[70.842601] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[71.919831] Starting dynamic subtest: pipe-B-plane-0
[72.347283] Dynamic subtest pipe-B-plane-0: SUCCESS (0.433s)
[72.355926] Starting dynamic subtest: pipe-B-plane-3
[72.603439] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[72.919933] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.802s)
[73.793730] [005/133] (948s left) kms_atomic (plane-primary-legacy)
[73.864987] Starting subtest: plane-primary-legacy
[73.867434] Starting dynamic subtest: pipe-A-eDP-1
[74.351629] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.485s)
[74.354077] Subtest plane-primary-legacy: SUCCESS (0.487s)
[75.129461] [006/133] (947s left) syncobj_wait (signal)
[75.178237] Starting subtest: signal
[75.180493] Subtest signal: SUCCESS (0.000s)
[75.307896] [007/133] (947s left) gem_create (busy-create)
[75.368074] Starting subtest: busy-create
[75.370122] Starting dynamic subtest: smem0
[108.829600] Dynamic subtest smem0: SUCCESS (33.449s)
[108.831959] Subtest busy-create: SUCCESS (33.450s)
[109.003895] [008/133] (913s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[109.093542] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[109.095958] Starting dynamic subtest: pipe-A
[109.432577] Dynamic subtest pipe-A: SUCCESS (0.340s)
[109.439714] Starting dynamic subtest: pipe-B
[110.861576] Dynamic subtest pipe-B: SUCCESS (1.428s)
[110.871061] Starting dynamic subtest: pipe-C
[112.288323] Dynamic subtest pipe-C: SUCCESS (1.426s)
[112.297609] Starting dynamic subtest: pipe-D
[113.755029] Dynamic subtest pipe-D: SUCCESS (1.466s)
[113.757311] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.662s)
[114.906190] [009/133] (907s left) kms_flip (bo-too-big)
[114.980367] Starting subtest: bo-too-big
[114.982647] Starting dynamic subtest: A-eDP1
[116.236921] Dynamic subtest A-eDP1: SUCCESS (1.258s)
[116.247991] Starting dynamic subtest: B-eDP1
[117.569511] Dynamic subtest B-eDP1: SUCCESS (1.330s)
[117.579719] Starting dynamic subtest: C-eDP1
[118.892736] Dynamic subtest C-eDP1: SUCCESS (1.325s)
[118.902337] Starting dynamic subtest: D-eDP1
[120.226823] Dynamic subtest D-eDP1: SUCCESS (1.333s)
[120.229544] Subtest bo-too-big: SUCCESS (5.251s)
[121.443909] [010/133] (901s left) kms_invalid_mode (overflow-vrefresh)
[121.511331] Starting subtest: overflow-vrefresh
[121.513625] Starting dynamic subtest: pipe-A-eDP-1
[121.793475] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[121.802145] Starting dynamic subtest: pipe-B-eDP-1
[121.804074] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[121.811083] Starting dynamic subtest: pipe-C-eDP-1
[121.813462] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[121.820259] Starting dynamic subtest: pipe-D-eDP-1
[121.822503] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[121.824775] Subtest overflow-vrefresh: SUCCESS (0.287s)
[122.680080] [011/133] (899s left) sysfs_timeslice_duration (timeout)
[122.737183] Starting subtest: timeout
[122.739595] Starting dynamic subtest: vecs0
[131.859096] Dynamic subtest vecs0: SUCCESS (9.117s)
[131.867369] Starting dynamic subtest: ccs0
[133.152687] Dynamic subtest ccs0: SUCCESS (1.295s)
[133.162097] Starting dynamic subtest: vcs0
[141.844963] Dynamic subtest vcs0: SUCCESS (8.687s)
[141.854540] Starting dynamic subtest: bcs0
[142.528265] Dynamic subtest bcs0: SUCCESS (0.683s)
[142.538208] Starting dynamic subtest: rcs0
[143.802599] Dynamic subtest rcs0: SUCCESS (1.273s)
[143.812623] Starting dynamic subtest: vcs1
[153.814720] Dynamic subtest vcs1: SUCCESS (10.009s)
[153.817400] Subtest timeout: SUCCESS (31.072s)
[153.886753] [012/133] (868s left) kms_psr (psr-sprite-blt)
[153.974584] Starting subtest: psr-sprite-blt
[153.976890] Starting dynamic subtest: eDP-1
[155.690286] Dynamic subtest eDP-1: SUCCESS (1.715s)
[155.692504] Subtest psr-sprite-blt: SUCCESS (1.716s)
[156.520261] [013/133] (866s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[156.597788] Starting subtest: stress-mm-invalidate-close-overlap
[158.805909] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[158.872279] [014/133] (863s left) kms_prop_blob (blob-prop-lifetime)
[158.936183] Starting subtest: blob-prop-lifetime
[158.938622] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[159.041020] [015/133] (863s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[159.128241] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[166.209382] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.084s)
[167.337284] [016/133] (855s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[167.428982] Starting subtest: extended-modeset-hang-oldfb-with-reset
[167.431440] Starting dynamic subtest: pipe-A
[179.120955] Dynamic subtest pipe-A: SUCCESS (11.691s)
[179.131094] Starting dynamic subtest: pipe-D
[191.378593] Dynamic subtest pipe-D: SUCCESS (12.254s)
[191.381019] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.949s)
[192.258774] [017/133] (830s left) gem_ctx_shared (single-timeline)
[192.327529] Starting subtest: single-timeline
[192.350719] Subtest single-timeline: SUCCESS (0.017s)
[192.566832] [018/133] (830s left) perf_pmu (busy-check-all)
[192.605105] Starting subtest: busy-check-all
[192.607262] Starting dynamic subtest: rcs0
[193.156588] Dynamic subtest rcs0: SUCCESS (0.553s)
[193.168682] Starting dynamic subtest: bcs0
[193.708944] Dynamic subtest bcs0: SUCCESS (0.551s)
[193.720490] Starting dynamic subtest: vcs0
[194.840080] Dynamic subtest vcs0: SUCCESS (1.129s)
[194.852210] Starting dynamic subtest: vcs1
[196.826599] Dynamic subtest vcs1: SUCCESS (1.984s)
[196.838300] Starting dynamic subtest: vecs0
[198.873705] Dynamic subtest vecs0: SUCCESS (2.045s)
[198.884267] Starting dynamic subtest: ccs0
[199.430620] Dynamic subtest ccs0: SUCCESS (0.557s)
[199.433129] Subtest busy-check-all: SUCCESS (6.828s)
[199.540698] [019/133] (823s left) gem_userptr_blits (probe)
[199.610925] Starting subtest: probe
[199.684282] Subtest probe: SUCCESS (0.073s)
[199.804871] [020/133] (823s left) i915_drm_fdinfo (most-busy-idle-check-all)
[199.843925] Starting subtest: most-busy-idle-check-all
[199.846494] Starting dynamic subtest: rcs0
[199.851083] Dynamic subtest rcs0: SKIP (0.004s)
[199.859757] Starting dynamic subtest: bcs0
[199.863629] Dynamic subtest bcs0: SKIP (0.000s)
[199.871416] Starting dynamic subtest: vcs0
[199.875569] Dynamic subtest vcs0: SKIP (0.000s)
[199.883341] Starting dynamic subtest: vcs1
[199.887598] Dynamic subtest vcs1: SKIP (0.000s)
[199.895149] Starting dynamic subtest: vecs0
[199.899361] Dynamic subtest vecs0: SKIP (0.000s)
[199.906617] Starting dynamic subtest: ccs0
[199.910592] Dynamic subtest ccs0: SKIP (0.000s)
[199.914539] Subtest most-busy-idle-check-all: SKIP (0.008s)
[199.948210] [021/133] (822s left) kms_big_fb (linear-16bpp-rotate-0)
[200.062748] Starting subtest: linear-16bpp-rotate-0
[201.353591] Subtest linear-16bpp-rotate-0: SUCCESS (1.291s)
[201.476084] [022/133] (821s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[201.559131] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[201.562551] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[201.662346] [023/133] (821s left) gem_readwrite (new-obj)
[201.715074] Subtest new-obj: SKIP (0.000s)
[201.782112] [024/133] (821s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[201.867780] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[201.871135] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[201.965854] [025/133] (821s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[202.054066] Starting subtest: flip-vs-dpms-off-vs-modeset
[202.056117] Starting dynamic subtest: A-eDP1
[205.684510] Dynamic subtest A-eDP1: SUCCESS (3.628s)
[205.693694] Starting dynamic subtest: B-eDP1
[208.884068] Dynamic subtest B-eDP1: SUCCESS (3.199s)
[208.895291] Starting dynamic subtest: C-eDP1
[212.011441] Dynamic subtest C-eDP1: SUCCESS (3.126s)
[212.020498] Starting dynamic subtest: D-eDP1
[215.209359] Dynamic subtest D-eDP1: SUCCESS (3.198s)
[215.211549] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.158s)
[216.002758] [026/133] (807s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[216.092099] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[216.094407] Starting dynamic subtest: pipe-A-eDP-1
[216.581860] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.464s)
[216.590717] Starting dynamic subtest: pipe-B-eDP-1
[218.066049] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.481s)
[218.076509] Starting dynamic subtest: pipe-C-eDP-1
[219.426561] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[219.437192] Starting dynamic subtest: pipe-D-eDP-1
[220.744891] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[220.747294] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.626s)
[221.941270] [027/133] (801s left) syncobj_wait (wait-any-interrupted)
[222.020569] Starting subtest: wait-any-interrupted
[222.125991] Subtest wait-any-interrupted: SUCCESS (0.104s)
[222.199703] [028/133] (800s left) kms_selftest (drm_plane_helper)
[222.241222] Starting subtest: drm_plane_helper
[222.289095] Starting dynamic subtest: drm_test_check_plane_state
[222.385908] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.099s)
[222.395162] Starting dynamic subtest: drm_test_check_invalid_plane_state
[222.482376] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.097s)
[222.484759] Subtest drm_plane_helper: SUCCESS (0.241s)
[222.643685] [029/133] (800s left) kms_lease (lease-uevent)
[222.718600] Starting subtest: lease-uevent
[223.737201] Subtest lease-uevent: SUCCESS (1.017s)
[223.831725] [030/133] (799s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[223.950026] Starting subtest: dp-mst-type-1-suspend-resume
[223.954845] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[224.062370] [031/133] (799s left) syncobj_wait (wait-all-delayed-signal)
[224.114068] Starting subtest: wait-all-delayed-signal
[224.214813] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[224.307945] [032/133] (799s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[224.372639] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[224.435375] [033/133] (798s left) syncobj_wait (wait-all-for-submit-complex)
[224.487778] Starting subtest: wait-all-for-submit-complex
[224.702073] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[224.785501] [034/133] (798s left) core_setmaster (master-drop-set-root)
[224.844841] Starting subtest: master-drop-set-root
[224.871178] Subtest master-drop-set-root: SUCCESS (0.027s)
[224.916803] [035/133] (798s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[224.988220] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[224.992725] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[226.071733] [036/133] (797s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[226.133879] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[226.138609] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[226.315221] [037/133] (797s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[226.429816] Starting subtest: 4-tiled-32bpp-rotate-180
[228.087308] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.655s)
[228.204225] [038/133] (795s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[228.290422] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[234.990807] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.700s)
[235.103861] [039/133] (788s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[235.175471] Starting subtest: bad-rotation-90-yf-tiled-ccs
[235.177664] Starting dynamic subtest: pipe-A-eDP-1
[235.200187] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[235.210947] Starting dynamic subtest: pipe-B-eDP-1
[235.231245] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[235.239231] Starting dynamic subtest: pipe-C-eDP-1
[235.260151] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[235.267921] Starting dynamic subtest: pipe-D-eDP-1
[235.289974] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[235.294077] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[237.438053] [040/133] (786s left) gem_mmap_wc (coherency)
[237.503158] Subtest coherency: SKIP (0.000s)
[237.567175] [041/133] (786s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[237.627322] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[237.629534] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[237.740392] [042/133] (785s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[237.821636] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[237.826251] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[237.920284] [043/133] (785s left) kms_plane_alpha_blend (constant-alpha-mid)
[238.004852] Starting subtest: constant-alpha-mid
[238.300508] Starting dynamic subtest: pipe-A-eDP-1
[239.121457] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.820s)
[239.130075] Starting dynamic subtest: pipe-D-eDP-1
[240.618559] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.496s)
[240.620864] Subtest constant-alpha-mid: SUCCESS (2.614s)
[241.491991] [044/133] (782s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[241.578631] Starting subtest: psr2-overlay-plane-update-continuous-sf
[241.580801] Starting dynamic subtest: pipe-A-eDP-1
[242.696373] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.122s)
[242.705379] Starting dynamic subtest: pipe-B-eDP-1
[244.436545] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.739s)
[244.438721] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.864s)
[245.245998] [045/133] (778s left) gem_exec_whisper (basic-contexts-priority)
[245.314382] Starting subtest: basic-contexts-priority
[270.411877] Subtest basic-contexts-priority: SUCCESS (25.043s)
[270.540998] [046/133] (753s left) kms_feature_discovery (chamelium)
[270.626741] Starting subtest: chamelium
[270.631257] Subtest chamelium: SKIP (0.000s)
[270.697774] [047/133] (753s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[270.832849] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[270.966255] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s)
[271.087241] [048/133] (752s left) gem_exec_balancer (persistence)
[271.361218] Starting subtest: persistence
[272.244413] Subtest persistence: SUCCESS (0.884s)
[272.356801] [049/133] (751s left) gem_render_copy (linear)
[272.420035] Starting subtest: linear
[272.422544] Starting dynamic subtest: smem
[272.464375] Dynamic subtest smem: SUCCESS (0.044s)
[272.466951] Subtest linear: SUCCESS (0.045s)
[272.700918] [050/133] (751s left) i915_pm_rps (thresholds)
[272.759805] Starting subtest: thresholds
[272.764510] Subtest thresholds: SKIP (0.001s)
[272.838132] [051/133] (751s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[272.927611] Starting subtest: yf-tiled-32bpp-rotate-0
[272.931952] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[273.016967] [052/133] (751s left) api_intel_bb (object-reloc-purge-cache)
[273.081920] Starting subtest: object-reloc-purge-cache
[273.086231] Subtest object-reloc-purge-cache: SKIP (0.002s)
[273.193819] [053/133] (750s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[273.283249] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[273.287295] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[273.392346] [054/133] (750s left) kms_chamelium_hpd (hdmi-hpd-storm)
[273.764071] Subtest hdmi-hpd-storm: SKIP (0.000s)
[274.545731] [055/133] (749s left) gem_caching (writes)
[274.611666] Subtest writes: SKIP (0.000s)
[274.674313] [056/133] (749s left) kms_cursor_legacy (forked-bo)
[274.765744] Starting subtest: forked-bo
[274.767826] Starting dynamic subtest: pipe-A
[280.270321] Dynamic subtest pipe-A: SUCCESS (5.451s)
[280.279187] Starting dynamic subtest: pipe-B
[285.730135] Dynamic subtest pipe-B: SUCCESS (5.457s)
[285.740354] Starting dynamic subtest: pipe-C
[291.200267] Dynamic subtest pipe-C: SUCCESS (5.467s)
[291.211065] Starting dynamic subtest: pipe-D
[296.679470] Dynamic subtest pipe-D: SUCCESS (5.478s)
[296.694150] Starting dynamic subtest: all-pipes
[302.192523] Dynamic subtest all-pipes: SUCCESS (5.509s)
[302.195018] Subtest forked-bo: SUCCESS (27.375s)
[302.293646] [057/133] (722s left) kms_async_flips (crc)
[302.366475] Starting subtest: crc
[302.741962] Starting dynamic subtest: pipe-A-eDP-1
[305.690569] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.946s)
[306.859601] Starting dynamic subtest: pipe-B-eDP-1
[309.290026] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.439s)
[309.676053] Starting dynamic subtest: pipe-C-eDP-1
[312.599304] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.932s)
[312.977225] Starting dynamic subtest: pipe-D-eDP-1
[315.826023] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.857s)
[315.828405] Subtest crc: SUCCESS (13.461s)
[316.985471] [058/133] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[317.074462] Starting subtest: yf-tiled-8bpp-rotate-90
[317.078868] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[317.169453] [059/133] (707s left) gem_ctx_bad_destroy (invalid-pad)
[317.216687] Starting subtest: invalid-pad
[317.219085] Subtest invalid-pad: SUCCESS (0.000s)
[317.302858] [060/133] (707s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[317.382762] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[317.384960] Starting dynamic subtest: pipe-A-eDP-1
[317.407022] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[317.416497] Starting dynamic subtest: pipe-B-eDP-1
[317.438420] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[317.446714] Starting dynamic subtest: pipe-C-eDP-1
[317.468324] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[317.476730] Starting dynamic subtest: pipe-D-eDP-1
[317.498257] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[317.502242] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[319.559784] [061/133] (704s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[319.631675] Starting subtest: y-tiled-16bpp-rotate-180
[319.636079] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[319.733357] [062/133] (704s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[319.816942] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[319.821515] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[319.955583] [063/133] (704s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[320.050921] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[320.055120] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[320.163244] [064/133] (704s left) gem_exec_parallel (engines)
[320.222857] Starting subtest: engines
[320.224720] Starting dynamic subtest: basic
[321.816385] Dynamic subtest basic: SUCCESS (1.594s)
[321.826655] Starting dynamic subtest: contexts
[326.221637] Dynamic subtest contexts: SUCCESS (4.401s)
[326.235170] Starting dynamic subtest: fds
[330.075223] Dynamic subtest fds: SUCCESS (3.852s)
[330.084200] Starting dynamic subtest: userptr
[331.813356] Dynamic subtest userptr: SUCCESS (1.735s)
[331.815894] Subtest engines: SUCCESS (11.593s)
[332.022054] [065/133] (692s left) kms_cursor_crc (cursor-onscreen-256x85)
[332.284952] Starting subtest: cursor-onscreen-256x85
[332.328513] Subtest cursor-onscreen-256x85: SKIP (0.040s)
[332.493419] [066/133] (692s left) gem_exec_flush (basic-uc-prw-default)
[332.554517] Starting subtest: basic-uc-prw-default
[338.020899] Subtest basic-uc-prw-default: SUCCESS (5.466s)
[338.204160] [067/133] (686s left) kms_pm_rpm (modeset-lpsp)
[340.027732] Starting subtest: modeset-lpsp
[341.496017] Subtest modeset-lpsp: SUCCESS (1.471s)
[341.586282] [068/133] (683s left) i915_hangman (gt-error-state-capture)
[341.650590] Starting subtest: gt-error-state-capture
[341.652806] Starting dynamic subtest: rcs0
[352.824884] Dynamic subtest rcs0: SUCCESS (11.173s)
[352.837372] Starting dynamic subtest: bcs0
[364.791339] Dynamic subtest bcs0: SUCCESS (11.963s)
[364.802555] Starting dynamic subtest: vcs0
[376.824778] Dynamic subtest vcs0: SUCCESS (12.030s)
[376.837170] Starting dynamic subtest: vcs1
[388.791505] Dynamic subtest vcs1: SUCCESS (11.963s)
[388.804047] Starting dynamic subtest: vecs0
[400.823599] Dynamic subtest vecs0: SUCCESS (12.029s)
[400.836114] Starting dynamic subtest: ccs0
[412.791676] Dynamic subtest ccs0: SUCCESS (11.965s)
[412.795058] Subtest gt-error-state-capture: SUCCESS (71.139s)
[412.870146] [069/133] (611s left) kms_psr (psr2-primary-page-flip)
[412.975190] Starting subtest: psr2-primary-page-flip
[412.977398] Starting dynamic subtest: eDP-1
[414.754076] Dynamic subtest eDP-1: SUCCESS (1.777s)
[414.756235] Subtest psr2-primary-page-flip: SUCCESS (1.778s)
[415.619829] [070/133] (609s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[415.700060] Starting subtest: x-tiled-64bpp-rotate-0
[417.300784] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.602s)
[417.467303] [071/133] (607s left) i915_pm_rps (min-max-config-loaded)
[417.524794] Starting subtest: min-max-config-loaded
[417.529326] Subtest min-max-config-loaded: SKIP (0.001s)
[417.629079] [072/133] (607s left) perf_pmu (busy-no-semaphores)
[417.663826] Starting subtest: busy-no-semaphores
[417.665712] Starting dynamic subtest: rcs0
[418.222627] Dynamic subtest rcs0: SUCCESS (0.558s)
[418.234992] Starting dynamic subtest: bcs0
[418.774621] Dynamic subtest bcs0: SUCCESS (0.549s)
[418.788891] Starting dynamic subtest: vcs0
[419.326761] Dynamic subtest vcs0: SUCCESS (0.549s)
[419.338452] Starting dynamic subtest: vcs1
[421.340604] Dynamic subtest vcs1: SUCCESS (2.010s)
[421.353102] Starting dynamic subtest: vecs0
[423.390524] Dynamic subtest vecs0: SUCCESS (2.047s)
[423.403098] Starting dynamic subtest: ccs0
[425.372881] Dynamic subtest ccs0: SUCCESS (1.979s)
[425.376399] Subtest busy-no-semaphores: SUCCESS (7.710s)
[425.492733] [073/133] (599s left) kms_flip (blocking-wf_vblank)
[425.576714] Starting subtest: blocking-wf_vblank
[425.578648] Starting dynamic subtest: A-eDP1
[434.499786] Dynamic subtest A-eDP1: SUCCESS (8.889s)
[434.511118] Starting dynamic subtest: B-eDP1
[443.433743] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[443.444937] Starting dynamic subtest: C-eDP1
[452.367537] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[452.378520] Starting dynamic subtest: D-eDP1
[461.262154] Dynamic subtest D-eDP1: SUCCESS (8.937s)
[461.268302] Subtest blocking-wf_vblank: SUCCESS (35.691s)
[462.484582] [074/133] (562s left) gem_mmap_wc (read-write)
[462.540912] Subtest read-write: SKIP (0.000s)
[462.594722] [075/133] (562s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[462.690533] Starting subtest: basic-flip-after-cursor-varying-size
[463.615177] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.925s)
[464.414407] [076/133] (560s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[464.469383] Starting subtest: etime-multi-wait-all-for-submit-submitted
[464.573859] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[464.682624] [077/133] (560s left) kms_properties (plane-properties-legacy)
[464.760397] Starting subtest: plane-properties-legacy
[464.762322] Starting dynamic subtest: pipe-A-eDP-1
[465.314833] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.552s)
[465.324377] Starting dynamic subtest: pipe-B-eDP-1
[466.591056] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.276s)
[466.600525] Starting dynamic subtest: pipe-C-eDP-1
[467.819876] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.228s)
[467.829700] Starting dynamic subtest: pipe-D-eDP-1
[469.055432] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s)
[469.057737] Subtest plane-properties-legacy: SUCCESS (4.294s)
[469.849481] [078/133] (555s left) gem_exec_fence (syncobj-invalid-flags)
[470.320103] Starting subtest: syncobj-invalid-flags
[470.322604] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[471.935732] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[471.939191] [079/133] (553s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[472.029072] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[472.033363] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[472.118289] [080/133] (553s left) gem_mmap_gtt (basic-small-bo-tiledy)
[472.174089] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[472.224616] [081/133] (553s left) syncobj_basic (bad-destroy)
[472.275452] Starting subtest: bad-destroy
[472.277612] Subtest bad-destroy: SUCCESS (0.000s)
[472.364116] [082/133] (553s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[472.434654] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[479.391635] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.958s)
[479.518836] [083/133] (545s left) gem_exec_balancer (hang)
[479.769401] Starting subtest: hang
[481.881885] Subtest hang: SUCCESS (2.112s)
[482.003271] [084/133] (543s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[482.091794] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[482.094085] Starting dynamic subtest: pipe-A-eDP-1
[482.952271] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.853s)
[482.962164] Starting dynamic subtest: pipe-B-eDP-1
[484.617658] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.663s)
[484.627183] Starting dynamic subtest: pipe-C-eDP-1
[486.135468] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.517s)
[486.147266] Starting dynamic subtest: pipe-D-eDP-1
[487.628928] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.490s)
[487.630829] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.529s)
[488.817481] [085/133] (536s left) prime_vgem (basic-blt)
[488.888124] Starting subtest: basic-blt
[488.914717] Subtest basic-blt: SUCCESS (0.027s)
[489.046316] [086/133] (536s left) gem_exec_schedule (implicit-write-read)
[489.126684] Starting subtest: implicit-write-read
[489.128859] Starting dynamic subtest: rcs0
[489.218278] Dynamic subtest rcs0: SUCCESS (0.101s)
[489.226805] Starting dynamic subtest: vcs0
[489.304725] Dynamic subtest vcs0: SUCCESS (0.084s)
[489.315700] Starting dynamic subtest: vcs1
[489.384491] Dynamic subtest vcs1: SUCCESS (0.078s)
[489.394185] Starting dynamic subtest: vecs0
[489.463970] Dynamic subtest vecs0: SUCCESS (0.079s)
[489.475635] Starting dynamic subtest: ccs0
[489.553709] Dynamic subtest ccs0: SUCCESS (0.089s)
[489.556183] Subtest implicit-write-read: SUCCESS (0.437s)
[490.923765] [087/133] (534s left) gem_userptr_blits (coherency-unsync)
[490.997688] Subtest coherency-unsync: SKIP (0.000s)
[491.115407] [088/133] (534s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[491.193503] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[491.197293] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[491.263618] [089/133] (534s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[491.332576] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[491.341980] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.008s)
[491.437897] [090/133] (534s left) gem_softpin (evict-prime)
[491.501632] Starting subtest: evict-prime
[491.504058] Starting dynamic subtest: rcs0
[492.649691] Dynamic subtest rcs0: SUCCESS (1.147s)
[492.659987] Starting dynamic subtest: bcs0
[493.798330] Dynamic subtest bcs0: SUCCESS (1.147s)
[493.810843] Starting dynamic subtest: vcs0
[495.832160] Dynamic subtest vcs0: SUCCESS (2.031s)
[495.843585] Starting dynamic subtest: vcs1
[497.820227] Dynamic subtest vcs1: SUCCESS (1.985s)
[497.833060] Starting dynamic subtest: vecs0
[499.869663] Dynamic subtest vecs0: SUCCESS (2.046s)
[499.881978] Starting dynamic subtest: ccs0
[500.992897] Dynamic subtest ccs0: SUCCESS (1.123s)
[501.002694] Starting dynamic subtest: all
[502.805057] Dynamic subtest all: SUCCESS (1.808s)
[502.808038] Subtest evict-prime: SUCCESS (11.302s)
[502.896043] [091/133] (522s left) gem_ctx_param (invalid-get-engines)
[502.951865] Starting subtest: invalid-get-engines
[502.954237] Subtest invalid-get-engines: SUCCESS (0.001s)
[503.063624] [092/133] (522s left) gem_exec_schedule (timeslicing)
[503.138312] Starting subtest: timeslicing
[503.140693] Starting dynamic subtest: rcs0
[503.153835] Dynamic subtest rcs0: SUCCESS (0.025s)
[503.165623] Starting dynamic subtest: bcs0
[503.179490] Dynamic subtest bcs0: SUCCESS (0.024s)
[503.189208] Starting dynamic subtest: vcs0
[503.203305] Dynamic subtest vcs0: SUCCESS (0.023s)
[503.212351] Starting dynamic subtest: vcs1
[503.225697] Dynamic subtest vcs1: SUCCESS (0.022s)
[503.237244] Starting dynamic subtest: vecs0
[503.248276] Dynamic subtest vecs0: SUCCESS (0.022s)
[503.257420] Starting dynamic subtest: ccs0
[503.273017] Dynamic subtest ccs0: SUCCESS (0.024s)
[503.275415] Subtest timeslicing: SUCCESS (0.145s)
[504.913499] [093/133] (520s left) kms_color_pipeline (plane-ctm3x4)
[504.994235] Starting subtest: plane-ctm3x4
[505.162625] Starting dynamic subtest: pipe-A-plane-0
[505.466838] Dynamic subtest pipe-A-plane-0: FAIL (0.140s)
[505.476528] Starting dynamic subtest: pipe-A-plane-1
[505.683403] Dynamic subtest pipe-A-plane-1: FAIL (0.146s)
[505.693968] Starting dynamic subtest: pipe-A-plane-2
[505.922668] Dynamic subtest pipe-A-plane-2: FAIL (0.165s)
[506.800308] Starting dynamic subtest: pipe-B-plane-0
[507.183616] Dynamic subtest pipe-B-plane-0: FAIL (0.119s)
[507.194839] Starting dynamic subtest: pipe-B-plane-1
[507.500182] Dynamic subtest pipe-B-plane-1: FAIL (0.144s)
[507.511917] Starting dynamic subtest: pipe-B-plane-2
[507.821182] Dynamic subtest pipe-B-plane-2: FAIL (0.624s)
[508.843839] Starting dynamic subtest: pipe-C-plane-0
[509.300523] Dynamic subtest pipe-C-plane-0: FAIL (0.106s)
[509.311879] Starting dynamic subtest: pipe-C-plane-1
[509.702465] Dynamic subtest pipe-C-plane-1: FAIL (0.600s)
[509.713777] Starting dynamic subtest: pipe-C-plane-2
[510.073393] Dynamic subtest pipe-C-plane-2: FAIL (0.143s)
[510.884796] Starting dynamic subtest: pipe-D-plane-0
[511.429274] Dynamic subtest pipe-D-plane-0: FAIL (0.107s)
[511.440953] Starting dynamic subtest: pipe-D-plane-1
[511.882187] Dynamic subtest pipe-D-plane-1: FAIL (0.599s)
[511.896408] Starting dynamic subtest: pipe-D-plane-2
[512.308333] Dynamic subtest pipe-D-plane-2: FAIL (0.560s)
[512.446042] Subtest plane-ctm3x4: FAIL (7.452s)
[513.246810] [094/133] (512s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[513.347253] Starting subtest: cursorA-vs-flipA-legacy
[524.476452] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.127s)
[525.275825] [095/133] (500s left) kms_cursor_crc (cursor-random-32x32)
[525.406760] Starting subtest: cursor-random-32x32
[525.457931] Subtest cursor-random-32x32: SKIP (0.045s)
[525.737739] [096/133] (500s left) sw_sync (alloc_merge_fence)
[525.774641] Starting subtest: alloc_merge_fence
[525.776965] Subtest alloc_merge_fence: SUCCESS (0.000s)
[525.831303] [097/133] (500s left) syncobj_wait (multi-wait-all-signaled)
[525.886033] Starting subtest: multi-wait-all-signaled
[525.888304] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[525.987428] [098/133] (500s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[526.033789] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[526.036382] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[526.154320] [099/133] (499s left) gem_render_tiled_blits (basic)
[526.224769] Subtest basic: SKIP (0.000s)
[526.303793] [100/133] (499s left) kms_pm_backlight (fade-with-suspend)
[526.388155] Starting subtest: fade-with-suspend
[542.309899] Starting dynamic subtest: eDP-1
[544.812504] Dynamic subtest eDP-1: SUCCESS (2.440s)
[544.814702] Subtest fade-with-suspend: SUCCESS (4.515s)
[545.710892] [101/133] (480s left) kms_cursor_crc (cursor-suspend)
[545.849758] Starting subtest: cursor-suspend
[545.852211] Starting dynamic subtest: pipe-A-eDP-1
[578.812075] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.152s)
[578.823998] Starting dynamic subtest: pipe-D-eDP-1
[612.797433] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.718s)
[612.801775] Subtest cursor-suspend: SUCCESS (6.873s)
[613.681515] [102/133] (412s left) gem_lmem_swapping (parallel-random-engines)
[613.741280] Subtest parallel-random-engines: SKIP (0.000s)
[613.789897] [103/133] (412s left) syncobj_timeline (invalid-query-bad-pad)
[613.846147] Starting subtest: invalid-query-bad-pad
[613.848968] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[613.965452] [104/133] (412s left) api_intel_bb (blit-noreloc-purge-cache)
[614.019704] Starting subtest: blit-noreloc-purge-cache
[614.028024] Subtest blit-noreloc-purge-cache: SUCCESS (0.011s)
[614.121426] [105/133] (412s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[614.206929] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[614.212651] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[614.308061] [106/133] (412s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[614.372472] Starting subtest: mixed-tiled-to-y-tiled-ccs
[614.377982] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[614.560331] [107/133] (411s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[614.641464] Starting subtest: psr-1p-primscrn-cur-indfb-move
[622.098000] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.336s)
[622.235705] [108/133] (404s left) kms_psr (fbc-psr-no-drrs)
[622.309917] Starting subtest: fbc-psr-no-drrs
[622.312670] Starting dynamic subtest: eDP-1
[622.317045] Dynamic subtest eDP-1: SKIP (0.000s)
[622.321791] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[622.383486] [109/133] (404s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[622.460431] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[622.464958] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[622.573919] [110/133] (403s left) kms_rotation_crc (bad-pixel-format)
[622.653936] Starting subtest: bad-pixel-format
[622.658979] Subtest bad-pixel-format: SKIP (0.001s)
[622.741598] [111/133] (403s left) kms_psr (fbc-pr-sprite-render)
[622.812883] Starting subtest: fbc-pr-sprite-render
[622.818154] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[622.927451] [112/133] (403s left) syncobj_timeline (wait-all-delayed-signal)
[622.976292] Starting subtest: wait-all-delayed-signal
[623.078199] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[623.159932] [113/133] (403s left) gem_ctx_persistence (legacy-engines-hang)
[623.217025] Starting subtest: legacy-engines-hang
[623.219942] Starting dynamic subtest: render
[623.338431] Dynamic subtest render: SUCCESS (0.119s)
[623.351221] Starting dynamic subtest: bsd1
[623.440288] Dynamic subtest bsd1: SUCCESS (0.098s)
[623.453017] Starting dynamic subtest: bsd2
[623.538681] Dynamic subtest bsd2: SUCCESS (0.095s)
[623.554898] Starting dynamic subtest: blt
[623.581372] Dynamic subtest blt: SUCCESS (0.039s)
[623.595939] Starting dynamic subtest: vebox
[623.681487] Dynamic subtest vebox: SUCCESS (0.096s)
[623.684446] Subtest legacy-engines-hang: SUCCESS (0.461s)
[623.762612] [114/133] (402s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[623.823117] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[623.929333] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[624.013258] [115/133] (402s left) gem_exec_suspend (basic-s4-devices)
[624.068701] Starting subtest: basic-S4-devices
[624.071448] Starting dynamic subtest: smem
[635.558798] Dynamic subtest smem: SUCCESS (11.438s)
[635.561917] Subtest basic-S4-devices: SUCCESS (11.441s)
[641.764546] [116/133] (391s left) gem_busy (extended)
[641.971731] Starting subtest: extended
[641.973900] Starting dynamic subtest: rcs0
[642.051812] Dynamic subtest rcs0: SUCCESS (0.084s)
[642.064063] Starting dynamic subtest: bcs0
[642.097760] Dynamic subtest bcs0: SUCCESS (0.043s)
[642.109563] Starting dynamic subtest: vcs0
[643.559633] Dynamic subtest vcs0: SUCCESS (1.457s)
[643.572616] Starting dynamic subtest: vcs1
[645.662915] Dynamic subtest vcs1: SUCCESS (2.043s)
[645.676081] Starting dynamic subtest: vecs0
[647.590747] Dynamic subtest vecs0: SUCCESS (1.980s)
[647.604040] Starting dynamic subtest: ccs0
[647.637792] Dynamic subtest ccs0: SUCCESS (0.046s)
[647.640554] Subtest extended: SUCCESS (5.666s)
[647.875480] [117/133] (385s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[647.960124] Starting subtest: fbcpsr-tiling-4
[654.941140] Subtest fbcpsr-tiling-4: SUCCESS (6.981s)
[655.078663] [118/133] (377s left) kms_vblank (ts-continuation-modeset-hang)
[655.158929] Starting subtest: ts-continuation-modeset-hang
[655.161850] Starting dynamic subtest: pipe-A-eDP-1
[681.431098] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.262s)
[681.448259] Starting dynamic subtest: pipe-D-eDP-1
[706.409739] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.963s)
[706.412490] Subtest ts-continuation-modeset-hang: SUCCESS (51.230s)
[707.227983] [119/133] (325s left) gen9_exec_parse (secure-batches)
[707.295080] Subtest secure-batches: SKIP (0.000s)
[707.367455] [120/133] (325s left) kms_chamelium_edid (dp-edid-read)
[707.729478] Subtest dp-edid-read: SKIP (0.000s)
[708.531448] [121/133] (324s left) gem_userptr_blits (forbidden-operations)
[708.599988] Starting subtest: forbidden-operations
[708.605777] Subtest forbidden-operations: SKIP (0.000s)
[708.720376] [122/133] (324s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[708.801084] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[708.805975] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[708.891258] [123/133] (324s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[708.982692] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[708.987323] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[709.080570] [124/133] (324s left) gem_mmap_gtt (isolation)
[709.137811] Subtest isolation: SKIP (0.000s)
[709.201560] [125/133] (324s left) gem_mmap_gtt (cpuset-big-copy-xy)
[709.249122] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[709.307708] [126/133] (323s left) vgem_basic (setversion)
[709.345879] Starting subtest: setversion
[709.351662] Subtest setversion: SUCCESS (0.000s)
[709.398727] [127/133] (323s left) kms_plane_multiple (tiling-4)
[709.481558] Starting subtest: tiling-4
[709.483926] Starting dynamic subtest: pipe-A-eDP-1
[713.179386] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.695s)
[713.189971] Starting dynamic subtest: pipe-B-eDP-1
[717.631895] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.449s)
[717.649437] Starting dynamic subtest: pipe-C-eDP-1
[722.025080] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.392s)
[722.036017] Starting dynamic subtest: pipe-D-eDP-1
[726.476266] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.446s)
[726.479010] Subtest tiling-4: SUCCESS (16.987s)
[727.284743] [128/133] (306s left) kms_atomic_interruptible (legacy-pageflip)
[727.384831] Starting subtest: legacy-pageflip
[727.387233] Starting dynamic subtest: pipe-A-eDP-1
[734.821866] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.433s)
[734.824682] Subtest legacy-pageflip: SUCCESS (7.435s)
[735.665562] [129/133] (297s left) gem_ppgtt (blt-vs-render-ctxn)
[735.723108] Starting subtest: blt-vs-render-ctxN
[769.514526] Subtest blt-vs-render-ctxN: SUCCESS (33.773s)
[769.638419] [130/133] (263s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[769.772957] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[778.489103] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.720s)
[778.613102] [131/133] (254s left) gem_eio (in-flight-contexts-immediate)
[778.858475] Starting subtest: in-flight-contexts-immediate
[792.635912] Subtest in-flight-contexts-immediate: SUCCESS (13.790s)
[792.796965] [132/133] (240s left) perf (oa-formats)
[792.949075] Starting subtest: oa-formats
[792.951586] Starting dynamic subtest: 0-rcs0
[792.965078] Dynamic subtest 0-rcs0: SUCCESS (0.017s)
[792.976709] Starting dynamic subtest: 1-vcs0
[792.989081] Dynamic subtest 1-vcs0: SUCCESS (0.023s)
[792.991858] Subtest oa-formats: SUCCESS (0.042s)
[793.042099] [133/133] (240s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[793.105866] Starting subtest: multi-wait-all-for-submit-submitted
[793.210934] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[793.283618] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_params
results: parsing output: 1/ for test: kms_plane_scaling
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: kms_atomic
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: gem_create
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_invalid_mode
results: parsing output: 10/ for test: sysfs_timeslice_duration
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: gem_userptr_blits
results: parsing output: 13/ for test: kms_prop_blob
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_busy
results: parsing output: 16/ for test: gem_ctx_shared
results: parsing output: 17/ for test: perf_pmu
results: parsing output: 18/ for test: gem_userptr_blits
results: parsing output: 19/ for test: i915_drm_fdinfo
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_readwrite
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: syncobj_wait
results: parsing output: 27/ for test: kms_selftest
results: parsing output: 28/ for test: kms_lease
results: parsing output: 29/ for test: kms_content_protection
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: kms_pipe_b_c_ivb
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: core_setmaster
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: gem_render_copy
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_mmap_wc
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: kms_psr2_sf
results: parsing output: 44/ for test: gem_exec_whisper
results: parsing output: 45/ for test: kms_feature_discovery
results: parsing output: 46/ for test: gem_pxp
results: parsing output: 47/ for test: gem_exec_balancer
results: parsing output: 48/ for test: gem_render_copy
results: parsing output: 49/ for test: i915_pm_rps
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_chamelium_hpd
results: parsing output: 54/ for test: gem_caching
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_async_flips
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: gem_ctx_bad_destroy
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_parallel
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: gem_exec_flush
results: parsing output: 66/ for test: kms_pm_rpm
results: parsing output: 67/ for test: i915_hangman
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: i915_pm_rps
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: gem_mmap_wc
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_properties
results: parsing output: 77/ for test: gem_exec_fence
results: parsing output: 78/ for test: kms_flip_scaled_crc
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: syncobj_basic
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_exec_balancer
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: prime_vgem
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: kms_psr2_sf
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: gem_softpin
results: parsing output: 90/ for test: gem_ctx_param
results: parsing output: 91/ for test: gem_exec_schedule
results: parsing output: 92/ for test: kms_color_pipeline
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: gem_render_tiled_blits
results: parsing output: 99/ for test: kms_pm_backlight
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: gem_lmem_swapping
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: api_intel_bb
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_render_copy
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: gem_ctx_persistence
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: gem_exec_suspend
results: parsing output: 115/ for test: gem_busy
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_vblank
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: kms_chamelium_edid
results: parsing output: 120/ for test: gem_userptr_blits
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_mmap_gtt
results: parsing output: 124/ for test: gem_mmap_gtt
results: parsing output: 125/ for test: vgem_basic
results: parsing output: 126/ for test: kms_plane_multiple
results: parsing output: 127/ for test: kms_atomic_interruptible
results: parsing output: 128/ for test: gem_ppgtt
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_eio
results: parsing output: 131/ for test: perf
results: parsing output: 132/ for test: syncobj_wait
Done.