[57.086369] Initializing watchdogs
[57.086414]   /dev/watchdog0
[57.099433] [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]
[57.103712] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.105903] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.109383] [001/130] (960s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[57.269982] Starting subtest: primary-y-tiled-reflect-x-0
[57.338760] Subtest primary-y-tiled-reflect-x-0: SKIP (0.066s)
[57.430046] [002/130] (960s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[57.565110] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[68.778962] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.233s)
[69.637947] [003/130] (948s left) kms_getfb (getfb-addfb-different-handles)
[69.700066] Starting subtest: getfb-addfb-different-handles
[69.702490] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[69.790325] [004/130] (947s left) sysfs_defaults (readonly)
[69.857541] Starting subtest: readonly
[69.860020] Starting dynamic subtest: vecs0
[69.862424] Dynamic subtest vecs0: SUCCESS (0.000s)
[69.869699] Starting dynamic subtest: ccs0
[69.871954] Dynamic subtest ccs0: SUCCESS (0.000s)
[69.877973] Starting dynamic subtest: vcs0
[69.880426] Dynamic subtest vcs0: SUCCESS (0.000s)
[69.888076] Starting dynamic subtest: bcs0
[69.890256] Dynamic subtest bcs0: SUCCESS (0.000s)
[69.896064] Starting dynamic subtest: rcs0
[69.898257] Dynamic subtest rcs0: SUCCESS (0.000s)
[69.903953] Starting dynamic subtest: vcs1
[69.906239] Dynamic subtest vcs1: SUCCESS (0.000s)
[69.908089] Subtest readonly: SUCCESS (0.003s)
[69.953610] [005/130] (947s left) perf_pmu (busy-accuracy-98)
[69.993393] Starting subtest: busy-accuracy-98
[69.995613] Starting dynamic subtest: rcs0
[73.030235] Dynamic subtest rcs0: SUCCESS (3.036s)
[73.039534] Starting dynamic subtest: bcs0
[76.085826] Dynamic subtest bcs0: SUCCESS (3.056s)
[76.094949] Starting dynamic subtest: vcs0
[79.122431] Dynamic subtest vcs0: SUCCESS (3.038s)
[79.129547] Starting dynamic subtest: vcs1
[82.160696] Dynamic subtest vcs1: SUCCESS (3.039s)
[82.167969] Starting dynamic subtest: vecs0
[85.202595] Dynamic subtest vecs0: SUCCESS (3.041s)
[85.212106] Starting dynamic subtest: ccs0
[88.247558] Dynamic subtest ccs0: SUCCESS (3.045s)
[88.250192] Subtest busy-accuracy-98: SUCCESS (18.256s)
[88.378539] [006/130] (929s left) gem_partial_pwrite_pread (writes-after-reads-display)
[88.440202] Subtest writes-after-reads-display: SKIP (0.000s)
[88.504889] [007/130] (929s left) prime_self_import (basic-llseek-bad)
[88.539716] Starting subtest: basic-llseek-bad
[88.565285] Subtest basic-llseek-bad: SUCCESS (0.022s)
[88.678773] [008/130] (929s left) perf (buffer-fill)
[88.786956] Starting subtest: buffer-fill
[88.789102] Starting dynamic subtest: 0-rcs0
[91.101623] Dynamic subtest 0-rcs0: SUCCESS (2.314s)
[91.108468] Starting dynamic subtest: 1-vecs0
[95.530722] Dynamic subtest 1-vecs0: SUCCESS (4.428s)
[95.533087] Subtest buffer-fill: SUCCESS (6.743s)
[95.569782] [009/130] (922s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[95.632687] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[95.634606] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[95.726741] [010/130] (922s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[95.835141] Starting subtest: fb-8bpc-vs-panel-8bpc
[95.837352] Starting dynamic subtest: pipe-A-eDP-1
[97.224346] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.387s)
[97.226845] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.388s)
[98.082800] [011/130] (919s left) kms_cursor_crc (cursor-offscreen-512x170)
[98.334669] Starting subtest: cursor-offscreen-512x170
[98.339066] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[98.490204] [012/130] (919s left) gem_softpin (allocator-basic)
[98.541192] Starting subtest: allocator-basic
[98.603340] Subtest allocator-basic: SUCCESS (0.062s)
[98.733465] [013/130] (919s left) kms_cursor_crc (cursor-sliding-32x10)
[98.880911] Starting subtest: cursor-sliding-32x10
[98.921713] Subtest cursor-sliding-32x10: SKIP (0.037s)
[99.162879] [014/130] (918s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[99.262423] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[102.505190] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (3.246s)
[102.625335] [015/130] (915s left) gem_pxp (create-regular-buffer)
[102.744242] Starting subtest: create-regular-buffer
[102.746212] Subtest create-regular-buffer: SUCCESS (0.000s)
[102.843154] [016/130] (915s left) gem_exec_schedule (pi-shared-iova)
[103.034943] Starting subtest: pi-shared-iova
[103.037255] Starting dynamic subtest: rcs0
[103.101132] Dynamic subtest rcs0: SUCCESS (0.069s)
[103.111190] Starting dynamic subtest: vcs0
[103.145045] Dynamic subtest vcs0: SUCCESS (0.045s)
[103.154199] Starting dynamic subtest: vcs1
[103.192749] Dynamic subtest vcs1: SUCCESS (0.047s)
[103.202816] Starting dynamic subtest: vecs0
[103.231209] Dynamic subtest vecs0: SUCCESS (0.038s)
[103.239554] Starting dynamic subtest: ccs0
[103.280770] Dynamic subtest ccs0: SUCCESS (0.050s)
[103.283099] Subtest pi-shared-iova: SUCCESS (0.249s)
[105.015485] [017/130] (913s left) kms_psr (fbc-psr2-cursor-blt)
[105.114848] Starting subtest: fbc-psr2-cursor-blt
[105.117231] Starting dynamic subtest: eDP-1
[105.121668] Dynamic subtest eDP-1: SKIP (0.000s)
[105.126132] Subtest fbc-psr2-cursor-blt: SKIP (0.000s)
[105.177969] [018/130] (912s left) gem_reg_read (timestamp-moving)
[105.224389] Starting subtest: timestamp-moving
[106.227769] Subtest timestamp-moving: SUCCESS (1.001s)
[106.290878] [019/130] (911s left) prime_busy (hang-wait)
[106.356717] Starting subtest: hang-wait
[106.359381] Starting dynamic subtest: rcs0
[132.433334] Dynamic subtest rcs0: SUCCESS (26.064s)
[132.440625] Starting dynamic subtest: bcs0
[139.508948] Dynamic subtest bcs0: SUCCESS (7.086s)
[139.518353] Starting dynamic subtest: vcs0
[146.549770] Dynamic subtest vcs0: SUCCESS (7.040s)
[146.556647] Starting dynamic subtest: vcs1
[153.523539] Dynamic subtest vcs1: SUCCESS (6.974s)
[153.533068] Starting dynamic subtest: vecs0
[160.510907] Dynamic subtest vecs0: SUCCESS (6.976s)
[160.519178] Starting dynamic subtest: ccs0
[174.402589] Dynamic subtest ccs0: SUCCESS (13.901s)
[174.404819] Subtest hang-wait: SUCCESS (68.045s)
[174.530959] [020/130] (843s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[174.633504] Starting subtest: psr-farfromfence-mmap-gtt
[174.638122] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[174.740491] [021/130] (843s left) syncobj_timeline (signal-point-0)
[174.798823] Starting subtest: signal-point-0
[174.801068] Subtest signal-point-0: SUCCESS (0.000s)
[174.900735] [022/130] (843s left) gem_basic (multigpu-create-close)
[174.951814] Starting subtest: multigpu-create-close
[174.956142] Subtest multigpu-create-close: SKIP (0.000s)
[175.036883] [023/130] (843s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[175.421211] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[176.210446] [024/130] (842s left) gem_spin_batch (spin-all-new)
[176.278182] Starting subtest: spin-all-new
[176.292075] Subtest spin-all-new: SUCCESS (0.012s)
[177.993813] [025/130] (840s left) gen9_exec_parse (bb-start-param)
[178.060860] Subtest bb-start-param: SKIP (0.000s)
[178.125084] [026/130] (840s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-blt)
[178.236788] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-blt
[183.058145] Subtest fbcpsr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.824s)
[183.217731] [027/130] (835s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[183.321298] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[187.581348] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (4.264s)
[187.701915] [028/130] (830s left) gem_exec_whisper (basic-sync)
[187.760115] Starting subtest: basic-sync
[193.872931] Subtest basic-sync: SUCCESS (6.101s)
[194.035962] [029/130] (824s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[194.149661] Starting subtest: y-tiled-64bpp-rotate-270
[194.153824] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[194.236141] [030/130] (824s left) kms_cursor_crc (cursor-onscreen-512x170)
[194.494522] Starting subtest: cursor-onscreen-512x170
[194.499054] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[194.641089] [031/130] (823s left) i915_query (hwconfig_table)
[194.699084] Starting subtest: hwconfig_table
[194.979285] Subtest hwconfig_table: SUCCESS (0.002s)
[195.009931] [032/130] (823s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[195.104066] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[199.614045] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (4.511s)
[199.752112] [033/130] (818s left) syncobj_timeline (host-signal-points)
[199.812029] Starting subtest: host-signal-points
[199.814318] Subtest host-signal-points: SUCCESS (0.004s)
[199.917799] [034/130] (818s left) api_intel_allocator (reopen)
[199.980976] Starting subtest: reopen
[199.985326] Subtest reopen: SUCCESS (0.006s)
[200.118269] [035/130] (818s left) perf (short-reads)
[200.199727] Starting subtest: short-reads
[200.217662] Subtest short-reads: SUCCESS (0.018s)
[200.258062] [036/130] (818s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[200.375674] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[205.027364] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.656s)
[205.154695] [037/130] (813s left) gem_mmap_gtt (basic-read-write-distinct)
[205.205282] Subtest basic-read-write-distinct: SKIP (0.000s)
[205.247724] [038/130] (813s left) gem_ctx_sseu (invalid-args)
[205.296457] Subtest invalid-args: SKIP (0.000s)
[205.373115] [039/130] (813s left) kms_color (ctm-signed)
[205.481327] Starting subtest: ctm-signed
[205.483704] Starting dynamic subtest: pipe-A-eDP-1
[207.505636] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.024s)
[207.514752] Starting dynamic subtest: pipe-B-eDP-1
[210.166677] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.660s)
[210.175463] Starting dynamic subtest: pipe-C-eDP-1
[212.775772] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.610s)
[212.785626] Starting dynamic subtest: pipe-D-eDP-1
[215.468839] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.693s)
[215.471251] Subtest ctm-signed: SUCCESS (9.987s)
[216.275649] [040/130] (802s left) drm_fdinfo (most-busy-check-all)
[216.317623] Starting subtest: most-busy-check-all
[216.319716] Starting dynamic subtest: rcs0
[216.324048] Dynamic subtest rcs0: SKIP (0.006s)
[216.333015] Starting dynamic subtest: bcs0
[216.337312] Dynamic subtest bcs0: SKIP (0.000s)
[216.344311] Starting dynamic subtest: vcs0
[216.348620] Dynamic subtest vcs0: SKIP (0.000s)
[216.355242] Starting dynamic subtest: vcs1
[216.359478] Dynamic subtest vcs1: SKIP (0.000s)
[216.366600] Starting dynamic subtest: vecs0
[216.370728] Dynamic subtest vecs0: SKIP (0.000s)
[216.377343] Starting dynamic subtest: ccs0
[216.381484] Dynamic subtest ccs0: SKIP (0.000s)
[216.385584] Subtest most-busy-check-all: SKIP (0.009s)
[216.411853] [041/130] (802s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[216.515781] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[216.519987] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[216.654967] [042/130] (802s left) gem_fence_thrash (bo-write-verify-threaded-none)
[216.689495] Starting subtest: bo-write-verify-threaded-none
[216.718447] Subtest bo-write-verify-threaded-none: SKIP (0.025s)
[216.773924] [043/130] (802s left) kms_plane_cursor (overlay)
[216.866997] Starting subtest: overlay
[216.871524] Starting dynamic subtest: pipe-A-eDP-1-size-64
[219.715398] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.847s)
[219.773934] Starting dynamic subtest: pipe-A-eDP-1-size-128
[222.571265] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.804s)
[222.633774] Starting dynamic subtest: pipe-A-eDP-1-size-256
[225.494831] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.870s)
[225.843835] Starting dynamic subtest: pipe-B-eDP-1-size-64
[229.443471] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.610s)
[229.502255] Starting dynamic subtest: pipe-B-eDP-1-size-128
[232.322684] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.827s)
[232.381073] Starting dynamic subtest: pipe-B-eDP-1-size-256
[235.201176] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.825s)
[235.546118] Starting dynamic subtest: pipe-C-eDP-1-size-64
[238.664273] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.127s)
[238.702021] Starting dynamic subtest: pipe-C-eDP-1-size-128
[241.075020] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.380s)
[241.112071] Starting dynamic subtest: pipe-C-eDP-1-size-256
[243.575540] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.469s)
[243.899473] Starting dynamic subtest: pipe-D-eDP-1-size-64
[247.068134] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.179s)
[247.105238] Starting dynamic subtest: pipe-D-eDP-1-size-128
[249.490102] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.391s)
[249.526555] Starting dynamic subtest: pipe-D-eDP-1-size-256
[252.012330] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.490s)
[252.329727] Subtest overlay: SUCCESS (35.462s)
[253.179075] [044/130] (765s left) kms_flip (flip-vs-suspend)
[253.276399] Starting subtest: flip-vs-suspend
[253.278759] Starting dynamic subtest: A-eDP1
[302.614862] Dynamic subtest A-eDP1: SUCCESS (4.536s)
[302.628640] Starting dynamic subtest: B-eDP1
[351.621395] Dynamic subtest B-eDP1: SUCCESS (4.280s)
[351.629718] Starting dynamic subtest: C-eDP1
[400.617272] Dynamic subtest C-eDP1: SUCCESS (4.342s)
[400.625599] Starting dynamic subtest: D-eDP1
[449.609743] Dynamic subtest D-eDP1: SUCCESS (4.266s)
[449.612449] Subtest flip-vs-suspend: SUCCESS (17.434s)
[450.817541] [045/130] (747s left) kms_vblank (wait-forked)
[450.909895] Starting subtest: wait-forked
[450.912507] Starting dynamic subtest: pipe-A-eDP-1
[452.735621] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.830s)
[452.749762] Starting dynamic subtest: pipe-D-eDP-1
[455.209488] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.476s)
[455.211926] Subtest wait-forked: SUCCESS (4.310s)
[456.051437] [046/130] (741s left) syncobj_timeline (invalid-wait-bad-flags)
[456.113003] Starting subtest: invalid-wait-bad-flags
[456.116039] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[456.218295] [047/130] (741s left) gem_mmap_offset (mmap-unmap)
[456.278965] Starting subtest: mmap-unmap
[456.281465] Starting dynamic subtest: smem0
[479.906239] Dynamic subtest smem0: SUCCESS (23.599s)
[479.909160] Subtest mmap-unmap: SUCCESS (23.600s)
[480.045383] [048/130] (717s left) kms_lease (cursor-implicit-plane)
[480.156738] Starting subtest: cursor-implicit-plane
[480.159383] Starting dynamic subtest: pipe-A-eDP-1
[480.618277] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.460s)
[480.626931] Starting dynamic subtest: pipe-B-eDP-1
[481.739883] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.120s)
[481.748141] Starting dynamic subtest: pipe-C-eDP-1
[482.858067] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[482.866854] Starting dynamic subtest: pipe-D-eDP-1
[483.985147] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[483.988184] Subtest cursor-implicit-plane: SUCCESS (3.829s)
[484.836473] [049/130] (713s left) i915_hangman (gt-engine-error)
[484.913120] Starting subtest: gt-engine-error
[484.915905] Starting dynamic subtest: rcs0
[486.860419] Dynamic subtest rcs0: SUCCESS (1.949s)
[486.871860] Starting dynamic subtest: bcs0
[488.845971] Dynamic subtest bcs0: SUCCESS (1.984s)
[488.858012] Starting dynamic subtest: vcs0
[490.829013] Dynamic subtest vcs0: SUCCESS (1.982s)
[490.843748] Starting dynamic subtest: vcs1
[492.876895] Dynamic subtest vcs1: SUCCESS (2.046s)
[492.888031] Starting dynamic subtest: vecs0
[494.860387] Dynamic subtest vecs0: SUCCESS (1.982s)
[494.871565] Starting dynamic subtest: ccs0
[496.845270] Dynamic subtest ccs0: SUCCESS (1.984s)
[496.848535] Subtest gt-engine-error: SUCCESS (11.940s)
[496.918434] [050/130] (701s left) vgem_basic (debugfs)
[497.003842] Starting subtest: debugfs
[497.006533] Subtest debugfs: SUCCESS (0.000s)
[497.056019] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[497.059909] [051/130] (700s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[497.162904] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[497.165862] Starting dynamic subtest: pipe-A-eDP-1
[497.189919] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[497.199426] Starting dynamic subtest: pipe-B-eDP-1
[497.223693] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[497.232938] Starting dynamic subtest: pipe-C-eDP-1
[497.256693] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[497.264884] Starting dynamic subtest: pipe-D-eDP-1
[497.289731] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[497.292576] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[499.294416] [052/130] (698s left) kms_addfb_basic (bad-pitch-0)
[499.354819] Starting subtest: bad-pitch-0
[499.357563] Subtest bad-pitch-0: SUCCESS (0.000s)
[499.490617] [053/130] (698s left) kms_chamelium_audio (dp-audio)
[499.884151] Subtest dp-audio: SKIP (0.000s)
[500.698904] [054/130] (697s left) gem_lmem_swapping (random-engines)
[500.745575] Subtest random-engines: SKIP (0.000s)
[500.783611] [055/130] (697s left) api_intel_bb (simple-bb)
[500.838462] Starting subtest: simple-bb
[500.841423] Subtest simple-bb: SUCCESS (0.006s)
[500.948403] [056/130] (697s left) gem_exec_flush (basic-uc-pro-default)
[501.016116] Starting subtest: basic-uc-pro-default
[506.515198] Subtest basic-uc-pro-default: SUCCESS (5.482s)
[506.676425] [057/130] (691s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[506.738840] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[506.741691] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[506.857918] [058/130] (691s left) kms_big_fb (linear-64bpp-rotate-180)
[506.972204] Starting subtest: linear-64bpp-rotate-180
[509.261050] Subtest linear-64bpp-rotate-180: SUCCESS (2.290s)
[509.554083] [059/130] (688s left) gem_exec_params (invalid-fence-in-submit)
[509.627118] Starting subtest: invalid-fence-in-submit
[509.633133] Subtest invalid-fence-in-submit: SUCCESS (0.005s)
[509.773427] [060/130] (688s left) kms_lease (page-flip-implicit-plane)
[509.864154] Starting subtest: page-flip-implicit-plane
[509.867072] Starting dynamic subtest: pipe-A-eDP-1
[510.351953] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.487s)
[510.359999] Starting dynamic subtest: pipe-B-eDP-1
[511.455020] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.101s)
[511.465175] Starting dynamic subtest: pipe-C-eDP-1
[512.565556] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.109s)
[512.574891] Starting dynamic subtest: pipe-D-eDP-1
[513.689974] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[513.692961] Subtest page-flip-implicit-plane: SUCCESS (3.827s)
[514.525614] [061/130] (683s left) gem_exec_schedule (u-submit-early-slice)
[514.604130] Starting subtest: u-submit-early-slice
[514.606803] Starting dynamic subtest: rcs0
[514.640617] Dynamic subtest rcs0: SUCCESS (0.046s)
[514.649403] Starting dynamic subtest: bcs0
[514.674744] Dynamic subtest bcs0: SUCCESS (0.034s)
[514.683134] Starting dynamic subtest: vcs0
[514.716964] Dynamic subtest vcs0: SUCCESS (0.042s)
[514.726765] Starting dynamic subtest: vcs1
[514.753877] Dynamic subtest vcs1: SUCCESS (0.036s)
[514.763257] Starting dynamic subtest: vecs0
[514.785148] Dynamic subtest vecs0: SUCCESS (0.031s)
[514.794363] Starting dynamic subtest: ccs0
[514.820395] Dynamic subtest ccs0: SUCCESS (0.035s)
[514.823132] Subtest u-submit-early-slice: SUCCESS (0.227s)
[515.979598] [062/130] (682s left) vgem_basic (bad-handle)
[516.025891] Starting subtest: bad-handle
[516.028939] Subtest bad-handle: SUCCESS (0.000s)
[516.076792] [063/130] (682s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[516.177074] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[517.211172] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.034s)
[517.403785] [064/130] (681s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[517.529131] Starting subtest: 2x-long-cursor-vs-flip-atomic
[517.535115] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[517.624258] [065/130] (680s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[517.727266] Starting subtest: 4-tiled-8bpp-rotate-90
[517.748142] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.021s)
[517.867461] [066/130] (680s left) gem_mmap_gtt (close-race)
[517.928078] Subtest close-race: SKIP (0.000s)
[517.991015] [067/130] (680s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[518.053454] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[518.114266] [068/130] (680s left) gem_exec_reloc (basic-wc)
[518.175954] Subtest basic-wc: SKIP (0.000s)
[518.245351] [069/130] (680s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[518.352589] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[518.357878] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[518.446147] [070/130] (680s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[518.538504] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[518.542338] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[518.634200] [071/130] (680s left) gem_ctx_exec (basic)
[518.692658] Starting subtest: basic
[518.698197] Subtest basic: SUCCESS (0.006s)
[518.800634] [072/130] (679s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[518.901863] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[518.906385] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[519.002503] [073/130] (679s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[519.113478] Starting subtest: yf-tiled-32bpp-rotate-270
[519.117609] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[519.208580] [074/130] (679s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[519.296419] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[519.298780] Starting dynamic subtest: pipe-A
[519.307766] Dynamic subtest pipe-A: SKIP (0.005s)
[519.316823] Starting dynamic subtest: pipe-B
[519.325794] Dynamic subtest pipe-B: SKIP (0.015s)
[519.333882] Starting dynamic subtest: pipe-C
[519.342797] Dynamic subtest pipe-C: SKIP (0.016s)
[519.350806] Starting dynamic subtest: pipe-D
[519.358125] Dynamic subtest pipe-D: SKIP (0.015s)
[519.362170] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.052s)
[519.450952] [075/130] (679s left) gem_readwrite (read-bad-handle)
[519.504930] Subtest read-bad-handle: SKIP (0.000s)
[519.555590] [076/130] (679s left) gem_eio (wait-1us)
[519.772395] Starting subtest: wait-1us
[520.105971] Subtest wait-1us: SUCCESS (0.334s)
[520.220270] [077/130] (678s left) gem_exec_fence (syncobj-wait)
[520.661962] Starting subtest: syncobj-wait
[521.959851] Subtest syncobj-wait: SUCCESS (1.297s)
[523.922590] [078/130] (675s left) i915_pm_rpm (system-suspend)
[526.061051] Starting subtest: system-suspend
[544.472737] Subtest system-suspend: SUCCESS (2.909s)
[544.832095] [079/130] (669s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[544.888856] Starting subtest: invalid-single-wait-all-unsubmitted
[544.891203] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[544.996320] [080/130] (669s left) gem_render_copy (x-tiled)
[545.070697] Starting subtest: x-tiled
[545.073221] Starting dynamic subtest: smem
[545.114581] Dynamic subtest smem: SUCCESS (0.043s)
[545.117701] Subtest x-tiled: SUCCESS (0.043s)
[545.355707] [081/130] (669s left) perf_pmu (invalid-init)
[545.396327] Starting subtest: invalid-init
[545.459812] Subtest invalid-init: FAIL (0.013s)
[545.518685] [082/130] (669s left) kms_sequence (get-forked)
[545.620023] Starting subtest: get-forked
[545.622394] Starting dynamic subtest: pipe-A-eDP-1
[547.517335] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.903s)
[547.527384] Starting dynamic subtest: pipe-B-eDP-1
[550.057294] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.547s)
[550.066913] Starting dynamic subtest: pipe-C-eDP-1
[552.584260] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.535s)
[552.593334] Starting dynamic subtest: pipe-D-eDP-1
[555.102140] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.521s)
[555.105288] Subtest get-forked: SUCCESS (9.512s)
[555.887607] [083/130] (658s left) kms_psr (psr-sprite-plane-move)
[555.989897] Starting subtest: psr-sprite-plane-move
[555.992129] Starting dynamic subtest: eDP-1
[557.719406] Dynamic subtest eDP-1: SUCCESS (1.729s)
[557.722230] Subtest psr-sprite-plane-move: SUCCESS (1.730s)
[558.534981] [084/130] (656s left) gem_exec_suspend (basic)
[558.588672] Starting subtest: basic
[558.591102] Starting dynamic subtest: smem
[560.385235] Dynamic subtest smem: SUCCESS (1.797s)
[560.388354] Subtest basic: SUCCESS (1.799s)
[560.566726] [085/130] (654s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[560.670123] Starting subtest: fbc-psr-cursor-mmap-cpu
[560.672846] Starting dynamic subtest: eDP-1
[560.678060] Dynamic subtest eDP-1: SKIP (0.000s)
[560.683162] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[560.738056] [086/130] (653s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[565.772397] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[565.775068] Starting dynamic subtest: pipe-A-eDP-1
[577.500264] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.735s)
[577.514805] Starting dynamic subtest: pipe-B-eDP-1
[589.239332] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.731s)
[589.242107] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (23.471s)
[590.070686] [087/130] (624s left) kms_flip (plain-flip-fb-recreate-interruptible)
[590.182389] Starting subtest: plain-flip-fb-recreate-interruptible
[590.185235] Starting dynamic subtest: A-eDP1
[599.058592] Dynamic subtest A-eDP1: SUCCESS (8.876s)
[599.067977] Starting dynamic subtest: B-eDP1
[607.892967] Dynamic subtest B-eDP1: SUCCESS (8.835s)
[607.903112] Starting dynamic subtest: C-eDP1
[616.711385] Dynamic subtest C-eDP1: SUCCESS (8.820s)
[616.722251] Starting dynamic subtest: D-eDP1
[625.538385] Dynamic subtest D-eDP1: SUCCESS (8.829s)
[625.544779] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.368s)
[626.702320] [088/130] (588s left) kms_prime (basic-modeset-hybrid)
[628.979251] Subtest basic-modeset-hybrid: SKIP (0.000s)
[629.051720] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.amdgpu: true
[629.052393] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.xe: true
[629.055886] [089/130] (585s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[629.150640] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[629.153151] Starting dynamic subtest: pipe-A-eDP-1
[629.163199] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[629.172863] Starting dynamic subtest: pipe-B-eDP-1
[629.182075] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[629.190821] Starting dynamic subtest: pipe-C-eDP-1
[629.200755] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[629.209140] Starting dynamic subtest: pipe-D-eDP-1
[629.219170] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[629.224382] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[631.359528] [090/130] (583s left) gem_ctx_persistence (engines-hostile-preempt)
[631.426173] Starting subtest: engines-hostile-preempt
[631.429240] Starting dynamic subtest: rcs0
[631.524612] Dynamic subtest rcs0: SUCCESS (0.098s)
[631.537076] Starting dynamic subtest: bcs0
[631.560631] Dynamic subtest bcs0: SUCCESS (0.035s)
[631.569386] Starting dynamic subtest: vcs0
[633.403183] Dynamic subtest vcs0: SUCCESS (1.841s)
[633.415652] Starting dynamic subtest: vcs1
[635.416844] Dynamic subtest vcs1: SUCCESS (2.013s)
[635.428355] Starting dynamic subtest: vecs0
[637.392211] Dynamic subtest vecs0: SUCCESS (1.973s)
[637.403355] Starting dynamic subtest: ccs0
[637.470951] Dynamic subtest ccs0: SUCCESS (0.078s)
[637.474003] Subtest engines-hostile-preempt: SUCCESS (6.047s)
[637.546706] [091/130] (577s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[637.667136] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[642.204908] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.542s)
[642.310575] [092/130] (572s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[642.413179] Starting subtest: fbc-rgb565-draw-blt
[645.667735] Subtest fbc-rgb565-draw-blt: SUCCESS (3.258s)
[645.810182] [093/130] (569s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[645.908753] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[645.910865] Starting dynamic subtest: pipe-A-default-mode
[648.649674] Dynamic subtest pipe-A-default-mode: SUCCESS (2.736s)
[648.652738] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.738s)
[649.510106] [094/130] (565s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[649.613127] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[649.615708] Starting dynamic subtest: pipe-A
[650.127188] Dynamic subtest pipe-A: SUCCESS (0.514s)
[650.136026] Starting dynamic subtest: pipe-B
[651.675147] Dynamic subtest pipe-B: SUCCESS (1.545s)
[651.684243] Starting dynamic subtest: pipe-C
[653.199204] Dynamic subtest pipe-C: SUCCESS (1.523s)
[653.209296] Starting dynamic subtest: pipe-D
[654.747811] Dynamic subtest pipe-D: SUCCESS (1.548s)
[654.750193] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (5.135s)
[655.933123] [095/130] (559s left) kms_pm_rpm (cursor-dpms)
[658.574975] Starting subtest: cursor-dpms
[658.580959] Subtest cursor-dpms: SKIP (0.000s)
[658.937655] [096/130] (556s left) kms_psr (psr2-sprite-blt)
[659.050171] Starting subtest: psr2-sprite-blt
[659.052826] Starting dynamic subtest: eDP-1
[660.819672] Dynamic subtest eDP-1: SUCCESS (1.767s)
[660.822145] Subtest psr2-sprite-blt: SUCCESS (1.768s)
[661.686713] [097/130] (553s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[661.798573] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[661.803787] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[661.898655] [098/130] (553s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[661.955055] Starting subtest: wait-all-for-submit-delayed-submit
[662.054892] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[662.127663] [099/130] (553s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[662.240548] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[662.243042] Starting dynamic subtest: pipe-A-default-mode
[662.536694] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[662.542207] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.296s)
[663.328649] [100/130] (551s left) drm_buddy (drm_buddy)
[663.370153] Starting subtest: drm_buddy
[663.380394] Starting dynamic subtest: drm_test_buddy_alloc_limit
[663.525218] Dynamic subtest drm_test_buddy_alloc_limit: SUCCESS (0.144s)
[663.536241] Starting dynamic subtest: drm_test_buddy_alloc_optimistic
[663.669266] Dynamic subtest drm_test_buddy_alloc_optimistic: SUCCESS (0.147s)
[663.680228] Starting dynamic subtest: drm_test_buddy_alloc_pessimistic
[663.818366] Dynamic subtest drm_test_buddy_alloc_pessimistic: SUCCESS (0.144s)
[663.828098] Starting dynamic subtest: drm_test_buddy_alloc_pathological
[663.958283] Dynamic subtest drm_test_buddy_alloc_pathological: SUCCESS (0.142s)
[663.969189] Starting dynamic subtest: drm_test_buddy_alloc_contiguous
[664.089145] Dynamic subtest drm_test_buddy_alloc_contiguous: SUCCESS (0.127s)
[664.099901] Starting dynamic subtest: drm_test_buddy_alloc_clear
[664.275113] Dynamic subtest drm_test_buddy_alloc_clear: SUCCESS (0.186s)
[664.285862] Starting dynamic subtest: drm_test_buddy_alloc_range_bias
[664.405084] Dynamic subtest drm_test_buddy_alloc_range_bias: SUCCESS (0.127s)
[664.408218] Subtest drm_buddy: SUCCESS (1.035s)
[664.600963] [101/130] (550s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[664.712242] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[665.978830] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.270s)
[666.214397] [102/130] (549s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[666.332121] Starting subtest: fbc-shrfb-scaledprimary
[670.433411] Subtest fbc-shrfb-scaledprimary: SUCCESS (4.107s)
[670.566871] [103/130] (544s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-blt)
[670.673770] Starting subtest: psr-1p-offscren-pri-indfb-draw-blt
[675.490951] Subtest psr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.821s)
[675.634907] [104/130] (539s left) gem_wait (write-busy)
[675.702257] Starting subtest: write-busy
[675.705269] Starting dynamic subtest: all-engines
[676.312660] Dynamic subtest all-engines: SUCCESS (0.610s)
[676.329163] Starting dynamic subtest: rcs0
[676.860831] Dynamic subtest rcs0: SUCCESS (0.548s)
[676.876041] Starting dynamic subtest: bcs0
[677.408053] Dynamic subtest bcs0: SUCCESS (0.546s)
[677.423031] Starting dynamic subtest: vcs0
[679.359772] Dynamic subtest vcs0: SUCCESS (1.950s)
[679.374708] Starting dynamic subtest: vcs1
[681.414795] Dynamic subtest vcs1: SUCCESS (2.047s)
[681.425212] Starting dynamic subtest: vecs0
[683.397126] Dynamic subtest vecs0: SUCCESS (1.983s)
[683.406722] Starting dynamic subtest: ccs0
[683.940220] Dynamic subtest ccs0: SUCCESS (0.548s)
[683.943138] Subtest write-busy: SUCCESS (8.239s)
[684.142272] [105/130] (531s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[684.247503] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[684.250326] Starting dynamic subtest: pipe-A
[684.260891] Dynamic subtest pipe-A: SKIP (0.004s)
[684.270992] Starting dynamic subtest: pipe-B
[684.281800] Dynamic subtest pipe-B: SKIP (0.015s)
[684.291274] Starting dynamic subtest: pipe-C
[684.303004] Dynamic subtest pipe-C: SKIP (0.015s)
[684.312144] Starting dynamic subtest: pipe-D
[684.320875] Dynamic subtest pipe-D: SKIP (0.015s)
[684.325833] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.051s)
[684.414742] [106/130] (531s left) gem_exec_balancer (full-pulse)
[684.482326] Starting subtest: full-pulse
[689.199358] Subtest full-pulse: SUCCESS (4.717s)