[56.710666] Initializing watchdogs
[56.710713]   /dev/watchdog0
[56.725074] [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]
[56.729771] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.732388] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.735794] [001/133] (960s left) gem_exec_params (dr4-dirt)
[56.828851] Starting subtest: DR4-dirt
[56.831143] Subtest DR4-dirt: SUCCESS (0.000s)
[56.962816] [002/133] (960s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[57.054673] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[57.056868] Starting dynamic subtest: pipe-A
[57.293608] Dynamic subtest pipe-A: SUCCESS (0.241s)
[57.300625] Starting dynamic subtest: pipe-B
[58.558123] Dynamic subtest pipe-B: SUCCESS (1.264s)
[58.565245] Starting dynamic subtest: pipe-C
[59.900874] Dynamic subtest pipe-C: SUCCESS (1.342s)
[59.909672] Starting dynamic subtest: pipe-D
[61.234526] Dynamic subtest pipe-D: SUCCESS (1.333s)
[61.236836] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.182s)
[62.420206] [003/133] (954s left) kms_psr (psr2-cursor-blt)
[62.505945] Starting subtest: psr2-cursor-blt
[62.508306] Starting dynamic subtest: eDP-1
[64.206263] Dynamic subtest eDP-1: SUCCESS (1.700s)
[64.208481] Subtest psr2-cursor-blt: SUCCESS (1.700s)
[64.992894] [004/133] (952s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[65.095841] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[65.122573] Starting dynamic subtest: pipe-A-plane-0
[65.555931] Dynamic subtest pipe-A-plane-0: SUCCESS (0.433s)
[65.563199] Starting dynamic subtest: pipe-A-plane-3
[65.822734] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[66.889879] Starting dynamic subtest: pipe-B-plane-0
[67.393753] Dynamic subtest pipe-B-plane-0: SUCCESS (0.511s)
[67.402783] Starting dynamic subtest: pipe-B-plane-3
[67.672259] Dynamic subtest pipe-B-plane-3: SUCCESS (0.277s)
[67.994623] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.896s)
[68.877627] [005/133] (948s left) kms_atomic (plane-primary-legacy)
[68.968866] Starting subtest: plane-primary-legacy
[68.971121] Starting dynamic subtest: pipe-A-eDP-1
[69.440740] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.471s)
[69.443075] Subtest plane-primary-legacy: SUCCESS (0.472s)
[70.306885] [006/133] (947s left) syncobj_wait (signal)
[70.381478] Starting subtest: signal
[70.383859] Subtest signal: SUCCESS (0.000s)
[70.475496] [007/133] (946s left) gem_create (busy-create)
[70.537091] Starting subtest: busy-create
[70.539435] Starting dynamic subtest: smem0
[103.880990] Dynamic subtest smem0: SUCCESS (33.341s)
[103.883737] Subtest busy-create: SUCCESS (33.342s)
[104.072496] [008/133] (913s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[104.180938] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[104.183398] Starting dynamic subtest: pipe-A
[104.541216] Dynamic subtest pipe-A: SUCCESS (0.362s)
[104.550433] Starting dynamic subtest: pipe-B
[105.994061] Dynamic subtest pipe-B: SUCCESS (1.453s)
[106.002866] Starting dynamic subtest: pipe-C
[107.435189] Dynamic subtest pipe-C: SUCCESS (1.439s)
[107.444503] Starting dynamic subtest: pipe-D
[108.868275] Dynamic subtest pipe-D: SUCCESS (1.434s)
[108.870814] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.689s)
[110.020723] [009/133] (907s left) kms_flip (bo-too-big)
[110.126285] Starting subtest: bo-too-big
[110.128714] Starting dynamic subtest: A-eDP1
[111.476284] Dynamic subtest A-eDP1: SUCCESS (1.350s)
[111.483828] Starting dynamic subtest: B-eDP1
[112.795060] Dynamic subtest B-eDP1: SUCCESS (1.317s)
[112.803333] Starting dynamic subtest: C-eDP1
[114.128506] Dynamic subtest C-eDP1: SUCCESS (1.332s)
[114.135916] Starting dynamic subtest: D-eDP1
[115.456985] Dynamic subtest D-eDP1: SUCCESS (1.328s)
[115.459453] Subtest bo-too-big: SUCCESS (5.335s)
[116.669830] [010/133] (900s left) kms_invalid_mode (overflow-vrefresh)
[116.776300] Starting subtest: overflow-vrefresh
[116.778514] Starting dynamic subtest: pipe-A-eDP-1
[117.059441] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[117.068199] Starting dynamic subtest: pipe-B-eDP-1
[117.070086] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[117.076745] Starting dynamic subtest: pipe-C-eDP-1
[117.078657] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[117.085820] Starting dynamic subtest: pipe-D-eDP-1
[117.088231] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[117.090575] Subtest overflow-vrefresh: SUCCESS (0.290s)
[117.899050] [011/133] (899s left) sysfs_timeslice_duration (timeout)
[117.950846] Starting subtest: timeout
[117.953167] Starting dynamic subtest: vecs0
[127.889073] Dynamic subtest vecs0: SUCCESS (9.934s)
[127.901519] Starting dynamic subtest: ccs0
[129.206511] Dynamic subtest ccs0: SUCCESS (1.317s)
[129.214590] Starting dynamic subtest: vcs0
[138.909149] Dynamic subtest vcs0: SUCCESS (9.703s)
[138.919334] Starting dynamic subtest: bcs0
[139.515089] Dynamic subtest bcs0: SUCCESS (0.603s)
[139.525176] Starting dynamic subtest: rcs0
[140.758298] Dynamic subtest rcs0: SUCCESS (1.243s)
[140.770339] Starting dynamic subtest: vcs1
[149.909986] Dynamic subtest vcs1: SUCCESS (9.146s)
[149.912748] Subtest timeout: SUCCESS (31.954s)
[149.990267] [012/133] (867s left) kms_psr (psr-sprite-blt)
[150.093786] Starting subtest: psr-sprite-blt
[150.096061] Starting dynamic subtest: eDP-1
[151.815959] Dynamic subtest eDP-1: SUCCESS (1.722s)
[151.818535] Subtest psr-sprite-blt: SUCCESS (1.723s)
[152.650431] [013/133] (865s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[152.712729] Starting subtest: stress-mm-invalidate-close-overlap
[154.859826] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[154.968625] [014/133] (862s left) kms_prop_blob (blob-prop-lifetime)
[155.025893] Starting subtest: blob-prop-lifetime
[155.028299] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[155.133698] [015/133] (862s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[155.232958] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[162.517348] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.288s)
[163.642285] [016/133] (854s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[163.741428] Starting subtest: extended-modeset-hang-oldfb-with-reset
[163.743437] Starting dynamic subtest: pipe-A
[175.613110] Dynamic subtest pipe-A: SUCCESS (11.871s)
[175.621530] Starting dynamic subtest: pipe-D
[187.885882] Dynamic subtest pipe-D: SUCCESS (12.271s)
[187.888300] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.147s)
[188.754632] [017/133] (829s left) gem_ctx_shared (single-timeline)
[188.825637] Starting subtest: single-timeline
[188.847169] Subtest single-timeline: SUCCESS (0.014s)
[189.042884] [018/133] (828s left) perf_pmu (busy-check-all)
[189.090076] Starting subtest: busy-check-all
[189.092493] Starting dynamic subtest: rcs0
[189.646742] Dynamic subtest rcs0: SUCCESS (0.557s)
[189.658226] Starting dynamic subtest: bcs0
[190.197257] Dynamic subtest bcs0: SUCCESS (0.549s)
[190.208986] Starting dynamic subtest: vcs0
[191.897671] Dynamic subtest vcs0: SUCCESS (1.697s)
[191.909333] Starting dynamic subtest: vcs1
[193.881247] Dynamic subtest vcs1: SUCCESS (1.982s)
[193.892911] Starting dynamic subtest: vecs0
[195.929361] Dynamic subtest vecs0: SUCCESS (2.046s)
[195.940189] Starting dynamic subtest: ccs0
[196.493449] Dynamic subtest ccs0: SUCCESS (0.564s)
[196.495958] Subtest busy-check-all: SUCCESS (7.405s)
[196.610539] [019/133] (821s left) gem_userptr_blits (probe)
[196.696108] Starting subtest: probe
[196.777039] Subtest probe: SUCCESS (0.081s)
[196.924508] [020/133] (821s left) i915_drm_fdinfo (most-busy-idle-check-all)
[196.964628] Starting subtest: most-busy-idle-check-all
[196.966959] Starting dynamic subtest: rcs0
[196.974059] Dynamic subtest rcs0: SKIP (0.007s)
[196.984877] Starting dynamic subtest: bcs0
[196.989495] Dynamic subtest bcs0: SKIP (0.000s)
[196.999479] Starting dynamic subtest: vcs0
[197.003812] Dynamic subtest vcs0: SKIP (0.000s)
[197.012214] Starting dynamic subtest: vcs1
[197.016509] Dynamic subtest vcs1: SKIP (0.000s)
[197.024881] Starting dynamic subtest: vecs0
[197.029220] Dynamic subtest vecs0: SKIP (0.000s)
[197.037545] Starting dynamic subtest: ccs0
[197.041616] Dynamic subtest ccs0: SKIP (0.000s)
[197.045638] Subtest most-busy-idle-check-all: SKIP (0.010s)
[197.083957] [021/133] (820s left) kms_big_fb (linear-16bpp-rotate-0)
[197.220167] Starting subtest: linear-16bpp-rotate-0
[198.607977] Subtest linear-16bpp-rotate-0: SUCCESS (1.389s)
[198.730581] [022/133] (819s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[198.834768] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[198.838111] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[198.951174] [023/133] (819s left) gem_readwrite (new-obj)
[199.005301] Subtest new-obj: SKIP (0.000s)
[199.071472] [024/133] (819s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[199.177226] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[199.180818] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[199.270137] [025/133] (818s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[199.362128] Starting subtest: flip-vs-dpms-off-vs-modeset
[199.364044] Starting dynamic subtest: A-eDP1
[202.994918] Dynamic subtest A-eDP1: SUCCESS (3.631s)
[203.006263] Starting dynamic subtest: B-eDP1
[206.208248] Dynamic subtest B-eDP1: SUCCESS (3.214s)
[206.217680] Starting dynamic subtest: C-eDP1
[209.330450] Dynamic subtest C-eDP1: SUCCESS (3.120s)
[209.340242] Starting dynamic subtest: D-eDP1
[212.468309] Dynamic subtest D-eDP1: SUCCESS (3.138s)
[212.470584] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.109s)
[213.271663] [026/133] (804s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[213.367146] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[213.369451] Starting dynamic subtest: pipe-A-eDP-1
[213.892336] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.495s)
[213.902968] Starting dynamic subtest: pipe-B-eDP-1
[215.367016] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.475s)
[215.377392] Starting dynamic subtest: pipe-C-eDP-1
[216.769555] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.401s)
[216.780839] Starting dynamic subtest: pipe-D-eDP-1
[218.118744] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.350s)
[218.120666] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.725s)
[219.293794] [027/133] (798s left) syncobj_wait (wait-any-interrupted)
[219.354083] Starting subtest: wait-any-interrupted
[219.457052] Subtest wait-any-interrupted: SUCCESS (0.102s)
[219.531277] [028/133] (798s left) kms_selftest (drm_plane_helper)
[219.568402] Starting subtest: drm_plane_helper
[219.620380] Starting dynamic subtest: drm_test_check_plane_state
[219.728943] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.110s)
[219.737605] Starting dynamic subtest: drm_test_check_invalid_plane_state
[219.837285] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.108s)
[219.839132] Subtest drm_plane_helper: SUCCESS (0.269s)
[219.987296] [029/133] (798s left) kms_lease (lease-uevent)
[220.086936] Starting subtest: lease-uevent
[221.097339] Subtest lease-uevent: SUCCESS (1.011s)
[221.170544] [030/133] (797s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[221.313884] Starting subtest: dp-mst-type-1-suspend-resume
[221.318609] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[221.444669] [031/133] (796s left) syncobj_wait (wait-all-delayed-signal)
[221.506399] Starting subtest: wait-all-delayed-signal
[221.607863] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[221.683224] [032/133] (796s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[221.747230] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[221.806170] [033/133] (796s left) syncobj_wait (wait-all-for-submit-complex)
[221.865598] Starting subtest: wait-all-for-submit-complex
[222.078798] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[222.163922] [034/133] (796s left) core_setmaster (master-drop-set-root)
[222.221231] Starting subtest: master-drop-set-root
[222.247640] Subtest master-drop-set-root: SUCCESS (0.027s)
[222.297007] [035/133] (796s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[222.384917] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[222.389447] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[223.527724] [036/133] (795s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[223.589578] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[223.594178] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[223.772687] [037/133] (794s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[223.891253] Starting subtest: 4-tiled-32bpp-rotate-180
[225.392997] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.503s)
[225.548576] [038/133] (793s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[225.649014] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[232.340200] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.693s)
[232.453394] [039/133] (786s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[232.547694] Starting subtest: bad-rotation-90-yf-tiled-ccs
[232.549905] Starting dynamic subtest: pipe-A-eDP-1
[232.573359] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[232.583096] Starting dynamic subtest: pipe-B-eDP-1
[232.605058] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[232.613779] Starting dynamic subtest: pipe-C-eDP-1
[232.634935] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[232.643490] Starting dynamic subtest: pipe-D-eDP-1
[232.665394] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[232.669418] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[234.791477] [040/133] (783s left) gem_mmap_wc (coherency)
[234.850488] Subtest coherency: SKIP (0.000s)
[234.909142] [041/133] (783s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[234.959062] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[234.961427] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[235.041314] [042/133] (783s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[235.136405] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[235.141075] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[235.229110] [043/133] (783s left) kms_plane_alpha_blend (constant-alpha-mid)
[235.325758] Starting subtest: constant-alpha-mid
[235.610831] Starting dynamic subtest: pipe-A-eDP-1
[236.539340] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.926s)
[236.548495] Starting dynamic subtest: pipe-D-eDP-1
[238.041809] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.500s)
[238.043841] Subtest constant-alpha-mid: SUCCESS (2.716s)
[238.876525] [044/133] (780s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[238.991074] Starting subtest: psr2-overlay-plane-update-continuous-sf
[238.993299] Starting dynamic subtest: pipe-A-eDP-1
[240.119294] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.131s)
[240.128081] Starting dynamic subtest: pipe-B-eDP-1
[241.829049] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.708s)
[241.831343] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.842s)
[242.639181] [045/133] (776s left) gem_exec_whisper (basic-contexts-priority)
[242.701808] Starting subtest: basic-contexts-priority
[267.422473] Subtest basic-contexts-priority: SUCCESS (24.719s)
[267.605939] [046/133] (751s left) kms_feature_discovery (chamelium)
[267.708946] Starting subtest: chamelium
[267.713364] Subtest chamelium: SKIP (0.000s)
[267.793277] [047/133] (751s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[267.916675] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[268.050900] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s)
[268.189859] [048/133] (750s left) gem_exec_balancer (persistence)
[268.481596] Starting subtest: persistence
[269.398193] Subtest persistence: SUCCESS (0.916s)
[269.511229] [049/133] (749s left) gem_render_copy (linear)
[269.573594] Starting subtest: linear
[269.576063] Starting dynamic subtest: smem
[269.615702] Dynamic subtest smem: SUCCESS (0.042s)
[269.618271] Subtest linear: SUCCESS (0.042s)
[269.847610] [050/133] (749s left) i915_pm_rps (thresholds)
[269.907185] Starting subtest: thresholds
[269.911486] Subtest thresholds: SKIP (0.001s)
[270.030845] [051/133] (749s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[270.134415] Starting subtest: yf-tiled-32bpp-rotate-0
[270.138959] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[270.233126] [052/133] (749s left) api_intel_bb (object-reloc-purge-cache)
[270.297449] Starting subtest: object-reloc-purge-cache
[270.301593] Subtest object-reloc-purge-cache: SKIP (0.003s)
[270.405065] [053/133] (748s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[270.500412] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[270.504746] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[270.596975] [054/133] (748s left) kms_chamelium_hpd (hdmi-hpd-storm)
[270.968736] Subtest hdmi-hpd-storm: SKIP (0.000s)
[271.753903] [055/133] (747s left) gem_caching (writes)
[271.813355] Subtest writes: SKIP (0.000s)
[271.880068] [056/133] (747s left) kms_cursor_legacy (forked-bo)
[271.995565] Starting subtest: forked-bo
[271.997647] Starting dynamic subtest: pipe-A
[277.452622] Dynamic subtest pipe-A: SUCCESS (5.455s)
[277.462368] Starting dynamic subtest: pipe-B
[282.940246] Dynamic subtest pipe-B: SUCCESS (5.484s)
[282.951998] Starting dynamic subtest: pipe-C
[288.425237] Dynamic subtest pipe-C: SUCCESS (5.482s)
[288.438382] Starting dynamic subtest: pipe-D
[293.911809] Dynamic subtest pipe-D: SUCCESS (5.483s)
[293.923545] Starting dynamic subtest: all-pipes
[299.424797] Dynamic subtest all-pipes: SUCCESS (5.509s)
[299.427469] Subtest forked-bo: SUCCESS (27.426s)
[299.567163] [057/133] (719s left) kms_async_flips (crc)
[299.671586] Starting subtest: crc
[300.057437] Starting dynamic subtest: pipe-A-eDP-1
[302.993515] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.936s)
[303.370743] Starting dynamic subtest: pipe-B-eDP-1
[306.324918] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.962s)
[306.712250] Starting dynamic subtest: pipe-C-eDP-1
[309.645038] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.941s)
[310.020520] Starting dynamic subtest: pipe-D-eDP-1
[312.888490] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.878s)
[312.890827] Subtest crc: SUCCESS (13.218s)
[314.038847] [058/133] (705s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[314.152441] Starting subtest: yf-tiled-8bpp-rotate-90
[314.156785] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[314.243493] [059/133] (705s left) gem_ctx_bad_destroy (invalid-pad)
[314.299966] Starting subtest: invalid-pad
[314.302206] Subtest invalid-pad: SUCCESS (0.000s)
[314.397386] [060/133] (705s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[314.494684] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[314.496940] Starting dynamic subtest: pipe-A-eDP-1
[314.520316] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[314.530627] Starting dynamic subtest: pipe-B-eDP-1
[314.552169] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[314.560856] Starting dynamic subtest: pipe-C-eDP-1
[314.582454] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.591053] Starting dynamic subtest: pipe-D-eDP-1
[314.612178] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.616221] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[316.719183] [061/133] (702s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[316.820337] Starting subtest: y-tiled-16bpp-rotate-180
[316.824338] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[316.912489] [062/133] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[317.022690] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[317.026730] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[317.114948] [063/133] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[317.223085] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[317.227489] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[317.319904] [064/133] (702s left) gem_exec_parallel (engines)
[317.390611] Starting subtest: engines
[317.395157] Starting dynamic subtest: basic
[318.953794] Dynamic subtest basic: SUCCESS (1.565s)
[318.965096] Starting dynamic subtest: contexts
[323.280721] Dynamic subtest contexts: SUCCESS (4.324s)
[323.296287] Starting dynamic subtest: fds
[327.073061] Dynamic subtest fds: SUCCESS (3.790s)
[327.085218] Starting dynamic subtest: userptr
[328.921381] Dynamic subtest userptr: SUCCESS (1.846s)
[328.923923] Subtest engines: SUCCESS (11.533s)
[329.129833] [065/133] (690s left) kms_cursor_crc (cursor-onscreen-256x85)
[329.393734] Starting subtest: cursor-onscreen-256x85
[329.442098] Subtest cursor-onscreen-256x85: SKIP (0.043s)
[329.629764] [066/133] (690s left) gem_exec_flush (basic-uc-prw-default)
[329.691495] Starting subtest: basic-uc-prw-default
[335.181638] Subtest basic-uc-prw-default: SUCCESS (5.490s)
[335.368474] [067/133] (684s left) kms_pm_rpm (modeset-lpsp)
[337.116439] Starting subtest: modeset-lpsp
[338.578250] Subtest modeset-lpsp: SUCCESS (1.461s)
[338.662410] [068/133] (681s left) i915_hangman (gt-error-state-capture)
[338.744208] Starting subtest: gt-error-state-capture
[338.746511] Starting dynamic subtest: rcs0
[349.879684] Dynamic subtest rcs0: SUCCESS (11.135s)
[349.892337] Starting dynamic subtest: bcs0
[361.847367] Dynamic subtest bcs0: SUCCESS (11.964s)
[361.859180] Starting dynamic subtest: vcs0
[373.815570] Dynamic subtest vcs0: SUCCESS (11.965s)
[373.832682] Starting dynamic subtest: vcs1
[385.846221] Dynamic subtest vcs1: SUCCESS (12.028s)
[385.856750] Starting dynamic subtest: vecs0
[397.815360] Dynamic subtest vecs0: SUCCESS (11.966s)
[397.827406] Starting dynamic subtest: ccs0
[409.846209] Dynamic subtest ccs0: SUCCESS (12.027s)
[409.848977] Subtest gt-error-state-capture: SUCCESS (71.102s)
[409.964641] [069/133] (610s left) kms_psr (psr2-primary-page-flip)
[410.062286] Starting subtest: psr2-primary-page-flip
[410.064548] Starting dynamic subtest: eDP-1
[411.797802] Dynamic subtest eDP-1: SUCCESS (1.736s)
[411.800112] Subtest psr2-primary-page-flip: SUCCESS (1.737s)
[412.683017] [070/133] (607s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[412.789101] Starting subtest: x-tiled-64bpp-rotate-0
[414.501765] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.712s)
[414.653203] [071/133] (605s left) i915_pm_rps (min-max-config-loaded)
[414.707469] Starting subtest: min-max-config-loaded
[414.711980] Subtest min-max-config-loaded: SKIP (0.000s)
[414.784594] [072/133] (605s left) perf_pmu (busy-no-semaphores)
[414.825236] Starting subtest: busy-no-semaphores
[414.826798] Starting dynamic subtest: rcs0
[415.387233] Dynamic subtest rcs0: SUCCESS (0.560s)
[415.397919] Starting dynamic subtest: bcs0
[415.937698] Dynamic subtest bcs0: SUCCESS (0.547s)
[415.949660] Starting dynamic subtest: vcs0
[416.487542] Dynamic subtest vcs0: SUCCESS (0.547s)
[416.499772] Starting dynamic subtest: vcs1
[418.459464] Dynamic subtest vcs1: SUCCESS (1.969s)
[418.471680] Starting dynamic subtest: vecs0
[420.444608] Dynamic subtest vecs0: SUCCESS (1.982s)
[420.457313] Starting dynamic subtest: ccs0
[422.453982] Dynamic subtest ccs0: SUCCESS (1.981s)
[422.458109] Subtest busy-no-semaphores: SUCCESS (7.603s)
[422.538196] [073/133] (597s left) kms_flip (blocking-wf_vblank)
[422.641231] Starting subtest: blocking-wf_vblank
[422.643646] Starting dynamic subtest: A-eDP1
[431.542425] Dynamic subtest A-eDP1: SUCCESS (8.900s)
[431.552117] Starting dynamic subtest: B-eDP1
[440.478641] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[440.491164] Starting dynamic subtest: C-eDP1
[449.404792] Dynamic subtest C-eDP1: SUCCESS (8.923s)
[449.415819] Starting dynamic subtest: D-eDP1
[458.341460] Dynamic subtest D-eDP1: SUCCESS (8.937s)
[458.346329] Subtest blocking-wf_vblank: SUCCESS (35.706s)
[459.585659] [074/133] (560s left) gem_mmap_wc (read-write)
[459.645141] Subtest read-write: SKIP (0.000s)
[459.700450] [075/133] (560s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[459.827667] Starting subtest: basic-flip-after-cursor-varying-size
[460.757221] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.927s)
[461.598475] [076/133] (558s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[461.658827] Starting subtest: etime-multi-wait-all-for-submit-submitted
[461.765296] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[461.853666] [077/133] (558s left) kms_properties (plane-properties-legacy)
[461.958875] Starting subtest: plane-properties-legacy
[461.961094] Starting dynamic subtest: pipe-A-eDP-1
[462.523243] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.562s)
[462.532391] Starting dynamic subtest: pipe-B-eDP-1
[463.757234] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.232s)
[463.772029] Starting dynamic subtest: pipe-C-eDP-1
[464.982129] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.226s)
[464.993186] Starting dynamic subtest: pipe-D-eDP-1
[466.207957] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.224s)
[466.210427] Subtest plane-properties-legacy: SUCCESS (4.250s)
[467.008920] [078/133] (553s left) gem_exec_fence (syncobj-invalid-flags)
[467.515219] Starting subtest: syncobj-invalid-flags
[467.517541] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[468.984033] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[468.987411] [079/133] (551s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[469.090160] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[469.094601] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[469.182679] [080/133] (551s left) gem_mmap_gtt (basic-small-bo-tiledy)
[469.235696] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[469.298183] [081/133] (551s left) syncobj_basic (bad-destroy)
[469.360663] Starting subtest: bad-destroy
[469.363030] Subtest bad-destroy: SUCCESS (0.000s)
[469.476545] [082/133] (551s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[469.575062] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[476.554592] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.981s)
[476.681125] [083/133] (543s left) gem_exec_balancer (hang)
[476.945959] Starting subtest: hang
[478.940573] Subtest hang: SUCCESS (1.998s)
[479.054438] [084/133] (541s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[479.147771] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[479.150105] Starting dynamic subtest: pipe-A-eDP-1
[480.016613] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.862s)
[480.025912] Starting dynamic subtest: pipe-B-eDP-1
[481.646334] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.628s)
[481.662033] Starting dynamic subtest: pipe-C-eDP-1
[483.201309] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.553s)
[483.211471] Starting dynamic subtest: pipe-D-eDP-1
[484.753069] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.551s)
[484.755736] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.599s)
[485.984876] [085/133] (534s left) prime_vgem (basic-blt)
[486.076229] Starting subtest: basic-blt
[486.109069] Subtest basic-blt: SUCCESS (0.033s)
[486.232915] [086/133] (534s left) gem_exec_schedule (implicit-write-read)
[486.294091] Starting subtest: implicit-write-read
[486.296336] Starting dynamic subtest: rcs0
[486.393554] Dynamic subtest rcs0: SUCCESS (0.109s)
[486.407192] Starting dynamic subtest: vcs0
[486.484731] Dynamic subtest vcs0: SUCCESS (0.090s)
[486.499269] Starting dynamic subtest: vcs1
[486.569330] Dynamic subtest vcs1: SUCCESS (0.084s)
[486.583423] Starting dynamic subtest: vecs0
[486.682874] Dynamic subtest vecs0: SUCCESS (0.110s)
[486.695352] Starting dynamic subtest: ccs0
[486.771715] Dynamic subtest ccs0: SUCCESS (0.088s)
[486.774200] Subtest implicit-write-read: SUCCESS (0.488s)
[487.981363] [087/133] (532s left) gem_userptr_blits (coherency-unsync)
[488.068794] Subtest coherency-unsync: SKIP (0.000s)
[488.208364] [088/133] (532s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[488.302060] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[488.305942] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[488.370276] [089/133] (532s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[488.474710] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[488.486862] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[488.562960] [090/133] (532s left) gem_softpin (evict-prime)
[488.630024] Starting subtest: evict-prime
[488.632301] Starting dynamic subtest: rcs0
[489.778423] Dynamic subtest rcs0: SUCCESS (1.148s)
[489.788722] Starting dynamic subtest: bcs0
[490.917055] Dynamic subtest bcs0: SUCCESS (1.137s)
[490.928838] Starting dynamic subtest: vcs0
[492.891972] Dynamic subtest vcs0: SUCCESS (1.971s)
[492.904055] Starting dynamic subtest: vcs1
[494.869533] Dynamic subtest vcs1: SUCCESS (1.975s)
[494.881454] Starting dynamic subtest: vecs0
[496.923821] Dynamic subtest vecs0: SUCCESS (2.052s)
[496.936688] Starting dynamic subtest: ccs0
[498.048633] Dynamic subtest ccs0: SUCCESS (1.124s)
[498.058934] Starting dynamic subtest: all
[499.870528] Dynamic subtest all: SUCCESS (1.818s)
[499.873078] Subtest evict-prime: SUCCESS (11.240s)
[499.953549] [091/133] (520s left) gem_ctx_param (invalid-get-engines)
[500.008812] Starting subtest: invalid-get-engines
[500.011146] Subtest invalid-get-engines: SUCCESS (0.001s)
[500.121464] [092/133] (520s left) gem_exec_schedule (timeslicing)
[500.198257] Starting subtest: timeslicing
[500.200596] Starting dynamic subtest: rcs0
[500.216511] Dynamic subtest rcs0: SUCCESS (0.029s)
[500.227841] Starting dynamic subtest: bcs0
[500.244491] Dynamic subtest bcs0: SUCCESS (0.026s)
[500.253921] Starting dynamic subtest: vcs0
[500.268307] Dynamic subtest vcs0: SUCCESS (0.023s)
[500.277553] Starting dynamic subtest: vcs1
[500.290201] Dynamic subtest vcs1: SUCCESS (0.021s)
[500.299347] Starting dynamic subtest: vecs0
[500.312241] Dynamic subtest vecs0: SUCCESS (0.021s)
[500.321301] Starting dynamic subtest: ccs0
[500.335502] Dynamic subtest ccs0: SUCCESS (0.022s)
[500.337826] Subtest timeslicing: SUCCESS (0.149s)
[501.954798] [093/133] (519s left) kms_color_pipeline (plane-ctm3x4)
[502.049859] Starting subtest: plane-ctm3x4
[502.229473] Starting dynamic subtest: pipe-A-plane-0
[502.558516] Dynamic subtest pipe-A-plane-0: FAIL (0.159s)
[502.570135] Starting dynamic subtest: pipe-A-plane-1
[502.781884] Dynamic subtest pipe-A-plane-1: FAIL (0.148s)
[502.792370] Starting dynamic subtest: pipe-A-plane-2
[503.014710] Dynamic subtest pipe-A-plane-2: FAIL (0.144s)
[503.851160] Starting dynamic subtest: pipe-B-plane-0
[504.242566] Dynamic subtest pipe-B-plane-0: FAIL (0.142s)
[504.253664] Starting dynamic subtest: pipe-B-plane-1
[504.545287] Dynamic subtest pipe-B-plane-1: FAIL (0.154s)
[504.556259] Starting dynamic subtest: pipe-B-plane-2
[504.858712] Dynamic subtest pipe-B-plane-2: FAIL (0.155s)
[505.492481] Starting dynamic subtest: pipe-C-plane-0
[505.929712] Dynamic subtest pipe-C-plane-0: FAIL (0.107s)
[505.939396] Starting dynamic subtest: pipe-C-plane-1
[506.326556] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[506.338062] Starting dynamic subtest: pipe-C-plane-2
[506.683709] Dynamic subtest pipe-C-plane-2: FAIL (0.602s)
[507.505942] Starting dynamic subtest: pipe-D-plane-0
[508.037116] Dynamic subtest pipe-D-plane-0: FAIL (0.108s)
[508.048430] Starting dynamic subtest: pipe-D-plane-1
[508.495545] Dynamic subtest pipe-D-plane-1: FAIL (0.595s)
[508.506711] Starting dynamic subtest: pipe-D-plane-2
[508.919849] Dynamic subtest pipe-D-plane-2: FAIL (0.546s)
[509.043448] Subtest plane-ctm3x4: FAIL (6.994s)
[509.878611] [094/133] (511s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[509.987370] Starting subtest: cursorA-vs-flipA-legacy
[521.163833] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.174s)
[522.017119] [095/133] (499s left) kms_cursor_crc (cursor-random-32x32)
[522.168044] Starting subtest: cursor-random-32x32
[522.222570] Subtest cursor-random-32x32: SKIP (0.050s)
[522.501079] [096/133] (498s left) sw_sync (alloc_merge_fence)
[522.548019] Starting subtest: alloc_merge_fence
[522.550277] Subtest alloc_merge_fence: SUCCESS (0.000s)
[522.609958] [097/133] (498s left) syncobj_wait (multi-wait-all-signaled)
[522.670900] Starting subtest: multi-wait-all-signaled
[522.673098] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[522.762506] [098/133] (498s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[522.824975] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[522.827258] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[522.934520] [099/133] (498s left) gem_render_tiled_blits (basic)
[522.981874] Subtest basic: SKIP (0.000s)
[523.050843] [100/133] (498s left) kms_pm_backlight (fade-with-suspend)
[523.149356] Starting subtest: fade-with-suspend
[539.470262] Starting dynamic subtest: eDP-1
[541.950697] Dynamic subtest eDP-1: SUCCESS (2.519s)
[541.952910] Subtest fade-with-suspend: SUCCESS (3.850s)
[542.823673] [101/133] (478s left) kms_cursor_crc (cursor-suspend)
[542.979104] Starting subtest: cursor-suspend
[542.981107] Starting dynamic subtest: pipe-A-eDP-1
[575.915456] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.143s)
[575.927975] Starting dynamic subtest: pipe-D-eDP-1
[609.904999] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.780s)
[609.907360] Subtest cursor-suspend: SUCCESS (6.927s)
[610.730292] [102/133] (410s left) gem_lmem_swapping (parallel-random-engines)
[610.773699] Subtest parallel-random-engines: SKIP (0.000s)
[610.812674] [103/133] (410s left) syncobj_timeline (invalid-query-bad-pad)
[610.876467] Starting subtest: invalid-query-bad-pad
[610.878584] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[610.975250] [104/133] (410s left) api_intel_bb (blit-noreloc-purge-cache)
[611.025713] Starting subtest: blit-noreloc-purge-cache
[611.034136] Subtest blit-noreloc-purge-cache: SUCCESS (0.011s)
[611.132765] [105/133] (410s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[611.230049] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[611.234068] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[611.336544] [106/133] (410s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[611.394334] Starting subtest: mixed-tiled-to-y-tiled-ccs
[611.398642] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[611.588637] [107/133] (409s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[611.683553] Starting subtest: psr-1p-primscrn-cur-indfb-move
[619.055900] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.444s)
[619.183018] [108/133] (402s left) kms_psr (fbc-psr-no-drrs)
[619.288286] Starting subtest: fbc-psr-no-drrs
[619.290554] Starting dynamic subtest: eDP-1
[619.295017] Dynamic subtest eDP-1: SKIP (0.000s)
[619.299227] Subtest fbc-psr-no-drrs: SKIP (0.003s)
[619.373045] [109/133] (402s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[619.470057] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[619.474124] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[619.571156] [110/133] (402s left) kms_rotation_crc (bad-pixel-format)
[619.667912] Starting subtest: bad-pixel-format
[619.672314] Subtest bad-pixel-format: SKIP (0.001s)
[619.743299] [111/133] (401s left) kms_psr (fbc-pr-sprite-render)
[619.831851] Starting subtest: fbc-pr-sprite-render
[619.836274] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[619.910497] [112/133] (401s left) syncobj_timeline (wait-all-delayed-signal)
[619.976516] Starting subtest: wait-all-delayed-signal
[620.077861] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[620.158501] [113/133] (401s left) gem_ctx_persistence (legacy-engines-hang)
[620.224679] Starting subtest: legacy-engines-hang
[620.227137] Starting dynamic subtest: render
[620.357766] Dynamic subtest render: SUCCESS (0.133s)
[620.373542] Starting dynamic subtest: bsd1
[620.450668] Dynamic subtest bsd1: SUCCESS (0.091s)
[620.465574] Starting dynamic subtest: bsd2
[620.548156] Dynamic subtest bsd2: SUCCESS (0.096s)
[620.563033] Starting dynamic subtest: blt
[620.644547] Dynamic subtest blt: SUCCESS (0.094s)
[620.659544] Starting dynamic subtest: vebox
[620.748565] Dynamic subtest vebox: SUCCESS (0.101s)
[620.751015] Subtest legacy-engines-hang: SUCCESS (0.528s)
[620.828319] [114/133] (401s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[620.887408] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[620.997462] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.109s)
[621.077044] [115/133] (400s left) gem_exec_suspend (basic-s4-devices)
[621.135360] Starting subtest: basic-S4-devices
[621.137853] Starting dynamic subtest: smem
[632.938451] Dynamic subtest smem: SUCCESS (11.830s)
[632.941545] Subtest basic-S4-devices: SUCCESS (11.832s)
[639.161499] [116/133] (388s left) gem_busy (extended)
[639.375576] Starting subtest: extended
[639.378191] Starting dynamic subtest: rcs0
[639.453144] Dynamic subtest rcs0: SUCCESS (0.078s)
[639.466511] Starting dynamic subtest: bcs0
[639.495801] Dynamic subtest bcs0: SUCCESS (0.040s)
[639.509413] Starting dynamic subtest: vcs0
[641.001408] Dynamic subtest vcs0: SUCCESS (1.502s)
[641.014945] Starting dynamic subtest: vcs1
[642.985202] Dynamic subtest vcs1: SUCCESS (1.980s)
[642.998818] Starting dynamic subtest: vecs0
[645.033143] Dynamic subtest vecs0: SUCCESS (2.045s)
[645.046583] Starting dynamic subtest: ccs0
[645.080717] Dynamic subtest ccs0: SUCCESS (0.046s)
[645.083086] Subtest extended: SUCCESS (5.706s)
[645.314309] [117/133] (382s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[645.407056] Starting subtest: fbcpsr-tiling-4
[652.529312] Subtest fbcpsr-tiling-4: SUCCESS (7.124s)
[652.702794] [118/133] (375s left) kms_vblank (ts-continuation-modeset-hang)
[652.807304] Starting subtest: ts-continuation-modeset-hang
[652.809631] Starting dynamic subtest: pipe-A-eDP-1
[678.760623] Dynamic subtest pipe-A-eDP-1: SUCCESS (25.958s)
[678.777046] Starting dynamic subtest: pipe-D-eDP-1
[703.795654] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.040s)
[703.798012] Subtest ts-continuation-modeset-hang: SUCCESS (51.003s)
[704.668146] [119/133] (323s left) gen9_exec_parse (secure-batches)
[704.729926] Subtest secure-batches: SKIP (0.000s)
[704.800046] [120/133] (323s left) kms_chamelium_edid (dp-edid-read)
[705.185595] Subtest dp-edid-read: SKIP (0.000s)
[706.032914] [121/133] (322s left) gem_userptr_blits (forbidden-operations)
[706.091354] Starting subtest: forbidden-operations
[706.096054] Subtest forbidden-operations: SKIP (0.000s)
[706.216148] [122/133] (322s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[706.318168] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[706.322509] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[706.431127] [123/133] (321s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[706.520375] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[706.524665] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[706.615921] [124/133] (321s left) gem_mmap_gtt (isolation)
[706.669486] Subtest isolation: SKIP (0.000s)
[706.728797] [125/133] (321s left) gem_mmap_gtt (cpuset-big-copy-xy)
[706.784755] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[706.843463] [126/133] (321s left) vgem_basic (setversion)
[706.882555] Starting subtest: setversion
[706.886685] Subtest setversion: SUCCESS (0.000s)
[706.950256] [127/133] (321s left) kms_plane_multiple (tiling-4)
[707.032256] Starting subtest: tiling-4
[707.034151] Starting dynamic subtest: pipe-A-eDP-1
[710.890211] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.857s)
[710.901625] Starting dynamic subtest: pipe-B-eDP-1
[715.258019] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.370s)
[715.269326] Starting dynamic subtest: pipe-C-eDP-1
[719.572065] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.315s)
[719.583849] Starting dynamic subtest: pipe-D-eDP-1
[723.901897] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.329s)
[723.904129] Subtest tiling-4: SUCCESS (16.876s)
[724.706366] [128/133] (303s left) kms_atomic_interruptible (legacy-pageflip)
[724.814946] Starting subtest: legacy-pageflip
[724.817112] Starting dynamic subtest: pipe-A-eDP-1
[732.242245] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.431s)
[732.244634] Subtest legacy-pageflip: SUCCESS (7.432s)
[733.086238] [129/133] (295s left) gem_ppgtt (blt-vs-render-ctxn)
[733.149130] Starting subtest: blt-vs-render-ctxN
[766.108399] Subtest blt-vs-render-ctxN: SUCCESS (32.975s)
[766.257508] [130/133] (262s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[766.401007] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[775.375922] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.965s)
[775.479837] [131/133] (253s left) gem_eio (in-flight-contexts-immediate)
[775.725411] Starting subtest: in-flight-contexts-immediate
[788.990444] Subtest in-flight-contexts-immediate: SUCCESS (13.251s)
[789.171068] [132/133] (239s left) perf (oa-formats)
[789.312671] Starting subtest: oa-formats
[789.314577] Starting dynamic subtest: 0-rcs0
[789.328051] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[789.339849] Starting dynamic subtest: 1-vcs0
[789.350764] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[789.353084] Subtest oa-formats: SUCCESS (0.040s)
[789.404320] [133/133] (239s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[789.468623] Starting subtest: multi-wait-all-for-submit-submitted
[789.572828] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[789.660063] 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.