[58.847357] Initializing watchdogs
[58.847505]   /dev/watchdog0
[58.863636] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.867866] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.869933] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.873132] [001/130] (960s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[58.987033] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[58.989349] Starting dynamic subtest: pipe-A-eDP-1
[59.008982] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[59.015614] Starting dynamic subtest: pipe-B-eDP-1
[59.033684] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[59.039738] Starting dynamic subtest: pipe-C-eDP-1
[59.058318] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[59.064868] Starting dynamic subtest: pipe-D-eDP-1
[59.083815] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[59.085702] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[61.252296] [002/130] (958s left) gem_fence_thrash (bo-write-verify-x)
[61.288202] Starting subtest: bo-write-verify-x
[61.307709] Subtest bo-write-verify-x: SKIP (0.017s)
[61.372848] [003/130] (958s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[61.464576] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[69.115858] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.654s)
[69.283842] [004/130] (950s left) gem_exec_parallel (contexts)
[69.350554] Starting subtest: contexts
[69.352950] Starting dynamic subtest: rcs0
[72.066476] Dynamic subtest rcs0: SUCCESS (2.714s)
[72.076896] Starting dynamic subtest: bcs0
[75.304032] Dynamic subtest bcs0: SUCCESS (3.236s)
[75.314253] Starting dynamic subtest: vcs0
[79.358800] Dynamic subtest vcs0: SUCCESS (4.055s)
[79.368709] Starting dynamic subtest: vcs1
[83.283791] Dynamic subtest vcs1: SUCCESS (3.923s)
[83.296753] Starting dynamic subtest: vecs0
[87.409738] Dynamic subtest vecs0: SUCCESS (4.125s)
[87.417815] Starting dynamic subtest: ccs0
[90.957039] Dynamic subtest ccs0: SUCCESS (3.545s)
[90.959866] Subtest contexts: SUCCESS (21.605s)
[91.168611] [005/130] (928s left) gem_wait (invalid-buf)
[91.227543] Starting subtest: invalid-buf
[91.230049] Subtest invalid-buf: SUCCESS (0.000s)
[91.454982] [006/130] (928s left) gem_tiled_wb
[91.563495] [007/130] (928s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[91.663218] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[95.252884] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.593s)
[96.494484] [008/130] (923s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[96.602545] Starting subtest: x-tiled-8bpp-rotate-90
[96.625694] Subtest x-tiled-8bpp-rotate-90: SKIP (0.021s)
[96.768322] [009/130] (922s left) i915_drm_fdinfo (most-busy-idle-check-all)
[96.812283] Starting subtest: most-busy-idle-check-all
[96.814154] Starting dynamic subtest: rcs0
[96.818611] Dynamic subtest rcs0: SKIP (0.003s)
[96.825950] Starting dynamic subtest: bcs0
[96.830268] Dynamic subtest bcs0: SKIP (0.000s)
[96.837500] Starting dynamic subtest: vcs0
[96.841264] Dynamic subtest vcs0: SKIP (0.000s)
[96.848105] Starting dynamic subtest: vcs1
[96.852201] Dynamic subtest vcs1: SKIP (0.000s)
[96.858940] Starting dynamic subtest: vecs0
[96.863074] Dynamic subtest vecs0: SKIP (0.000s)
[96.869724] Starting dynamic subtest: ccs0
[96.873713] Dynamic subtest ccs0: SKIP (0.000s)
[96.878104] Subtest most-busy-idle-check-all: SKIP (0.006s)
[96.909225] [010/130] (922s left) gem_exec_reloc (basic-wc-noreloc)
[97.010293] Subtest basic-wc-noreloc: SKIP (0.000s)
[97.075751] [011/130] (922s left) gem_mmap_offset (blt-coherency)
[97.131045] Starting subtest: blt-coherency
[97.139244] Subtest blt-coherency: SUCCESS (0.010s)
[97.288242] [012/130] (922s left) kms_dp_aux_dev
[97.655033] [013/130] (922s left) gem_exec_params (cliprects-invalid)
[97.710553] Starting subtest: cliprects-invalid
[97.713052] Subtest cliprects-invalid: SUCCESS (0.000s)
[97.837608] [014/130] (922s left) vgem_basic (mmap)
[97.958953] Starting subtest: mmap
[97.965745] Subtest mmap: SUCCESS (0.010s)
[98.045103] [FACT vgem_basic (mmap)] new: kernel.kmod_is_loaded.vgem: true
[98.048112] [015/130] (921s left) kms_getfb (getfb-handle-closed)
[98.149712] Starting subtest: getfb-handle-closed
[98.152072] Subtest getfb-handle-closed: SUCCESS (0.000s)
[98.283767] [016/130] (921s left) kms_cursor_crc (cursor-offscreen-max-size)
[98.582906] Starting subtest: cursor-offscreen-max-size
[98.588495] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[98.745679] [017/130] (921s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[98.857039] Starting subtest: cursorB-vs-flipB-varying-size
[98.860768] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[99.033541] [018/130] (920s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[99.140838] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[99.144289] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[99.271603] [019/130] (920s left) kms_big_fb (linear-8bpp-rotate-270)
[99.368967] Starting subtest: linear-8bpp-rotate-270
[99.390256] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[99.547159] [020/130] (920s left) kms_dp_linktrain_fallback (dp-fallback)
[99.644998] Starting subtest: dp-fallback
[99.650390] Subtest dp-fallback: SKIP (0.000s)
[99.741680] [021/130] (920s left) core_debugfs (read-all-entries)
[99.802064] Starting subtest: read-all-entries
[99.895575] Subtest read-all-entries: SUCCESS (0.095s)
[100.038314] [022/130] (920s left) gem_ctx_param (invalid-get-engines)
[100.102981] Starting subtest: invalid-get-engines
[100.105378] Subtest invalid-get-engines: SUCCESS (0.001s)
[100.265367] [023/130] (919s left) vgem_basic (setversion)
[100.316177] Starting subtest: setversion
[100.319849] Subtest setversion: SUCCESS (0.000s)
[100.391423] [024/130] (919s left) kms_psr (psr-primary-render)
[100.485128] Starting subtest: psr-primary-render
[100.487104] Starting dynamic subtest: eDP-1
[102.251078] Dynamic subtest eDP-1: SUCCESS (1.766s)
[102.253403] Subtest psr-primary-render: SUCCESS (1.766s)
[103.130459] [025/130] (917s left) kms_getfb (getfb-handle-zero)
[103.234477] Starting subtest: getfb-handle-zero
[103.236686] Subtest getfb-handle-zero: SUCCESS (0.000s)
[103.357985] [026/130] (916s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[103.454805] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[103.457183] Starting dynamic subtest: pipe-A-default-mode
[103.739191] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[103.743528] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.283s)
[104.557620] [027/130] (915s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[104.629376] Starting subtest: basic-batch-kernel-default-cmd
[104.633488] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[104.832832] [028/130] (915s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[104.942126] Starting subtest: fbc-farfromfence-mmap-gtt
[104.946570] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[105.081609] [029/130] (915s left) kms_plane_multiple (2x-tiling-4)
[105.180678] Starting subtest: 2x-tiling-4
[105.184981] Subtest 2x-tiling-4: SKIP (0.000s)
[105.275154] [030/130] (915s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[105.365218] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[105.367457] Starting dynamic subtest: pipe-A-eDP-1
[105.534586] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.159s)
[105.544888] Starting dynamic subtest: pipe-B-eDP-1
[105.565203] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[105.573291] Starting dynamic subtest: pipe-C-eDP-1
[105.593045] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[105.600330] Starting dynamic subtest: pipe-D-eDP-1
[105.619661] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[105.621863] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.166s)
[107.823603] [031/130] (912s left) gem_eio (in-flight-suspend)
[108.063973] Starting subtest: in-flight-suspend
[154.823649] Subtest in-flight-suspend: SUCCESS (16.835s)
[155.006709] [032/130] (865s left) gen7_exec_parse (bitmasks)
[155.071994] Subtest bitmasks: SKIP (0.000s)
[155.144079] [033/130] (865s left) gem_mmap_gtt (medium-copy-xy)
[155.202649] Subtest medium-copy-XY: SKIP (0.000s)
[155.264387] [034/130] (865s left) kms_chamelium_frames (hdmi-crc-single)
[155.638676] Subtest hdmi-crc-single: SKIP (0.000s)
[156.407781] [035/130] (864s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[156.510962] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[156.515286] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[156.625880] [036/130] (864s left) gem_exec_schedule (timeslicing)
[156.702075] Starting subtest: timeslicing
[156.704423] Starting dynamic subtest: rcs0
[156.713208] Dynamic subtest rcs0: SUCCESS (0.021s)
[156.725321] Starting dynamic subtest: bcs0
[156.734339] Dynamic subtest bcs0: SUCCESS (0.021s)
[156.743747] Starting dynamic subtest: vcs0
[156.754920] Dynamic subtest vcs0: SUCCESS (0.020s)
[156.764155] Starting dynamic subtest: vcs1
[156.775934] Dynamic subtest vcs1: SUCCESS (0.021s)
[156.790136] Starting dynamic subtest: vecs0
[156.798294] Dynamic subtest vecs0: SUCCESS (0.022s)
[156.808941] Starting dynamic subtest: ccs0
[156.819382] Dynamic subtest ccs0: SUCCESS (0.021s)
[156.821690] Subtest timeslicing: SUCCESS (0.128s)
[157.882736] [037/130] (862s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[162.920862] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[162.923067] Starting dynamic subtest: pipe-A-eDP-1
[180.092054] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.169s)
[180.102734] Starting dynamic subtest: pipe-B-eDP-1
[197.222769] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.126s)
[197.225080] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.298s)
[198.045696] [038/130] (822s left) gem_exec_schedule (deep)
[198.222932] Starting subtest: deep
[198.225689] Starting dynamic subtest: rcs0
[203.477024] Dynamic subtest rcs0: SKIP (5.251s)
[203.489153] Starting dynamic subtest: vcs0
[214.431517] Dynamic subtest vcs0: SUCCESS (10.954s)
[214.443741] Starting dynamic subtest: vcs1
[226.146516] Dynamic subtest vcs1: SUCCESS (11.714s)
[226.158952] Starting dynamic subtest: vecs0
[237.773872] Dynamic subtest vecs0: SUCCESS (11.627s)
[237.785563] Starting dynamic subtest: ccs0
[250.562311] Dynamic subtest ccs0: SUCCESS (12.788s)
[250.564657] Subtest deep: SUCCESS (52.337s)
[251.645867] [039/130] (769s left) kms_color (degamma)
[251.750623] Starting subtest: degamma
[251.752523] Starting dynamic subtest: pipe-A-eDP-1
[252.485682] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.735s)
[252.497118] Starting dynamic subtest: pipe-B-eDP-1
[253.952157] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.466s)
[253.961494] Starting dynamic subtest: pipe-C-eDP-1
[255.436937] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.486s)
[255.446119] Starting dynamic subtest: pipe-D-eDP-1
[256.838553] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.399s)
[256.840754] Subtest degamma: SUCCESS (5.089s)
[257.664554] [040/130] (763s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[257.770903] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[257.775412] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[257.915856] [041/130] (763s left) gem_eio (banned)
[258.142269] Starting subtest: banned
[258.761857] Subtest banned: SUCCESS (0.620s)
[258.955152] [042/130] (762s left) gem_ppgtt (flink-and-close-vma-leak)
[259.034844] Starting subtest: flink-and-close-vma-leak
[259.075520] Subtest flink-and-close-vma-leak: SUCCESS (0.041s)
[259.229800] [043/130] (761s left) kms_cursor_crc (cursor-sliding-128x42)
[259.458089] Starting subtest: cursor-sliding-128x42
[259.514918] Subtest cursor-sliding-128x42: SKIP (0.054s)
[259.751236] [044/130] (761s left) kms_atomic_transition (modeset-transition-nonblocking)
[259.855249] Starting subtest: modeset-transition-nonblocking
[259.857176] Starting dynamic subtest: 1x-outputs
[271.004119] Dynamic subtest 1x-outputs: SUCCESS (11.149s)
[271.006625] Subtest modeset-transition-nonblocking: SUCCESS (11.150s)
[271.886488] [045/130] (749s left) kms_plane_alpha_blend (alpha-transparent-fb)
[271.993978] Starting subtest: alpha-transparent-fb
[272.281310] Starting dynamic subtest: pipe-A-eDP-1
[272.918193] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.637s)
[272.927784] Starting dynamic subtest: pipe-D-eDP-1
[274.220582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.299s)
[274.222957] Subtest alpha-transparent-fb: SUCCESS (2.227s)
[275.069229] [046/130] (746s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[275.177554] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[282.473369] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.298s)
[282.630854] [047/130] (738s left) syncobj_wait (wait-all-complex)
[282.681296] Starting subtest: wait-all-complex
[282.893962] Subtest wait-all-complex: SUCCESS (0.212s)
[282.994291] [048/130] (738s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[283.097838] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[289.830703] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.737s)
[289.978770] [049/130] (731s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[290.082728] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[290.087240] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[291.271238] [050/130] (730s left) gem_exec_whisper (basic-fds-priority)
[291.342630] Starting subtest: basic-fds-priority
[315.299480] Subtest basic-fds-priority: SUCCESS (23.956s)
[315.509689] [051/130] (705s left) gem_wait (await)
[315.581581] Starting subtest: await
[315.583963] Starting dynamic subtest: all-engines
[316.757093] Dynamic subtest all-engines: SUCCESS (1.174s)
[316.778632] Starting dynamic subtest: rcs0
[317.825159] Dynamic subtest rcs0: SUCCESS (1.066s)
[317.842189] Starting dynamic subtest: bcs0
[318.889177] Dynamic subtest bcs0: SUCCESS (1.062s)
[318.904822] Starting dynamic subtest: vcs0
[319.945940] Dynamic subtest vcs0: SUCCESS (1.055s)
[319.963042] Starting dynamic subtest: vcs1
[321.000382] Dynamic subtest vcs1: SUCCESS (1.052s)
[321.017287] Starting dynamic subtest: vecs0
[322.054478] Dynamic subtest vecs0: SUCCESS (1.052s)
[322.070703] Starting dynamic subtest: ccs0
[323.110235] Dynamic subtest ccs0: SUCCESS (1.054s)
[323.112777] Subtest await: SUCCESS (7.529s)
[323.332692] [052/130] (698s left) kms_psr (pr-no-drrs)
[323.433754] Starting subtest: pr-no-drrs
[323.438134] Subtest pr-no-drrs: SKIP (0.000s)
[323.532097] [053/130] (697s left) kms_joiner (basic-max-non-joiner)
[323.650517] Starting subtest: basic-max-non-joiner
[323.654806] Subtest basic-max-non-joiner: SKIP (0.000s)
[323.733298] [054/130] (697s left) kms_vblank (query-idle-hang)
[323.820850] Starting subtest: query-idle-hang
[323.823120] Starting dynamic subtest: pipe-A-eDP-1
[350.588631] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.767s)
[350.613755] Starting dynamic subtest: pipe-D-eDP-1
[375.602090] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.006s)
[375.604727] Subtest query-idle-hang: SUCCESS (51.781s)
[376.425788] [055/130] (645s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[376.483471] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[376.485452] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[376.611853] [056/130] (644s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[376.858820] Starting subtest: cursor-rapid-movement-256x85
[376.901299] Subtest cursor-rapid-movement-256x85: SKIP (0.041s)
[377.140180] [057/130] (644s left) gem_lmem_swapping (verify-ccs)
[377.181283] Subtest verify-ccs: SKIP (0.000s)
[377.225142] [058/130] (644s left) kms_universal_plane (cursor-fb-leak)
[377.319553] Starting subtest: cursor-fb-leak
[377.321900] Starting dynamic subtest: pipe-A-eDP-1
[377.631352] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.314s)
[377.647216] Starting dynamic subtest: pipe-B-eDP-1
[378.997958] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.364s)
[379.009459] Starting dynamic subtest: pipe-C-eDP-1
[380.329822] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.328s)
[380.339090] Starting dynamic subtest: pipe-D-eDP-1
[381.761138] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.430s)
[381.763465] Subtest cursor-fb-leak: SUCCESS (4.442s)
[382.952777] [059/130] (638s left) kms_getfb (getfb-reject-nv12)
[383.051088] Starting subtest: getfb-reject-nv12
[383.053427] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[383.184266] [060/130] (638s left) kms_lease (atomic-implicit-crtc)
[383.268011] Starting subtest: atomic-implicit-crtc
[383.270556] Starting dynamic subtest: pipe-A-eDP-1
[383.282096] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[383.293654] Starting dynamic subtest: pipe-B-eDP-1
[383.305142] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[383.313930] Starting dynamic subtest: pipe-C-eDP-1
[383.324247] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[383.332619] Starting dynamic subtest: pipe-D-eDP-1
[383.343070] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[383.344960] Subtest atomic-implicit-crtc: SUCCESS (0.037s)
[383.444567] [061/130] (638s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[383.550074] Starting subtest: flip-vs-dpms-on-nop-interruptible
[383.552262] Starting dynamic subtest: A-eDP1
[385.433342] Dynamic subtest A-eDP1: SUCCESS (1.881s)
[385.446426] Starting dynamic subtest: B-eDP1
[387.267701] Dynamic subtest B-eDP1: SUCCESS (1.833s)
[387.279835] Starting dynamic subtest: C-eDP1
[389.093488] Dynamic subtest C-eDP1: SUCCESS (1.824s)
[389.103773] Starting dynamic subtest: D-eDP1
[390.909338] Dynamic subtest D-eDP1: SUCCESS (1.815s)
[390.914124] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.364s)
[392.121750] [062/130] (629s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[392.230202] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[392.232121] Starting dynamic subtest: pipe-A-eDP-1
[392.974482] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[392.984951] Starting dynamic subtest: pipe-B-eDP-1
[394.728033] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.752s)
[394.737073] Starting dynamic subtest: pipe-C-eDP-1
[396.550187] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.820s)
[396.560883] Starting dynamic subtest: pipe-D-eDP-1
[398.386971] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.835s)
[398.389305] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.150s)
[399.547228] [063/130] (622s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[399.650880] Starting subtest: fbc-modesetfrombusy
[403.496213] Subtest fbc-modesetfrombusy: SUCCESS (3.857s)
[404.692506] [064/130] (617s left) gem_create (busy-create)
[404.750477] Starting subtest: busy-create
[404.752842] Starting dynamic subtest: smem0
[438.791152] Dynamic subtest smem0: SUCCESS (34.037s)
[438.793781] Subtest busy-create: SUCCESS (34.039s)
[439.006169] [065/130] (582s left) kms_psr (fbc-pr-sprite-render)
[439.110497] Starting subtest: fbc-pr-sprite-render
[439.115202] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[439.215525] [066/130] (582s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[439.308190] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[439.312366] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[439.444357] [067/130] (582s left) gem_ctx_persistence (engines-queued)
[439.509928] Starting subtest: engines-queued
[439.512138] Starting dynamic subtest: rcs0
[439.612886] Dynamic subtest rcs0: SUCCESS (0.102s)
[439.629723] Starting dynamic subtest: bcs0
[439.648570] Dynamic subtest bcs0: SUCCESS (0.034s)
[439.659713] Starting dynamic subtest: vcs0
[440.795990] Dynamic subtest vcs0: SUCCESS (1.145s)
[440.808696] Starting dynamic subtest: vcs1
[442.778677] Dynamic subtest vcs1: SUCCESS (1.979s)
[442.791925] Starting dynamic subtest: vecs0
[444.761194] Dynamic subtest vecs0: SUCCESS (1.979s)
[444.776567] Starting dynamic subtest: ccs0
[444.949086] Dynamic subtest ccs0: SUCCESS (0.186s)
[444.951515] Subtest engines-queued: SUCCESS (5.439s)
[445.114325] [068/130] (576s left) gem_sync (basic-each)
[445.180465] Starting subtest: basic-each
[447.735977] Subtest basic-each: SUCCESS (2.565s)
[447.944365] [069/130] (574s left) gem_render_copy (x-tiled)
[448.016581] Starting subtest: x-tiled
[448.019025] Starting dynamic subtest: smem
[448.066809] Dynamic subtest smem: SUCCESS (0.049s)
[448.069961] Subtest x-tiled: SUCCESS (0.050s)
[448.341315] [070/130] (573s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[448.407193] Starting subtest: yf-tiled-ccs-to-x-tiled
[448.411682] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[448.618022] [071/130] (573s left) kms_flip (basic-flip-vs-wf_vblank)
[448.729738] Starting subtest: basic-flip-vs-wf_vblank
[448.732158] Starting dynamic subtest: A-eDP1
[450.702145] Dynamic subtest A-eDP1: SUCCESS (1.972s)
[450.714425] Starting dynamic subtest: B-eDP1
[452.629736] Dynamic subtest B-eDP1: SUCCESS (1.926s)
[452.645226] Starting dynamic subtest: C-eDP1
[454.548398] Dynamic subtest C-eDP1: SUCCESS (1.916s)
[454.557579] Starting dynamic subtest: D-eDP1
[456.479377] Dynamic subtest D-eDP1: SUCCESS (1.929s)
[456.484337] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.755s)
[457.720128] [072/130] (564s left) gem_exec_reloc (basic-gtt-wc-active)
[457.788967] Subtest basic-gtt-wc-active: SKIP (0.000s)
[457.870443] [073/130] (564s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[457.957278] Starting subtest: async-flip-with-page-flip-events-tiled
[458.332520] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[459.676192] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: FAIL (0.767s)
[459.686916] Starting dynamic subtest: pipe-A-eDP-1-4
[460.033863] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.646s)
[460.044660] Starting dynamic subtest: pipe-A-eDP-1-4
[460.156435] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.016s)
[460.166865] Starting dynamic subtest: pipe-A-eDP-1-x
[460.466477] Dynamic subtest pipe-A-eDP-1-x: FAIL (0.170s)
[460.478499] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[461.696215] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: FAIL (1.176s)
[461.707326] Starting dynamic subtest: pipe-B-eDP-1-4
[462.060937] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.176s)
[462.072263] Starting dynamic subtest: pipe-B-eDP-1-4
[462.188030] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.008s)
[462.198551] Starting dynamic subtest: pipe-B-eDP-1-x
[462.487335] Dynamic subtest pipe-B-eDP-1-x: FAIL (0.610s)
[462.674570] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[465.422194] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.756s)
[465.432200] Starting dynamic subtest: pipe-C-eDP-1-4
[467.595573] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.172s)
[467.605080] Starting dynamic subtest: pipe-C-eDP-1-4
[469.606667] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[469.619086] Starting dynamic subtest: pipe-C-eDP-1-x
[471.786021] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[472.146923] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[474.945824] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.805s)
[474.961527] Starting dynamic subtest: pipe-D-eDP-1-4
[477.121659] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[477.133380] Starting dynamic subtest: pipe-D-eDP-1-4
[479.132508] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[479.144557] Starting dynamic subtest: pipe-D-eDP-1-x
[481.309827] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[481.312161] Subtest async-flip-with-page-flip-events-tiled: FAIL (23.353s)
[482.561417] [074/130] (539s left) gem_softpin (allocator-basic-reserve)
[482.620235] Starting subtest: allocator-basic-reserve
[482.685440] Subtest allocator-basic-reserve: SUCCESS (0.065s)
[482.866856] [075/130] (539s left) perf_pmu (pmu-read)
[482.911018] Starting subtest: pmu-read
[482.913369] Subtest pmu-read: SUCCESS (0.002s)
[483.038993] [076/130] (539s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[483.148369] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[483.152423] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[483.263355] [077/130] (539s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[483.361197] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[483.365553] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[483.495968] [078/130] (538s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[483.549658] Starting subtest: single-wait-for-submit-available-submitted
[483.551557] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[483.690821] [079/130] (538s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[483.781110] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[483.784649] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[483.890772] [080/130] (538s left) gem_render_copy (yf-tiled-to-vebox-linear)
[483.953466] Starting subtest: yf-tiled-to-vebox-linear
[483.957258] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[484.159124] [081/130] (538s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[484.710475] Starting subtest: syncobj-stationary-timeline-chain-engines
[484.827639] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.118s)
[485.944458] [082/130] (536s left) kms_psr (fbc-pr-primary-mmap-gtt)
[486.037930] Starting subtest: fbc-pr-primary-mmap-gtt
[486.042585] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[486.136502] [083/130] (536s left) gem_mmap_wc (copy)
[486.196596] Subtest copy: SKIP (0.000s)
[486.263294] [084/130] (536s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[486.390437] Starting subtest: plane-upscale-factor-0-25-with-rotation
[486.392363] Starting dynamic subtest: pipe-A
[486.631420] Dynamic subtest pipe-A: SUCCESS (0.242s)
[486.641240] Starting dynamic subtest: pipe-B
[487.914352] Dynamic subtest pipe-B: SUCCESS (1.282s)
[487.924401] Starting dynamic subtest: pipe-C
[489.247052] Dynamic subtest pipe-C: SUCCESS (1.332s)
[489.256952] Starting dynamic subtest: pipe-D
[490.570661] Dynamic subtest pipe-D: SUCCESS (1.322s)
[490.573071] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.182s)
[491.781279] [085/130] (530s left) gem_exec_schedule (preemptive-hang)
[491.953187] Starting subtest: preemptive-hang
[491.955305] Starting dynamic subtest: rcs0
[500.965917] Dynamic subtest rcs0: SUCCESS (9.014s)
[500.974773] Starting dynamic subtest: bcs0
[509.923620] Dynamic subtest bcs0: SUCCESS (8.955s)
[509.934764] Starting dynamic subtest: vcs0
[518.946711] Dynamic subtest vcs0: SUCCESS (9.021s)
[518.961503] Starting dynamic subtest: vcs1
[527.968184] Dynamic subtest vcs1: SUCCESS (9.023s)
[527.977157] Starting dynamic subtest: vecs0
[536.931752] Dynamic subtest vecs0: SUCCESS (8.960s)
[536.945517] Starting dynamic subtest: ccs0
[545.955754] Dynamic subtest ccs0: SUCCESS (9.022s)
[545.958038] Subtest preemptive-hang: SUCCESS (54.003s)
[546.246681] [086/130] (476s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[546.340709] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[546.342974] Starting dynamic subtest: pipe-A-eDP-1
[546.365568] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[546.376471] Starting dynamic subtest: pipe-B-eDP-1
[546.398738] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[546.408239] Starting dynamic subtest: pipe-C-eDP-1
[546.429722] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[546.438382] Starting dynamic subtest: pipe-D-eDP-1
[546.459760] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[546.464066] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.017s)
[548.639850] [087/130] (474s left) gem_exec_suspend (basic-s3)
[548.694447] Starting subtest: basic-S3
[548.696819] Starting dynamic subtest: smem
[568.433388] Dynamic subtest smem: SUCCESS (6.116s)
[568.436322] Subtest basic-S3: SUCCESS (6.118s)
[568.646006] [088/130] (454s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[568.759668] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[568.763726] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[568.898039] [089/130] (454s left) gem_huc_copy (huc-copy)
[568.974236] Starting subtest: huc-copy
[568.979369] Subtest huc-copy: SUCCESS (0.004s)
[569.146607] [090/130] (453s left) kms_flip (flip-vs-expired-vblank)
[569.239297] Starting subtest: flip-vs-expired-vblank
[569.241476] Starting dynamic subtest: A-eDP1
[577.899201] Dynamic subtest A-eDP1: SUCCESS (8.656s)
[577.908921] Starting dynamic subtest: B-eDP1
[586.528408] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[586.540195] Starting dynamic subtest: C-eDP1
[595.150395] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[595.160943] Starting dynamic subtest: D-eDP1
[603.780913] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[603.786068] Subtest flip-vs-expired-vblank: SUCCESS (34.539s)
[604.979835] [091/130] (418s left) i915_pm_rc6_residency (rc6-fence)
[605.044033] Starting subtest: rc6-fence
[605.066879] Starting dynamic subtest: gt0
[617.383263] Dynamic subtest gt0: SUCCESS (12.314s)
[617.395841] Starting dynamic subtest: gt1
[632.431021] Dynamic subtest gt1: SUCCESS (15.042s)
[632.434218] Subtest rc6-fence: SUCCESS (27.384s)
[632.533453] [092/130] (390s left) kms_plane (plane-position-hole)
[632.628270] Starting subtest: plane-position-hole
[633.135011] Starting dynamic subtest: pipe-A-plane-1
[634.431124] Dynamic subtest pipe-A-plane-1: SUCCESS (1.297s)
[634.443305] Starting dynamic subtest: pipe-A-plane-2
[635.752261] Dynamic subtest pipe-A-plane-2: SUCCESS (1.321s)
[635.763199] Starting dynamic subtest: pipe-A-plane-3
[636.998702] Dynamic subtest pipe-A-plane-3: SUCCESS (1.244s)
[637.009511] Starting dynamic subtest: pipe-A-plane-4
[638.265259] Dynamic subtest pipe-A-plane-4: SUCCESS (1.267s)
[638.276436] Starting dynamic subtest: pipe-A-plane-5
[638.291106] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[639.486652] Starting dynamic subtest: pipe-B-plane-1
[640.711116] Dynamic subtest pipe-B-plane-1: SUCCESS (1.232s)
[640.721548] Starting dynamic subtest: pipe-B-plane-2
[641.946606] Dynamic subtest pipe-B-plane-2: SUCCESS (1.237s)
[641.958731] Starting dynamic subtest: pipe-B-plane-3
[643.196571] Dynamic subtest pipe-B-plane-3: SUCCESS (1.247s)
[643.206765] Starting dynamic subtest: pipe-B-plane-4
[644.470847] Dynamic subtest pipe-B-plane-4: SUCCESS (1.273s)
[644.486220] Starting dynamic subtest: pipe-B-plane-5
[644.494882] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[644.496986] Subtest plane-position-hole: SUCCESS (11.864s)
[645.346314] [093/130] (377s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[645.466587] Starting subtest: random-ccs-data-yf-tiled-ccs
[645.468705] Starting dynamic subtest: pipe-A-eDP-1
[645.492507] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[645.503766] Starting dynamic subtest: pipe-B-eDP-1
[645.527533] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[645.537410] Starting dynamic subtest: pipe-C-eDP-1
[645.560087] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[645.569472] Starting dynamic subtest: pipe-D-eDP-1
[645.592607] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[645.596614] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.013s)
[647.736571] [094/130] (375s left) i915_hangman (engine-engine-error)
[647.803479] Starting subtest: engine-engine-error
[647.805496] Starting dynamic subtest: rcs0
[649.422420] Dynamic subtest rcs0: SUCCESS (1.619s)
[649.435646] Starting dynamic subtest: bcs0
[651.406005] Dynamic subtest bcs0: SUCCESS (1.980s)
[651.418908] Starting dynamic subtest: vcs0
[653.390140] Dynamic subtest vcs0: SUCCESS (1.981s)
[653.404348] Starting dynamic subtest: vcs1
[655.437997] Dynamic subtest vcs1: SUCCESS (2.045s)
[655.454209] Starting dynamic subtest: vecs0
[657.421853] Dynamic subtest vecs0: SUCCESS (1.981s)
[657.436032] Starting dynamic subtest: ccs0
[659.406413] Dynamic subtest ccs0: SUCCESS (1.981s)
[659.409146] Subtest engine-engine-error: SUCCESS (11.604s)
[659.517986] [095/130] (363s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[659.626358] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[666.988019] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.362s)
[667.147488] [096/130] (356s left) gem_pwrite (basic-exhaustion)
[667.220628] Subtest basic-exhaustion: SKIP (0.000s)
[667.293388] [097/130] (355s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[667.349395] Starting subtest: etime-single-wait-available-unsubmitted
[667.451466] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.102s)
[667.551464] [098/130] (355s left) kms_prime (basic-crc-vgem)
[667.640005] Starting subtest: basic-crc-vgem
[667.642260] Starting dynamic subtest: second-to-first
[668.594218] Dynamic subtest second-to-first: SUCCESS (0.958s)
[668.596034] Subtest basic-crc-vgem: SUCCESS (0.959s)
[668.710605] [099/130] (354s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[668.809121] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[668.810937] Starting dynamic subtest: pipe-A-default-mode
[669.106685] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[669.111298] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.296s)
[670.002339] [100/130] (353s left) gem_exec_balancer (invalid-bonds)
[670.065577] Starting subtest: invalid-bonds
[670.070193] Subtest invalid-bonds: SKIP (0.000s)
[670.397301] [101/130] (353s left) gem_exec_balancer (sliced)
[670.451519] Starting subtest: sliced
[670.456286] Subtest sliced: SKIP (0.000s)
[670.787297] [102/130] (352s left) gem_exec_parallel (basic)
[670.834296] Starting subtest: basic
[670.836556] Starting dynamic subtest: rcs0
[672.287424] Dynamic subtest rcs0: SUCCESS (1.453s)
[672.297347] Starting dynamic subtest: bcs0
[673.779110] Dynamic subtest bcs0: SUCCESS (1.491s)
[673.789342] Starting dynamic subtest: vcs0
[675.465250] Dynamic subtest vcs0: SUCCESS (1.683s)
[675.474915] Starting dynamic subtest: vcs1
[677.423707] Dynamic subtest vcs1: SUCCESS (1.957s)
[677.437180] Starting dynamic subtest: vecs0
[679.407339] Dynamic subtest vecs0: SUCCESS (1.981s)
[679.417918] Starting dynamic subtest: ccs0
[680.906396] Dynamic subtest ccs0: SUCCESS (1.498s)
[680.908831] Subtest basic: SUCCESS (10.074s)
[681.127764] [103/130] (342s left) prime_busy (after)
[681.191411] Starting subtest: after
[681.195772] Starting dynamic subtest: rcs0
[681.246886] Dynamic subtest rcs0: SUCCESS (0.052s)
[681.256708] Starting dynamic subtest: bcs0
[681.334118] Dynamic subtest bcs0: SUCCESS (0.086s)
[681.343602] Starting dynamic subtest: vcs0
[681.375852] Dynamic subtest vcs0: SUCCESS (0.041s)
[681.385480] Starting dynamic subtest: vcs1
[681.417209] Dynamic subtest vcs1: SUCCESS (0.041s)
[681.427134] Starting dynamic subtest: vecs0
[681.458895] Dynamic subtest vecs0: SUCCESS (0.041s)
[681.468815] Starting dynamic subtest: ccs0
[681.500950] Dynamic subtest ccs0: SUCCESS (0.041s)
[681.610415] Subtest after: SUCCESS (0.417s)
[681.711391] [104/130] (341s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[681.817501] Starting subtest: y-tiled-64bpp-rotate-0
[681.821668] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[681.954060] [105/130] (341s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[682.065452] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[682.069528] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[682.197998] [106/130] (341s left) gem_mmap_wc (read-write-distinct)
[682.257219] Subtest read-write-distinct: SKIP (0.000s)
[682.324413] [107/130] (341s left) gem_ctx_sseu (engines)
[682.390745] Subtest engines: SKIP (0.000s)
[682.484971] [108/130] (341s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[682.542219] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[682.642940] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.101s)
[682.747475] [109/130] (340s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[682.884920] Starting subtest: basic-flip-before-cursor-atomic
[683.814676] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.929s)
[684.636580] [110/130] (339s left) syncobj_wait (single-wait-all-signaled)
[684.695595] Starting subtest: single-wait-all-signaled
[684.697543] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[684.828485] [111/130] (338s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[684.931625] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[685.533442] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.604s)
[685.697335] [112/130] (338s left) perf (gen12-unprivileged-single-ctx-counters)
[685.848734] Starting subtest: gen12-unprivileged-single-ctx-counters
[685.850891] Starting dynamic subtest: rcs0
[685.988638] Dynamic subtest rcs0: SUCCESS (0.141s)
[685.990980] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.142s)
[686.038331] [113/130] (337s left) kms_psr (psr-cursor-blt)
[686.131849] Starting subtest: psr-cursor-blt
[686.134026] Starting dynamic subtest: eDP-1
[687.811824] Dynamic subtest eDP-1: SUCCESS (1.680s)
[687.814057] Subtest psr-cursor-blt: SUCCESS (1.681s)
[688.700733] [114/130] (335s left) gem_ccs (ctrl-surf-copy)
[688.758268] Starting subtest: ctrl-surf-copy
[688.762772] Subtest ctrl-surf-copy: SKIP (0.000s)
[688.884785] [115/130] (335s left) kms_flip (2x-absolute-wf_vblank)
[688.985343] Starting subtest: 2x-absolute-wf_vblank
[688.996936] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[689.145059] [116/130] (334s left) kms_pm_dc (dc3co-vpb-simulation)
[690.235051] Starting subtest: dc3co-vpb-simulation
[699.230159] Subtest dc3co-vpb-simulation: SKIP (8.991s)
[699.399611] [117/130] (324s left) i915_query (test-query-geometry-subslices)
[699.459615] Starting subtest: test-query-geometry-subslices
[699.461939] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[699.581606] [118/130] (324s left) kms_psr (fbc-psr-sprite-render)
[699.689465] Starting subtest: fbc-psr-sprite-render
[699.691643] Starting dynamic subtest: eDP-1
[699.695653] Dynamic subtest eDP-1: SKIP (0.000s)
[699.699729] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[699.788280] [119/130] (324s left) gem_userptr_blits (sync-overlap)
[699.866701] Starting subtest: sync-overlap
[699.868946] Subtest sync-overlap: SUCCESS (0.000s)
[700.024949] [120/130] (324s left) prime_vgem (fence-read-hang)
[700.104296] Subtest fence-read-hang: SKIP (0.000s)
[700.236401] [121/130] (323s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[700.297576] Starting subtest: invalid-single-wait-all-unsubmitted
[700.299380] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[700.418550] [122/130] (323s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[700.514619] Starting subtest: yf-tiled-64bpp-rotate-270
[700.518332] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[700.648166] [123/130] (323s left) core_setmaster (master-drop-set-shared-fd)
[700.743517] Starting subtest: master-drop-set-shared-fd
[700.765046] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[700.800539] [124/130] (323s left) gem_ctx_create (basic-files)
[700.858573] Starting subtest: basic-files
[702.865659] Subtest basic-files: SUCCESS (2.007s)
[704.501322] [125/130] (319s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[704.569669] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[704.635866] [126/130] (319s left) kms_color (ctm-blue-to-red)
[704.740935] Starting subtest: ctm-blue-to-red
[704.743169] Starting dynamic subtest: pipe-A-eDP-1
[705.403593] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.664s)
[705.414942] Starting dynamic subtest: pipe-B-eDP-1
[706.731837] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.325s)
[706.741555] Starting dynamic subtest: pipe-C-eDP-1
[708.038832] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.306s)
[708.049086] Starting dynamic subtest: pipe-D-eDP-1
[709.340253] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.303s)
[709.342617] Subtest ctm-blue-to-red: SUCCESS (4.602s)
[710.208584] [127/130] (314s left) gem_eio (in-flight-external)
[710.442592] Starting subtest: in-flight-external
[711.479333] Subtest in-flight-external: SUCCESS (1.036s)
[711.653300] [128/130] (312s left) kms_frontbuffer_tracking (fbc-2p-rte)
[711.761222] Starting subtest: fbc-2p-rte
[711.765425] Subtest fbc-2p-rte: SKIP (0.000s)
[711.900112] [129/130] (312s left) syncobj_wait (wait-all-for-submit-complex)
[711.964985] Starting subtest: wait-all-for-submit-complex
[712.178797] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[712.277966] [130/130] (312s left) kms_properties (colorop-properties-atomic)
[712.368395] Starting subtest: colorop-properties-atomic
[712.370789] Starting dynamic subtest: pipe-A-eDP-1
[712.704240] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.336s)
[712.715680] Starting dynamic subtest: pipe-B-eDP-1
[713.749017] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.044s)
[713.764320] Starting dynamic subtest: pipe-C-eDP-1
[714.770183] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.020s)
[714.781700] Starting dynamic subtest: pipe-D-eDP-1
[715.802373] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.031s)
[715.804683] Subtest colorop-properties-atomic: SUCCESS (3.437s)
[716.620937] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: gem_fence_thrash
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_exec_parallel
results: parsing output: 4/ for test: gem_wait
results: parsing output: 5/ for test: gem_tiled_wb
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: i915_drm_fdinfo
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: gem_mmap_offset
results: parsing output: 11/ for test: kms_dp_aux_dev
results: parsing output: 12/ for test: gem_exec_params
results: parsing output: 13/ for test: vgem_basic
results: parsing output: 14/ for test: kms_getfb
results: parsing output: 15/ for test: kms_cursor_crc
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_dp_linktrain_fallback
results: parsing output: 20/ for test: core_debugfs
results: parsing output: 21/ for test: gem_ctx_param
results: parsing output: 22/ for test: vgem_basic
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_getfb
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: gem_exec_flush
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_plane_multiple
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: gem_eio
results: parsing output: 31/ for test: gen7_exec_parse
results: parsing output: 32/ for test: gem_mmap_gtt
results: parsing output: 33/ for test: kms_chamelium_frames
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_exec_schedule
results: parsing output: 36/ for test: kms_psr2_sf
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_color
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: gem_eio
results: parsing output: 41/ for test: gem_ppgtt
results: parsing output: 42/ for test: kms_cursor_crc
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: gem_exec_whisper
results: parsing output: 50/ for test: gem_wait
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_joiner
results: parsing output: 53/ for test: kms_vblank
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: gem_lmem_swapping
results: parsing output: 57/ for test: kms_universal_plane
results: parsing output: 58/ for test: kms_getfb
results: parsing output: 59/ for test: kms_lease
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_create
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gem_sync
results: parsing output: 68/ for test: gem_render_copy
results: parsing output: 69/ for test: gem_render_copy
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: gem_exec_reloc
results: parsing output: 72/ for test: kms_async_flips
results: parsing output: 73/ for test: gem_softpin
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_render_copy
results: parsing output: 80/ for test: gem_exec_fence
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: gem_mmap_wc
results: parsing output: 83/ for test: kms_plane_scaling
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: gem_exec_suspend
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_huc_copy
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: i915_pm_rc6_residency
results: parsing output: 91/ for test: kms_plane
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: i915_hangman
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_pwrite
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_prime
results: parsing output: 98/ for test: kms_flip_scaled_crc
results: parsing output: 99/ for test: gem_exec_balancer
results: parsing output: 100/ for test: gem_exec_balancer
results: parsing output: 101/ for test: gem_exec_parallel
results: parsing output: 102/ for test: prime_busy
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_mmap_wc
results: parsing output: 106/ for test: gem_ctx_sseu
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: perf
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_ccs
results: parsing output: 114/ for test: kms_flip
results: parsing output: 115/ for test: kms_pm_dc
results: parsing output: 116/ for test: i915_query
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: gem_userptr_blits
results: parsing output: 119/ for test: prime_vgem
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: core_setmaster
results: parsing output: 123/ for test: gem_ctx_create
results: parsing output: 124/ for test: kms_pipe_b_c_ivb
results: parsing output: 125/ for test: kms_color
results: parsing output: 126/ for test: gem_eio
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_properties
Done.