[37.770031] Initializing watchdogs
[37.770062]   /dev/watchdog0
[37.775970] [001/130] (960s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[37.867065] Starting subtest: modeset-transition-nonblocking-fencing
[37.869302] Starting dynamic subtest: 1x-outputs
[49.293173] Dynamic subtest 1x-outputs: SUCCESS (11.368s)
[49.564305] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.635s)
[50.485538] [002/130] (947s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[50.581813] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[50.586128] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[50.653645] [003/130] (947s left) gem_mmap (pf-nonblock)
[50.707728] Subtest pf-nonblock: SKIP (0.000s)
[50.742079] [004/130] (947s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[50.843793] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[50.848277] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[50.910655] [005/130] (947s left) gem_create (busy-create)
[50.954674] Starting subtest: busy-create
[50.957075] Starting dynamic subtest: smem0
[84.446313] Dynamic subtest smem0: SUCCESS (33.394s)
[84.449221] Subtest busy-create: SUCCESS (33.394s)
[84.603631] [006/130] (913s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[89.728988] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[89.731363] Starting dynamic subtest: pipe-A-eDP-1
[90.582160] Dynamic subtest pipe-A-eDP-1: SKIP (0.848s)
[90.588962] Starting dynamic subtest: pipe-B-eDP-1
[91.796409] Dynamic subtest pipe-B-eDP-1: SKIP (1.213s)
[91.801074] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (2.064s)
[93.043627] [007/130] (905s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[93.159536] Starting subtest: flip-vs-cursor-legacy
[99.594785] Subtest flip-vs-cursor-legacy: SUCCESS (6.434s)
[100.463297] [008/130] (898s left) gem_exec_schedule (smoketest)
[100.631877] Starting subtest: smoketest
[100.634388] Starting dynamic subtest: rcs0
[106.153912] Dynamic subtest rcs0: SUCCESS (5.461s)
[106.160402] Starting dynamic subtest: bcs0
[111.628633] Dynamic subtest bcs0: SUCCESS (5.482s)
[111.634826] Starting dynamic subtest: vcs0
[117.092671] Dynamic subtest vcs0: SUCCESS (5.467s)
[117.098310] Starting dynamic subtest: vcs1
[122.579405] Dynamic subtest vcs1: SUCCESS (5.485s)
[122.585428] Starting dynamic subtest: vecs0
[128.060131] Dynamic subtest vecs0: SUCCESS (5.476s)
[128.065832] Starting dynamic subtest: ccs0
[133.522900] Dynamic subtest ccs0: SUCCESS (5.467s)
[133.524957] Subtest smoketest: SUCCESS (32.839s)
[133.699357] [009/130] (864s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[133.798441] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[138.372269] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (4.576s)
[138.479550] [010/130] (860s left) gem_mmap (basic)
[138.527297] Subtest basic: SKIP (0.000s)
[138.570182] [011/130] (860s left) kms_psr (fbc-psr-cursor-blt)
[138.659849] Starting subtest: fbc-psr-cursor-blt
[138.662210] Starting dynamic subtest: eDP-1
[138.666704] Dynamic subtest eDP-1: SKIP (0.000s)
[138.671241] Subtest fbc-psr-cursor-blt: SKIP (0.000s)
[138.710248] [012/130] (859s left) gem_ctx_persistence (heartbeat-hang)
[138.753659] Starting subtest: heartbeat-hang
[138.758272] Subtest heartbeat-hang: SKIP (0.000s)
[138.831146] [013/130] (859s left) i915_pciid
[138.959414] [014/130] (859s left) perf (invalid-oa-metric-set-id)
[139.114161] Starting subtest: invalid-oa-metric-set-id
[139.124815] Subtest invalid-oa-metric-set-id: SUCCESS (0.012s)
[139.148573] [015/130] (859s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[139.261938] Starting subtest: flip-vs-cursor-busy-crc-legacy
[140.077202] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.815s)
[140.974391] [016/130] (857s left) gem_ctx_persistence (saturated-hostile)
[141.024456] Starting subtest: saturated-hostile
[141.026812] Starting dynamic subtest: rcs0
[141.126143] Dynamic subtest rcs0: SUCCESS (0.102s)
[141.133905] Starting dynamic subtest: bcs0
[141.215932] Dynamic subtest bcs0: SUCCESS (0.089s)
[141.221901] Starting dynamic subtest: vcs0
[141.305724] Dynamic subtest vcs0: SUCCESS (0.090s)
[141.311537] Starting dynamic subtest: vcs1
[141.402653] Dynamic subtest vcs1: SUCCESS (0.097s)
[141.408569] Starting dynamic subtest: vecs0
[141.507363] Dynamic subtest vecs0: SUCCESS (0.104s)
[141.518367] Starting dynamic subtest: ccs0
[141.641318] Dynamic subtest ccs0: SUCCESS (0.134s)
[141.644077] Subtest saturated-hostile: SUCCESS (0.617s)
[141.700991] [017/130] (856s left) kms_lease (empty-lease)
[141.787759] Starting subtest: empty-lease
[141.791865] Starting dynamic subtest: pipe-A-eDP-1
[141.794210] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[141.801437] Starting dynamic subtest: pipe-B-eDP-1
[141.803604] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[141.808653] Starting dynamic subtest: pipe-C-eDP-1
[141.810735] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[141.815823] Starting dynamic subtest: pipe-D-eDP-1
[141.817947] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[141.820074] Subtest empty-lease: SUCCESS (0.005s)
[141.889903] [018/130] (856s left) gen9_exec_parse (bb-oversize)
[141.936239] Subtest bb-oversize: SKIP (0.000s)
[141.976061] [019/130] (856s left) gem_mmap_gtt (basic-read)
[142.018790] Subtest basic-read: SKIP (0.000s)
[142.061835] [020/130] (856s left) i915_query (query-topology-garbage-items)
[142.101430] Starting subtest: query-topology-garbage-items
[142.103025] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[142.164278] [021/130] (856s left) syncobj_wait (single-wait-for-submit-submitted)
[142.198849] Starting subtest: single-wait-for-submit-submitted
[142.308439] Subtest single-wait-for-submit-submitted: SUCCESS (0.108s)
[142.366394] [022/130] (856s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[142.467953] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[147.419166] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.953s)
[147.511086] [023/130] (851s left) kms_pm_rpm (cursor)
[149.523332] Starting subtest: cursor
[160.384291] Subtest cursor: SUCCESS (10.861s)
[160.713711] [024/130] (838s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[160.821523] Starting subtest: cursorB-vs-flipB-atomic
[160.826325] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[160.896057] [025/130] (837s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[160.992170] Starting subtest: 2x-long-cursor-vs-flip-legacy
[160.996530] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[161.079190] [026/130] (837s left) gem_exec_balancer (parallel-ordering)
[161.288449] Starting subtest: parallel-ordering
[161.814395] Subtest parallel-ordering: SUCCESS (0.526s)
[161.927817] [027/130] (836s left) prime_mmap (test_aperture_limit)
[161.982394] Starting subtest: test_aperture_limit
[161.984725] Starting dynamic subtest: test_aperture_limit-smem
[161.987520] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.005s)
[161.990198] Subtest test_aperture_limit: SUCCESS (0.005s)
[162.040310] [028/130] (836s left) kms_plane (plane-panning-top-left)
[162.127638] Starting subtest: plane-panning-top-left
[162.129887] Starting dynamic subtest: pipe-A
[163.789501] Dynamic subtest pipe-A: SUCCESS (1.660s)
[163.795280] Starting dynamic subtest: pipe-B
[166.250245] Dynamic subtest pipe-B: SUCCESS (2.458s)
[166.252708] Subtest plane-panning-top-left: SUCCESS (4.119s)
[167.149164] [029/130] (831s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[167.264442] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[167.268766] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.002s)
[167.361815] [030/130] (831s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[167.408029] Starting subtest: invalid-multi-wait-unsubmitted
[167.409942] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[167.460314] [031/130] (831s left) gem_render_copy (yf-tiled-to-vebox-linear)
[167.518663] Starting subtest: yf-tiled-to-vebox-linear
[167.523872] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[167.673562] [032/130] (831s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[167.767154] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[167.771307] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[167.843653] [033/130] (831s left) kms_vblank (accuracy-idle)
[167.925788] Starting subtest: accuracy-idle
[167.928176] Starting dynamic subtest: pipe-A-eDP-1
[169.670543] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.741s)
[169.681192] Starting dynamic subtest: pipe-D-eDP-1
[172.170637] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.500s)
[172.173587] Subtest accuracy-idle: SUCCESS (4.244s)
[173.036393] [034/130] (825s left) i915_pm_rpm (system-suspend)
[174.549687] Starting subtest: system-suspend
[192.719584] Subtest system-suspend: SUCCESS (2.871s)
[193.068057] [035/130] (821s left) drm_fdinfo (busy-idle)
[193.103138] Starting subtest: busy-idle
[193.105044] Starting dynamic subtest: rcs0
[193.126375] Dynamic subtest rcs0: SKIP (0.022s)
[193.134712] Starting dynamic subtest: bcs0
[193.139450] Dynamic subtest bcs0: SKIP (0.010s)
[193.145603] Starting dynamic subtest: vcs0
[193.150333] Dynamic subtest vcs0: SKIP (0.000s)
[193.156200] Starting dynamic subtest: vcs1
[193.160498] Dynamic subtest vcs1: SKIP (0.000s)
[193.167261] Starting dynamic subtest: vecs0
[193.172248] Dynamic subtest vecs0: SKIP (0.000s)
[193.178215] Starting dynamic subtest: ccs0
[193.183069] Dynamic subtest ccs0: SKIP (0.000s)
[193.187915] Subtest busy-idle: SKIP (0.034s)
[193.201166] [036/130] (821s left) kms_flip (2x-flip-vs-suspend)
[193.297883] Starting subtest: 2x-flip-vs-suspend
[193.311561] Subtest 2x-flip-vs-suspend: SKIP (0.013s)
[193.366509] [037/130] (820s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render)
[193.465505] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render
[198.028515] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.495s)
[198.162659] [038/130] (816s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[198.252963] Starting subtest: psr-rgb565-draw-pwrite
[202.859351] Subtest psr-rgb565-draw-pwrite: SUCCESS (4.569s)
[202.957758] [039/130] (811s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[203.050621] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[203.052923] Starting dynamic subtest: pipe-A
[203.061969] Dynamic subtest pipe-A: SKIP (0.003s)
[203.069758] Starting dynamic subtest: pipe-B
[203.079181] Dynamic subtest pipe-B: SKIP (0.012s)
[203.089359] Starting dynamic subtest: pipe-C
[203.100729] Dynamic subtest pipe-C: SKIP (0.013s)
[203.107526] Starting dynamic subtest: pipe-D
[203.116708] Dynamic subtest pipe-D: SKIP (0.013s)
[203.120991] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.041s)
[203.159780] [040/130] (811s left) perf_pmu (most-busy-idle-check-all)
[203.205847] Starting subtest: most-busy-idle-check-all
[203.208413] Starting dynamic subtest: rcs0
[203.919272] Dynamic subtest rcs0: FAIL (0.639s)
[203.927129] Starting dynamic subtest: bcs0
[204.684190] Dynamic subtest bcs0: SUCCESS (0.829s)
[204.693973] Starting dynamic subtest: vcs0
[206.667110] Dynamic subtest vcs0: SUCCESS (1.973s)
[206.678323] Starting dynamic subtest: vcs1
[208.652291] Dynamic subtest vcs1: SUCCESS (1.976s)
[208.661453] Starting dynamic subtest: vecs0
[210.699589] Dynamic subtest vecs0: SUCCESS (2.041s)
[210.708170] Starting dynamic subtest: ccs0
[212.682861] Dynamic subtest ccs0: SUCCESS (1.979s)
[212.685486] Subtest most-busy-idle-check-all: FAIL (9.441s)
[212.749017] [041/130] (801s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[212.824715] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[212.827299] Starting dynamic subtest: pipe-A-eDP-1
[213.541277] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.706s)
[213.549117] Starting dynamic subtest: pipe-B-eDP-1
[215.400700] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.853s)
[215.409026] Starting dynamic subtest: pipe-C-eDP-1
[217.244347] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.842s)
[217.251920] Starting dynamic subtest: pipe-D-eDP-1
[219.111159] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.864s)
[219.113134] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.268s)
[220.330559] [042/130] (794s left) api_intel_bb (object-noreloc-purge-cache-simple)
[220.381221] Starting subtest: object-noreloc-purge-cache-simple
[220.386404] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.005s)
[220.447530] [043/130] (794s left) kms_psr (fbc-pr-no-drrs)
[220.531519] Starting subtest: fbc-pr-no-drrs
[220.535887] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[220.580488] [044/130] (793s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[220.670214] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[223.640395] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.973s)
[223.762627] [045/130] (790s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[223.849909] Starting subtest: flip-primary-invalidate-overlay
[235.219288] Subtest flip-primary-invalidate-overlay: SUCCESS (11.370s)
[235.399351] [046/130] (779s left) prime_udl
[235.441940] [047/130] (779s left) syncobj_timeline (invalid-query-one-illegal-handle)
[235.489324] Starting subtest: invalid-query-one-illegal-handle
[235.491385] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[235.570139] [048/130] (779s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[235.656129] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[235.658112] Starting dynamic subtest: pipe-A-eDP-1
[235.674811] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[235.680240] Starting dynamic subtest: pipe-B-eDP-1
[235.696501] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[235.702316] Starting dynamic subtest: pipe-C-eDP-1
[235.718841] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[235.723982] Starting dynamic subtest: pipe-D-eDP-1
[235.739446] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[235.740984] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[235.752952] [049/130] (778s left) gem_userptr_blits (stress-mm-invalidate-close)
[235.808475] Starting subtest: stress-mm-invalidate-close
[237.955238] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[238.056580] [050/130] (776s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[238.139188] Starting subtest: plane-all-modeset-transition-internal-panels
[238.141450] Starting dynamic subtest: pipe-A-eDP-1
[239.518141] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.376s)
[239.791683] Starting dynamic subtest: pipe-B-eDP-1
[240.926228] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.141s)
[241.189053] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (3.048s)
[242.092881] [051/130] (772s left) perf_pmu (cpu-hotplug)
[242.123155] Starting subtest: cpu-hotplug
[242.127674] Subtest cpu-hotplug: SKIP (0.004s)
[242.231336] [052/130] (772s left) kms_flip (flip-vs-panning-interruptible)
[242.331422] Starting subtest: flip-vs-panning-interruptible
[242.333840] Starting dynamic subtest: A-eDP1
[251.072699] Dynamic subtest A-eDP1: SUCCESS (8.738s)
[251.078550] Starting dynamic subtest: B-eDP1
[259.843688] Dynamic subtest B-eDP1: SUCCESS (8.767s)
[259.850510] Starting dynamic subtest: C-eDP1
[268.601049] Dynamic subtest C-eDP1: SUCCESS (8.753s)
[268.612390] Starting dynamic subtest: D-eDP1
[277.366268] Dynamic subtest D-eDP1: SUCCESS (8.761s)
[277.371707] Subtest flip-vs-panning-interruptible: SUCCESS (35.027s)
[278.641787] [053/130] (736s left) kms_frontbuffer_tracking (psr-2p-rte)
[278.731359] Starting subtest: psr-2p-rte
[278.736010] Subtest psr-2p-rte: SKIP (0.000s)
[278.797359] [054/130] (735s left) perf (per-context-mode-unprivileged)
[278.873671] Starting subtest: per-context-mode-unprivileged
[278.878370] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[278.896393] [055/130] (735s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[278.993670] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[283.203976] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (4.213s)
[283.293535] [056/130] (731s left) syncobj_wait (wait-for-submit-complex)
[283.341410] Starting subtest: wait-for-submit-complex
[283.400868] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[283.453824] [057/130] (731s left) kms_flip (2x-flip-vs-panning)
[283.545676] Starting subtest: 2x-flip-vs-panning
[283.559206] Subtest 2x-flip-vs-panning: SKIP (0.013s)
[283.609676] [058/130] (731s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[283.696760] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[286.817313] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (3.125s)
[286.932952] [059/130] (727s left) gem_mmap_offset (bad-extensions)
[286.973231] Starting subtest: bad-extensions
[286.975738] Subtest bad-extensions: SUCCESS (0.000s)
[287.034045] [060/130] (727s left) sysfs_defaults (readonly)
[287.076575] Starting subtest: readonly
[287.078403] Starting dynamic subtest: vecs0
[287.080343] Dynamic subtest vecs0: SUCCESS (0.000s)
[287.085927] Starting dynamic subtest: ccs0
[287.087728] Dynamic subtest ccs0: SUCCESS (0.000s)
[287.093547] Starting dynamic subtest: vcs0
[287.095138] Dynamic subtest vcs0: SUCCESS (0.000s)
[287.100542] Starting dynamic subtest: bcs0
[287.102177] Dynamic subtest bcs0: SUCCESS (0.000s)
[287.107511] Starting dynamic subtest: rcs0
[287.109144] Dynamic subtest rcs0: SUCCESS (0.000s)
[287.115154] Starting dynamic subtest: vcs1
[287.116957] Dynamic subtest vcs1: SUCCESS (0.000s)
[287.118738] Subtest readonly: SUCCESS (0.002s)
[287.151411] [061/130] (727s left) gem_exec_store (dword)
[287.197842] Starting subtest: dword
[287.199601] Starting dynamic subtest: rcs0
[287.267548] Dynamic subtest rcs0: SUCCESS (0.071s)
[287.276713] Starting dynamic subtest: bcs0
[287.309383] Dynamic subtest bcs0: SUCCESS (0.041s)
[287.317588] Starting dynamic subtest: vcs0
[287.350481] Dynamic subtest vcs0: SUCCESS (0.040s)
[287.360133] Starting dynamic subtest: vcs1
[287.393332] Dynamic subtest vcs1: SUCCESS (0.042s)
[287.401693] Starting dynamic subtest: vecs0
[287.435547] Dynamic subtest vecs0: SUCCESS (0.042s)
[287.443322] Starting dynamic subtest: ccs0
[287.477273] Dynamic subtest ccs0: SUCCESS (0.041s)
[287.479580] Subtest dword: SUCCESS (0.281s)
[287.641691] [062/130] (727s left) gem_exec_fence (basic-wait)
[287.809253] Starting subtest: basic-wait
[287.812420] Starting dynamic subtest: rcs0
[287.873259] Dynamic subtest rcs0: SUCCESS (0.065s)
[287.882536] Starting dynamic subtest: bcs0
[287.932614] Dynamic subtest bcs0: SUCCESS (0.059s)
[287.941445] Starting dynamic subtest: vcs0
[287.993764] Dynamic subtest vcs0: SUCCESS (0.060s)
[288.002855] Starting dynamic subtest: vcs1
[288.052243] Dynamic subtest vcs1: SUCCESS (0.058s)
[288.060691] Starting dynamic subtest: vecs0
[288.112735] Dynamic subtest vecs0: SUCCESS (0.059s)
[288.121944] Starting dynamic subtest: ccs0
[288.171491] Dynamic subtest ccs0: SUCCESS (0.058s)
[288.174540] Subtest basic-wait: SUCCESS (0.363s)
[289.729572] [063/130] (725s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[289.827721] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[290.328619] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.503s)
[290.464791] [064/130] (724s left) gem_ctx_param (invalid-param-get)
[290.521378] Starting subtest: invalid-param-get
[290.524258] Subtest invalid-param-get: SUCCESS (0.001s)
[290.592799] [065/130] (724s left) kms_psr (psr-dpms)
[290.673454] Starting subtest: psr-dpms
[290.675889] Starting dynamic subtest: eDP-1
[293.722287] Dynamic subtest eDP-1: SUCCESS (3.049s)
[293.724291] Subtest psr-dpms: SUCCESS (3.049s)
[294.635817] [066/130] (720s left) perf (sysctl-defaults)
[294.702732] Starting subtest: sysctl-defaults
[294.705631] Subtest sysctl-defaults: SUCCESS (0.000s)
[294.734524] [067/130] (720s left) api_intel_allocator (two-level-inception)
[294.788108] Starting subtest: two-level-inception
[300.171935] Subtest two-level-inception: SUCCESS (5.387s)
[300.271407] [068/130] (714s left) gem_exec_fence (syncobj-timeline-repeat)
[300.779156] Starting subtest: syncobj-timeline-repeat
[300.905801] Subtest syncobj-timeline-repeat: SUCCESS (0.128s)
[302.385402] [069/130] (712s left) gem_pxp (reject-modify-context-protection-off-1)
[304.965000] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[305.012605] [070/130] (709s left) kms_draw_crc (draw-method-pwrite)
[305.097608] Starting subtest: draw-method-pwrite
[305.099872] Starting dynamic subtest: xrgb8888-untiled
[305.495145] Dynamic subtest xrgb8888-untiled: SUCCESS (0.399s)
[305.503202] Starting dynamic subtest: rgb565-untiled
[305.817252] Dynamic subtest rgb565-untiled: SUCCESS (0.321s)
[305.825356] Starting dynamic subtest: xrgb2101010-untiled
[306.194086] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.377s)
[306.201161] Starting dynamic subtest: xrgb8888-xtiled
[307.316388] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.122s)
[307.323109] Starting dynamic subtest: rgb565-xtiled
[308.049753] Dynamic subtest rgb565-xtiled: SUCCESS (0.733s)
[308.058515] Starting dynamic subtest: xrgb2101010-xtiled
[309.194265] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.144s)
[309.202293] Starting dynamic subtest: xrgb8888-4tiled
[309.927366] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.733s)
[309.934224] Starting dynamic subtest: rgb565-4tiled
[310.327251] Dynamic subtest rgb565-4tiled: SUCCESS (0.400s)
[310.334540] Starting dynamic subtest: xrgb2101010-4tiled
[311.049679] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.721s)
[311.051923] Subtest draw-method-pwrite: SUCCESS (5.955s)
[311.133909] [071/130] (703s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[311.221389] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[312.053988] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.833s)
[312.165569] [072/130] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[312.259791] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[312.264102] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[312.339856] [073/130] (702s left) kms_psr (pr-cursor-mmap-cpu)
[312.434148] Starting subtest: pr-cursor-mmap-cpu
[312.438047] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[312.484280] [074/130] (702s left) gem_exec_params (rel-constants-invalid)
[312.534916] Starting subtest: rel-constants-invalid
[312.537038] Subtest rel-constants-invalid: SUCCESS (0.000s)
[312.606223] [075/130] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[312.698956] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[312.702416] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[312.771851] [076/130] (702s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[312.858129] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[312.859980] Starting dynamic subtest: pipe-A-default-mode
[313.148942] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[313.153731] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.285s)
[314.026776] [077/130] (700s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[314.107448] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[314.109682] Starting dynamic subtest: pipe-A-eDP-1
[314.131378] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[314.138455] Starting dynamic subtest: pipe-B-eDP-1
[314.161684] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[314.168333] Starting dynamic subtest: pipe-C-eDP-1
[314.190249] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.196568] Starting dynamic subtest: pipe-D-eDP-1
[314.217903] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.221870] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[314.235430] [078/130] (700s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[314.610423] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[315.484124] [079/130] (699s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[315.585670] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[315.589845] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[315.649814] [080/130] (699s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[320.809577] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[320.814958] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[321.712214] [081/130] (693s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[321.765492] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[321.870899] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.104s)
[321.920268] [082/130] (693s left) syncobj_wait (multi-wait-submitted)
[321.965763] Starting subtest: multi-wait-submitted
[322.070458] Subtest multi-wait-submitted: SUCCESS (0.104s)
[322.123537] [083/130] (692s left) gem_spin_batch (spin-all-new)
[322.177091] Starting subtest: spin-all-new
[322.192456] Subtest spin-all-new: SUCCESS (0.014s)
[323.741477] [084/130] (691s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[323.823377] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[323.827710] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[323.884023] [085/130] (691s left) syncobj_eventfd (binary-wait-before-signal)
[323.935368] Starting subtest: binary-wait-before-signal
[323.963501] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[324.015229] [086/130] (691s left) kms_setmode (basic-clone-single-crtc)
[324.064702] Starting subtest: basic-clone-single-crtc
[324.071743] Subtest basic-clone-single-crtc: SKIP (0.000s)
[324.112669] [087/130] (691s left) gem_exec_schedule (fairslice-all)
[324.168901] Starting subtest: fairslice-all
[326.210135] Subtest fairslice-all: SUCCESS (2.046s)
[327.728801] [088/130] (687s left) gem_mmap_gtt (cpuset-big-copy-odd)
[327.786918] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[327.823379] [089/130] (687s left) gem_exec_params (sol-reset-invalid)
[327.879007] Starting subtest: sol-reset-invalid
[327.881661] Subtest sol-reset-invalid: SUCCESS (0.003s)
[329.699385] [090/130] (685s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[329.807735] Starting subtest: basic-flip-after-cursor-legacy
[330.723712] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.915s)
[331.626043] [091/130] (683s left) gem_ctx_engines (independent)
[331.676976] Starting subtest: independent
[331.679581] Starting dynamic subtest: rcs0
[331.779698] Dynamic subtest rcs0: SUCCESS (0.102s)
[331.789611] Starting dynamic subtest: bcs0
[331.861449] Dynamic subtest bcs0: SUCCESS (0.081s)
[331.871349] Starting dynamic subtest: vcs0
[331.940854] Dynamic subtest vcs0: SUCCESS (0.079s)
[331.953620] Starting dynamic subtest: vcs1
[332.044573] Dynamic subtest vcs1: SUCCESS (0.103s)
[332.054361] Starting dynamic subtest: vecs0
[332.135262] Dynamic subtest vecs0: SUCCESS (0.091s)
[332.144705] Starting dynamic subtest: ccs0
[332.233700] Dynamic subtest ccs0: SUCCESS (0.098s)
[332.240078] Starting dynamic subtest: all-engines
[332.650094] Dynamic subtest all-engines: SUCCESS (0.415s)
[332.652991] Subtest independent: SUCCESS (0.972s)
[333.818420] [092/130] (681s left) gem_exec_alignment (many)
[333.873267] Starting subtest: many
[372.900970] Subtest many: SUCCESS (39.018s)
[374.056240] [093/130] (641s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[374.165904] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[374.171414] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[374.213439] [094/130] (641s left) kms_getfb (getfb2-accept-ccs)
[374.260677] Starting subtest: getfb2-accept-ccs
[374.262938] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[374.337574] [095/130] (640s left) kms_cursor_crc (cursor-random-64x21)
[374.495090] Starting subtest: cursor-random-64x21
[374.526243] Subtest cursor-random-64x21: SKIP (0.028s)
[374.726307] [096/130] (640s left) kms_legacy_colorkey (invalid-plane)
[374.813536] Starting subtest: invalid-plane
[374.815953] Starting dynamic subtest: zero-id
[374.818211] Dynamic subtest zero-id: SUCCESS (0.000s)
[374.825998] Starting dynamic subtest: outrange-id
[374.828310] Dynamic subtest outrange-id: SUCCESS (0.000s)
[374.830470] Subtest invalid-plane: SUCCESS (0.001s)
[374.863697] [097/130] (640s left) gem_exec_whisper (basic-queues-priority-all)
[374.924113] Starting subtest: basic-queues-priority-all
[396.856369] Subtest basic-queues-priority-all: SUCCESS (21.924s)
[397.014236] [098/130] (618s left) kms_chamelium_frames (hdmi-crc-fast)
[397.397694] Subtest hdmi-crc-fast: SKIP (0.000s)
[398.235340] [099/130] (617s left) kms_pm_rpm (modeset-lpsp-stress)
[399.867415] Starting subtest: modeset-lpsp-stress
[415.410121] Subtest modeset-lpsp-stress: SUCCESS (15.539s)
[415.485265] [100/130] (599s left) gem_userptr_blits (invalid-null-pointer)
[415.549771] Starting subtest: invalid-null-pointer
[415.552484] Subtest invalid-null-pointer: SUCCESS (0.001s)
[415.656156] [101/130] (599s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[415.703201] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[415.746957] [102/130] (599s left) gem_exec_capture (pi)
[415.792413] Starting subtest: pi
[415.795105] Starting dynamic subtest: rcs0
[419.690716] Dynamic subtest rcs0: SUCCESS (3.899s)
[419.700416] Starting dynamic subtest: bcs0
[423.585894] Dynamic subtest bcs0: SUCCESS (3.895s)
[423.594806] Starting dynamic subtest: vcs0
[427.536507] Dynamic subtest vcs0: SUCCESS (3.951s)
[427.546917] Starting dynamic subtest: vcs1
[431.436724] Dynamic subtest vcs1: SUCCESS (3.900s)
[431.446676] Starting dynamic subtest: vecs0
[435.345610] Dynamic subtest vecs0: SUCCESS (3.908s)
[435.354572] Starting dynamic subtest: ccs0
[439.283775] Dynamic subtest ccs0: SUCCESS (3.937s)
[439.286465] Subtest pi: SUCCESS (23.496s)
[439.335437] [103/130] (576s left) kms_big_fb (y-tiled-addfb-size-overflow)
[439.472511] Starting subtest: y-tiled-addfb-size-overflow
[439.476834] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[439.554523] [104/130] (575s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[439.655500] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[444.212416] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.560s)
[444.333081] [105/130] (571s left) gem_exec_suspend (basic-s3)
[444.386370] Starting subtest: basic-S3
[444.388577] Starting dynamic subtest: smem
[463.935554] Dynamic subtest smem: SUCCESS (6.277s)
[463.938639] Subtest basic-S3: SUCCESS (6.278s)
[464.098313] [106/130] (564s left) fbdev (eof)
[464.130848] Starting subtest: eof
[464.133456] Subtest eof: SUCCESS (0.000s)
[464.163443] [107/130] (564s left) kms_hdr (static-toggle)
[464.255136] Starting subtest: static-toggle
[464.261962] Subtest static-toggle: SKIP (0.000s)
[464.305440] [108/130] (564s left) syncobj_wait (wait-any-snapshot)
[464.362642] Starting subtest: wait-any-snapshot
[464.445166] Subtest wait-any-snapshot: SUCCESS (0.081s)
[464.501442] [109/130] (564s left) kms_plane_scaling (planes-upscale-20x20)
[464.589302] Starting subtest: planes-upscale-20x20
[464.591800] Starting dynamic subtest: pipe-A
[464.655599] Dynamic subtest pipe-A: SUCCESS (0.065s)
[464.667052] Starting dynamic subtest: pipe-B
[465.848920] Dynamic subtest pipe-B: SUCCESS (1.172s)
[465.858652] Starting dynamic subtest: pipe-C
[467.042777] Dynamic subtest pipe-C: SUCCESS (1.176s)
[467.057093] Starting dynamic subtest: pipe-D
[468.259463] Dynamic subtest pipe-D: SUCCESS (1.201s)
[468.261785] Subtest planes-upscale-20x20: SUCCESS (3.617s)
[469.474280] [110/130] (559s left) gem_softpin (allocator-evict)
[469.520178] Starting subtest: allocator-evict
[469.522948] Starting dynamic subtest: rcs0
[492.331687] Dynamic subtest rcs0: SUCCESS (22.733s)
[492.339389] Starting dynamic subtest: bcs0
[516.286644] Dynamic subtest bcs0: SUCCESS (23.950s)
[516.293810] Starting dynamic subtest: vcs0
[539.547154] Dynamic subtest vcs0: SUCCESS (23.251s)
[539.554564] Starting dynamic subtest: vcs1
[562.752866] Dynamic subtest vcs1: SUCCESS (23.198s)
[562.759960] Starting dynamic subtest: vecs0
[585.963634] Dynamic subtest vecs0: SUCCESS (23.214s)
[585.973142] Starting dynamic subtest: ccs0
[609.702193] Dynamic subtest ccs0: SUCCESS (23.736s)
[609.704899] Subtest allocator-evict: SUCCESS (140.087s)
[609.756054] [111/130] (419s left) kms_psr (psr2-cursor-plane-move)
[609.883204] Starting subtest: psr2-cursor-plane-move
[609.886196] Starting dynamic subtest: eDP-1
[611.634347] Dynamic subtest eDP-1: SUCCESS (1.749s)
[611.637206] Subtest psr2-cursor-plane-move: SUCCESS (1.750s)
[612.515690] [112/130] (416s left) kms_vblank (ts-continuation-modeset)
[612.591951] Starting subtest: ts-continuation-modeset
[612.594338] Starting dynamic subtest: pipe-A-eDP-1
[614.116068] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.521s)
[614.129837] Starting dynamic subtest: pipe-D-eDP-1
[616.420773] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.302s)
[616.423502] Subtest ts-continuation-modeset: SUCCESS (3.826s)
[617.316630] [113/130] (411s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[617.376010] Starting subtest: etime-single-wait-all-for-submit-submitted
[617.482593] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.105s)
[617.534530] [114/130] (411s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[617.631842] Starting subtest: fbc-rgb565-draw-mmap-cpu
[620.359558] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (2.731s)
[620.471457] [115/130] (408s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[620.558153] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[620.561866] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[620.625913] [116/130] (408s left) kms_cursor_crc (cursor-suspend)
[620.731024] Starting subtest: cursor-suspend
[620.732964] Starting dynamic subtest: pipe-A-eDP-1
[653.291533] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.802s)
[653.299088] Starting dynamic subtest: pipe-D-eDP-1