[58.430646] Initializing watchdogs
[58.430693]   /dev/watchdog0
[58.445117] [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.449641] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.451830] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.456040] [001/131] (960s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[58.581237] Starting subtest: x-tiled-8bpp-rotate-180
[59.855941] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.271s)
[59.974968] [002/131] (959s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[60.068207] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[60.071692] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[60.143649] [003/131] (958s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[60.235341] Starting subtest: pr-cursor-plane-update-sf
[60.238833] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[60.300948] [004/131] (958s left) kms_frontbuffer_tracking (psr-slowdraw)
[60.395083] Starting subtest: psr-slowdraw
[85.678545] Subtest psr-slowdraw: SUCCESS (25.268s)
[85.815316] [005/131] (933s left) kms_flip (plain-flip-fb-recreate)
[85.914173] Starting subtest: plain-flip-fb-recreate
[85.916392] Starting dynamic subtest: A-eDP1
[94.765605] Dynamic subtest A-eDP1: SUCCESS (8.849s)
[94.773072] Starting dynamic subtest: B-eDP1
[103.597672] Dynamic subtest B-eDP1: SUCCESS (8.829s)
[103.606099] Starting dynamic subtest: C-eDP1
[112.419057] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[112.426073] Starting dynamic subtest: D-eDP1
[121.259245] Dynamic subtest D-eDP1: SUCCESS (8.839s)
[121.263155] Subtest plain-flip-fb-recreate: SUCCESS (35.346s)
[122.468540] [006/131] (896s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[122.526075] Starting subtest: invalid-signal-one-illegal-handle
[122.528436] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[122.619955] [007/131] (896s left) api_intel_allocator (open-vm)
[122.673916] Starting subtest: open-vm
[122.676328] Subtest open-vm: SUCCESS (0.003s)
[122.825690] [008/131] (896s left) gem_exec_params (cliprects_ptr-dirt)
[122.884663] Starting subtest: cliprects_ptr-dirt
[122.886795] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[122.994090] [009/131] (896s left) gem_ctx_persistence (engines-hang)
[123.045639] Starting subtest: engines-hang
[123.047492] Starting dynamic subtest: rcs0
[123.137686] Dynamic subtest rcs0: SUCCESS (0.094s)
[123.150640] Starting dynamic subtest: bcs0
[123.245323] Dynamic subtest bcs0: SUCCESS (0.106s)
[123.254674] Starting dynamic subtest: vcs0
[123.336683] Dynamic subtest vcs0: SUCCESS (0.091s)
[123.346565] Starting dynamic subtest: vcs1
[123.438482] Dynamic subtest vcs1: SUCCESS (0.101s)
[123.448533] Starting dynamic subtest: vecs0
[123.509262] Dynamic subtest vecs0: SUCCESS (0.070s)
[123.517384] Starting dynamic subtest: ccs0
[123.631476] Dynamic subtest ccs0: SUCCESS (0.121s)
[123.633875] Subtest engines-hang: SUCCESS (0.589s)
[123.710339] [010/131] (895s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[123.815206] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[128.136378] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.324s)
[129.259792] [011/131] (890s left) kms_psr (fbc-psr2-primary-page-flip)
[129.375186] Starting subtest: fbc-psr2-primary-page-flip
[129.377598] Starting dynamic subtest: eDP-1
[129.382137] Dynamic subtest eDP-1: SKIP (0.000s)
[129.386631] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[129.444236] [012/131] (889s left) i915_drm_fdinfo (busy)
[129.488556] Starting subtest: busy
[129.490445] Starting dynamic subtest: rcs0
[129.513712] Dynamic subtest rcs0: SKIP (0.025s)
[129.522805] Starting dynamic subtest: bcs0
[129.526901] Dynamic subtest bcs0: SKIP (0.001s)
[129.534174] Starting dynamic subtest: vcs0
[129.538594] Dynamic subtest vcs0: SKIP (0.000s)
[129.546099] Starting dynamic subtest: vcs1
[129.550189] Dynamic subtest vcs1: SKIP (0.000s)
[129.556826] Starting dynamic subtest: vecs0
[129.560984] Dynamic subtest vecs0: SKIP (0.000s)
[129.568178] Starting dynamic subtest: ccs0
[129.572157] Dynamic subtest ccs0: SKIP (0.000s)
[129.576309] Subtest busy: SKIP (0.029s)
[129.610142] [013/131] (889s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[129.717972] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[129.722561] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[129.784914] [014/131] (889s left) kms_cursor_crc (cursor-offscreen-64x64)
[129.948676] Starting subtest: cursor-offscreen-64x64
[129.950843] Starting dynamic subtest: pipe-A-eDP-1
[131.890642] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.940s)
[131.920116] Starting dynamic subtest: pipe-D-eDP-1
[134.469695] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.553s)
[134.472047] Subtest cursor-offscreen-64x64: SUCCESS (4.521s)
[135.323013] [015/131] (884s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[135.410033] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[135.412367] Starting dynamic subtest: pipe-A-eDP-1
[135.432883] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[135.442467] Starting dynamic subtest: pipe-B-eDP-1
[135.461322] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[135.468356] Starting dynamic subtest: pipe-C-eDP-1
[135.487872] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[135.495606] Starting dynamic subtest: pipe-D-eDP-1
[135.514855] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[135.516773] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[137.669208] [016/131] (881s left) syncobj_timeline (single-wait-available-signaled)
[137.729027] Starting subtest: single-wait-available-signaled
[137.731376] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[137.822171] [017/131] (881s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[137.921320] Starting subtest: 2x-flip-vs-modeset-vs-hang
[137.930154] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.010s)
[138.009281] [018/131] (881s left) syncobj_timeline (multi-wait-available-signaled)
[138.061744] Starting subtest: multi-wait-available-signaled
[138.064039] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[138.178466] [019/131] (881s left) kms_content_protection (uevent-hdcp14)
[138.313384] Starting subtest: uevent-hdcp14
[138.318065] Subtest uevent-hdcp14: SKIP (0.000s)
[138.425825] [020/131] (881s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[138.535482] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[138.539963] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[138.630506] [021/131] (881s left) perf_pmu (semaphore-wait-idle)
[138.671999] Starting subtest: semaphore-wait-idle
[138.673904] Starting dynamic subtest: rcs0
[139.185952] Dynamic subtest rcs0: SUCCESS (0.513s)
[139.196996] Starting dynamic subtest: bcs0
[139.698242] Dynamic subtest bcs0: SUCCESS (0.511s)
[139.709267] Starting dynamic subtest: vcs0
[140.210631] Dynamic subtest vcs0: SUCCESS (0.511s)
[140.220738] Starting dynamic subtest: vcs1
[140.726984] Dynamic subtest vcs1: SUCCESS (0.515s)
[140.737312] Starting dynamic subtest: vecs0
[141.239487] Dynamic subtest vecs0: SUCCESS (0.511s)
[141.250731] Starting dynamic subtest: ccs0
[141.754848] Dynamic subtest ccs0: SUCCESS (0.513s)
[141.757956] Subtest semaphore-wait-idle: SUCCESS (3.083s)
[142.941305] [022/131] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[143.031023] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[150.494800] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.465s)
[150.635666] [023/131] (869s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[150.724067] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[150.728097] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[150.795183] [024/131] (869s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[150.888160] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[150.892196] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[150.993025] [025/131] (868s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[151.072116] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[151.135793] [026/131] (868s left) kms_psr (pr-sprite-blt)
[151.234845] Starting subtest: pr-sprite-blt
[151.239434] Subtest pr-sprite-blt: SKIP (0.000s)
[151.319262] [027/131] (868s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[151.444342] Starting subtest: flip-vs-cursor-busy-crc-atomic
[152.255978] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.810s)
[153.145098] [028/131] (866s left) kms_psr (psr2-dpms)
[153.237808] Starting subtest: psr2-dpms
[153.240096] Starting dynamic subtest: eDP-1
[156.056746] Dynamic subtest eDP-1: SUCCESS (2.817s)
[156.058915] Subtest psr2-dpms: SUCCESS (2.818s)
[156.916903] [029/131] (863s left) syncobj_wait (single-wait-all-signaled)
[156.974372] Starting subtest: single-wait-all-signaled
[156.976377] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[157.065461] [030/131] (863s left) gem_render_copy_redux (interruptible)
[157.122519] Starting subtest: interruptible
[157.404163] Subtest interruptible: SUCCESS (0.281s)
[157.518868] [031/131] (862s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[157.635811] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[157.640678] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[157.752442] [032/131] (862s left) i915_pm_freq_api (freq-basic-api)
[157.826492] Starting subtest: freq-basic-api
[157.829247] Starting dynamic subtest: gt0
[157.831846] Dynamic subtest gt0: SUCCESS (0.004s)
[157.847823] Starting dynamic subtest: gt1
[157.850489] Dynamic subtest gt1: SUCCESS (0.003s)
[157.852805] Subtest freq-basic-api: SUCCESS (0.008s)
[157.974083] [033/131] (862s left) gem_exercise_blt (fast-copy)
[158.055188] Starting subtest: fast-copy
[158.057059] Starting dynamic subtest: linear-smem-smem
[158.076288] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[158.084795] Starting dynamic subtest: xmajor-smem-smem
[158.095193] Dynamic subtest xmajor-smem-smem: SUCCESS (0.019s)
[158.102213] Starting dynamic subtest: tile4-smem-smem
[158.112699] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[158.119715] Starting dynamic subtest: tile64-smem-smem
[158.131377] Dynamic subtest tile64-smem-smem: SUCCESS (0.018s)
[158.133401] Subtest fast-copy: SUCCESS (0.079s)
[158.244242] [034/131] (862s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[158.340072] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[158.343765] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[158.449069] [035/131] (861s left) gem_partial_pwrite_pread (reads-display)
[158.522729] Subtest reads-display: SKIP (0.000s)
[158.600658] [036/131] (861s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[158.697749] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[158.701244] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[158.793765] [037/131] (861s left) gem_ctx_param (basic-default)
[158.852766] Starting subtest: basic-default
[158.854868] Subtest basic-default: SUCCESS (0.000s)
[158.924173] [038/131] (861s left) gem_exec_balancer (parallel)
[159.219597] Starting subtest: parallel
[160.148901] Subtest parallel: SUCCESS (0.929s)
[160.892061] [039/131] (859s left) gem_create (create-ext-set-pat)
[161.061375] Starting subtest: create-ext-set-pat
[161.064080] Subtest create-ext-set-pat: SUCCESS (0.001s)
[161.174513] [040/131] (859s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[161.267042] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[161.269405] Starting dynamic subtest: pipe-A-default-mode
[162.403282] Dynamic subtest pipe-A-default-mode: SKIP (1.134s)
[162.407625] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.135s)
[162.553894] [041/131] (858s left) gem_exec_reloc (basic-gtt-cpu-active)
[162.625296] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[162.703960] [042/131] (857s left) perf (rc6-disable)
[162.819201] Starting subtest: rc6-disable
[162.996269] Subtest rc6-disable: SUCCESS (0.177s)
[163.036875] [043/131] (857s left) gem_mmap_offset (clear-via-pagefault)
[163.091175] Starting subtest: clear-via-pagefault
[163.093452] Starting dynamic subtest: smem0
[343.173474] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[375.592616] Closing watchdogs
[375.594908] Initializing watchdogs
[375.594938]   /dev/watchdog0
[375.609627] [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]
[375.614211] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[375.615451] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[375.619412] [044/131] (645s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[375.740820] Starting subtest: psr-dirtyfb-ioctl
[375.742950] Starting dynamic subtest: A-eDP-1
[379.660404] Dynamic subtest A-eDP-1: SUCCESS (3.919s)
[379.662781] Subtest psr-dirtyfb-ioctl: SUCCESS (3.920s)
[380.540344] [045/131] (640s left) kms_lease (lease-unleased-crtc)
[380.642251] Starting subtest: lease-unleased-crtc
[380.644622] Starting dynamic subtest: pipe-A-eDP-1
[380.949279] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.307s)
[380.957820] Starting dynamic subtest: pipe-B-eDP-1
[381.702452] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.752s)
[381.714445] Starting dynamic subtest: pipe-C-eDP-1
[381.716963] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[381.724727] Starting dynamic subtest: pipe-D-eDP-1
[381.727072] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[381.729376] Subtest lease-unleased-crtc: SUCCESS (1.076s)
[382.073362] [046/131] (638s left) gem_render_copy (yf-tiled)
[382.149974] Starting subtest: yf-tiled
[382.154744] Subtest yf-tiled: SKIP (0.000s)
[382.345692] [047/131] (638s left) i915_suspend (sysfs-reader)
[382.398917] Starting subtest: sysfs-reader
[400.253030] Subtest sysfs-reader: SUCCESS (4.019s)
[400.353187] [048/131] (620s left) gem_render_linear_blits (basic)
[400.420956] Starting subtest: basic
[400.443155] Subtest basic: SUCCESS (0.022s)
[400.543455] [049/131] (620s left) gem_ctx_bad_destroy (invalid-pad)
[400.589973] Starting subtest: invalid-pad
[400.592760] Subtest invalid-pad: SUCCESS (0.000s)
[400.660290] [050/131] (620s left) gem_render_copy (y-tiled-ccs-to-linear)
[400.713095] Starting subtest: y-tiled-ccs-to-linear
[400.718316] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[400.912871] [051/131] (620s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[401.017610] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[408.044495] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.963s)
[408.247306] [052/131] (612s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[408.362403] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[414.957447] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.573s)
[415.078971] [053/131] (606s left) kms_cursor_edge_walk (128x128-right-edge)
[415.182369] Starting subtest: 128x128-right-edge
[415.185550] Starting dynamic subtest: pipe-A-eDP-1
[418.659479] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.466s)
[418.668711] Starting dynamic subtest: pipe-D-eDP-1
[421.792139] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.125s)
[421.794608] Subtest 128x128-right-edge: SUCCESS (6.597s)
[422.595845] [054/131] (598s left) kms_plane (plane-position-hole-dpms)
[422.692705] Starting subtest: plane-position-hole-dpms
[423.253958] Starting dynamic subtest: pipe-A-plane-1
[425.704165] Dynamic subtest pipe-A-plane-1: SUCCESS (2.447s)
[425.713693] Starting dynamic subtest: pipe-A-plane-2
[428.068153] Dynamic subtest pipe-A-plane-2: SUCCESS (2.361s)
[428.082055] Starting dynamic subtest: pipe-A-plane-3
[430.426003] Dynamic subtest pipe-A-plane-3: SUCCESS (2.355s)
[430.435255] Starting dynamic subtest: pipe-A-plane-4
[432.783653] Dynamic subtest pipe-A-plane-4: SUCCESS (2.355s)
[432.793512] Starting dynamic subtest: pipe-A-plane-5
[432.812713] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[433.967997] Starting dynamic subtest: pipe-B-plane-1
[436.425629] Dynamic subtest pipe-B-plane-1: SUCCESS (2.462s)
[436.436797] Starting dynamic subtest: pipe-B-plane-2
[438.869232] Dynamic subtest pipe-B-plane-2: SUCCESS (2.439s)
[438.882433] Starting dynamic subtest: pipe-B-plane-3
[441.213399] Dynamic subtest pipe-B-plane-3: SUCCESS (2.344s)
[441.223865] Starting dynamic subtest: pipe-B-plane-4
[443.579096] Dynamic subtest pipe-B-plane-4: SUCCESS (2.364s)
[443.588895] Starting dynamic subtest: pipe-B-plane-5
[443.609589] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[443.612332] Subtest plane-position-hole-dpms: SUCCESS (20.901s)
[444.381190] [055/131] (576s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[444.485053] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[451.838400] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.354s)
[451.961224] [056/131] (569s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[452.061540] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[452.066835] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[452.166821] [057/131] (569s left) gem_softpin (full)
[452.226194] Starting subtest: full
[452.234752] Subtest full: SUCCESS (0.008s)
[452.361188] [058/131] (569s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[452.745132] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[453.636524] [059/131] (567s left) kms_dsc (dsc-fractional-bpp)
[453.733128] Starting subtest: dsc-fractional-bpp
[453.748940] Subtest dsc-fractional-bpp: SKIP (0.000s)
[453.809873] [060/131] (567s left) gem_exec_parallel (userptr)
[453.874846] Starting subtest: userptr
[453.877410] Starting dynamic subtest: rcs0
[456.230592] Dynamic subtest rcs0: SUCCESS (2.354s)
[456.241578] Starting dynamic subtest: bcs0
[457.973694] Dynamic subtest bcs0: SUCCESS (1.741s)
[457.982526] Starting dynamic subtest: vcs0
[459.751637] Dynamic subtest vcs0: SUCCESS (1.774s)
[459.762500] Starting dynamic subtest: vcs1
[461.720314] Dynamic subtest vcs1: SUCCESS (1.965s)
[461.731438] Starting dynamic subtest: vecs0
[463.703765] Dynamic subtest vecs0: SUCCESS (1.980s)
[463.714257] Starting dynamic subtest: ccs0
[465.469468] Dynamic subtest ccs0: SUCCESS (1.762s)
[465.472467] Subtest userptr: SUCCESS (11.589s)
[465.685928] [061/131] (555s left) gem_ctx_exec (basic)
[465.746479] Starting subtest: basic
[465.754257] Subtest basic: SUCCESS (0.010s)
[465.868168] [062/131] (555s left) gem_exec_schedule (submit-late-slice)
[465.947221] Starting subtest: submit-late-slice
[465.950309] Starting dynamic subtest: rcs0
[466.012305] Dynamic subtest rcs0: SUCCESS (0.067s)
[466.022295] Starting dynamic subtest: bcs0
[466.070172] Dynamic subtest bcs0: SUCCESS (0.057s)
[466.081033] Starting dynamic subtest: vcs0
[466.128615] Dynamic subtest vcs0: SUCCESS (0.058s)
[466.138341] Starting dynamic subtest: vcs1
[466.179322] Dynamic subtest vcs1: SUCCESS (0.049s)
[466.189468] Starting dynamic subtest: vecs0
[466.221878] Dynamic subtest vecs0: SUCCESS (0.043s)
[466.233451] Starting dynamic subtest: ccs0
[466.276151] Dynamic subtest ccs0: SUCCESS (0.053s)
[466.278930] Subtest submit-late-slice: SUCCESS (0.331s)
[467.831880] [063/131] (553s left) gem_exec_schedule (preempt-contexts)
[467.914310] Starting subtest: preempt-contexts
[467.917171] Starting dynamic subtest: rcs0
[467.997446] Dynamic subtest rcs0: SUCCESS (0.092s)
[468.006674] Starting dynamic subtest: bcs0
[468.103792] Dynamic subtest bcs0: SUCCESS (0.105s)
[468.114085] Starting dynamic subtest: vcs0
[468.165105] Dynamic subtest vcs0: SUCCESS (0.060s)
[468.174340] Starting dynamic subtest: vcs1
[468.244897] Dynamic subtest vcs1: SUCCESS (0.078s)
[468.254353] Starting dynamic subtest: vecs0
[468.310849] Dynamic subtest vecs0: SUCCESS (0.066s)
[468.323902] Starting dynamic subtest: ccs0
[468.397485] Dynamic subtest ccs0: SUCCESS (0.086s)
[468.400441] Subtest preempt-contexts: SUCCESS (0.493s)
[469.824647] [064/131] (551s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[469.872136] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[469.927811] [065/131] (551s left) gem_exec_reloc (basic-write-read-noreloc)
[469.986432] Subtest basic-write-read-noreloc: SKIP (0.000s)
[470.061918] [066/131] (551s left) kms_addfb_basic (master-rmfb)
[470.118183] Starting subtest: master-rmfb
[470.120724] Subtest master-rmfb: SUCCESS (0.001s)
[470.256970] [067/131] (551s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[470.353693] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[471.190195] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.837s)
[471.352274] [068/131] (550s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[471.446180] Starting subtest: nonblocking-crc-frame-sequence
[471.448435] Starting dynamic subtest: pipe-A-eDP-1
[472.184147] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.739s)
[472.194861] Starting dynamic subtest: pipe-B-eDP-1
[473.663360] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.476s)
[473.672655] Starting dynamic subtest: pipe-C-eDP-1
[475.041295] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[475.051223] Starting dynamic subtest: pipe-D-eDP-1
[476.365125] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[476.367831] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.918s)
[477.159831] [069/131] (544s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[477.263311] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[477.268323] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[477.355753] [070/131] (544s left) kms_color (ctm-signed)
[477.443168] Starting subtest: ctm-signed
[477.445403] Starting dynamic subtest: pipe-A-eDP-1
[479.557077] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.114s)
[479.565964] Starting dynamic subtest: pipe-B-eDP-1
[482.423193] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.863s)
[482.432422] Starting dynamic subtest: pipe-C-eDP-1
[485.068408] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.644s)
[485.078033] Starting dynamic subtest: pipe-D-eDP-1
[487.741740] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.669s)
[487.744445] Subtest ctm-signed: SUCCESS (10.294s)
[488.581832] [071/131] (533s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[488.696843] Starting subtest: 4-tiled-32bpp-rotate-90
[488.718414] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.020s)
[488.831720] [072/131] (533s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[488.934458] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[488.939459] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[489.047073] [073/131] (533s left) syncobj_wait (invalid-wait-bad-flags)
[489.104000] Starting subtest: invalid-wait-bad-flags
[489.106375] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[489.222867] [074/131] (532s left) sw_sync (sync_multi_timeline_wait)
[489.262577] Starting subtest: sync_multi_timeline_wait
[489.264945] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[489.310452] [075/131] (532s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[489.412089] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[489.416564] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[489.501117] [076/131] (532s left) gem_exec_fence (basic-busy)
[489.675486] Starting subtest: basic-busy
[489.678411] Starting dynamic subtest: rcs0
[489.753468] Dynamic subtest rcs0: SUCCESS (0.081s)
[489.766586] Starting dynamic subtest: bcs0
[489.809136] Dynamic subtest bcs0: SUCCESS (0.053s)
[489.820310] Starting dynamic subtest: vcs0
[489.872976] Dynamic subtest vcs0: SUCCESS (0.061s)
[489.884518] Starting dynamic subtest: vcs1
[489.929602] Dynamic subtest vcs1: SUCCESS (0.055s)
[489.940491] Starting dynamic subtest: vecs0
[489.988784] Dynamic subtest vecs0: SUCCESS (0.057s)
[490.000478] Starting dynamic subtest: ccs0
[490.046833] Dynamic subtest ccs0: SUCCESS (0.056s)
[490.050021] Subtest basic-busy: SUCCESS (0.377s)
[491.820569] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[491.824328] [077/131] (530s left) kms_async_flips (invalid-async-flip)
[491.913220] Starting subtest: invalid-async-flip
[492.304990] Starting dynamic subtest: pipe-A-eDP-1
[493.105876] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.802s)
[493.464303] Starting dynamic subtest: pipe-B-eDP-1
[494.316163] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.859s)
[494.673856] Starting dynamic subtest: pipe-C-eDP-1
[495.445738] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.780s)
[495.800947] Starting dynamic subtest: pipe-D-eDP-1
[496.566962] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[496.569299] Subtest invalid-async-flip: SUCCESS (4.659s)
[497.722125] [078/131] (524s left) kms_psr2_su (frontbuffer-xrgb8888)
[497.781654] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[497.841414] [079/131] (524s left) syncobj_timeline (etime-single-wait-submitted)
[497.889949] Starting subtest: etime-single-wait-submitted
[497.996472] Subtest etime-single-wait-submitted: SUCCESS (0.107s)
[498.080889] [080/131] (524s left) gem_exec_reloc (basic-cpu-wc-active)
[498.139237] Subtest basic-cpu-wc-active: SKIP (0.000s)
[498.220672] [081/131] (524s left) kms_flip (2x-flip-vs-panning-interruptible)
[498.317006] Starting subtest: 2x-flip-vs-panning-interruptible
[498.329050] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s)
[498.406244] [082/131] (524s left) i915_pm_freq_mult (media-freq)
[498.452670] Starting subtest: media-freq
[498.455262] Starting dynamic subtest: gt0
[498.460211] Dynamic subtest gt0: SKIP (0.000s)
[498.469434] Starting dynamic subtest: gt1
[498.474206] Dynamic subtest gt1: SKIP (0.000s)
[498.479273] Subtest media-freq: SKIP (0.001s)
[498.551346] [083/131] (523s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[498.673267] Starting subtest: cursorA-vs-flipB-varying-size
[498.677828] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[498.818603] [084/131] (523s left) kms_pm_rpm (modeset-lpsp)
[500.957792] Starting subtest: modeset-lpsp
[502.438159] Subtest modeset-lpsp: SUCCESS (1.485s)
[502.550185] [085/131] (520s left) gem_exec_whisper (basic-fds-all)
[502.616451] Starting subtest: basic-fds-all
[512.698422] Subtest basic-fds-all: SUCCESS (10.084s)
[512.898306] [086/131] (509s left) fbdev (nullptr)
[512.942719] Starting subtest: nullptr
[512.945297] Subtest nullptr: SUCCESS (0.000s)
[513.008495] [087/131] (509s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[513.112239] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[520.374782] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.267s)
[520.493401] [088/131] (502s left) gem_userptr_blits (nohangcheck)
[520.576288] Starting subtest: nohangcheck
[520.825460] Subtest nohangcheck: SUCCESS (0.245s)
[520.960917] [089/131] (501s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[521.056130] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[521.058473] Starting dynamic subtest: pipe-A-eDP-1
[521.081371] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[521.091032] Starting dynamic subtest: pipe-B-eDP-1
[521.116034] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[521.125272] Starting dynamic subtest: pipe-C-eDP-1
[521.150214] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[521.160172] Starting dynamic subtest: pipe-D-eDP-1
[521.186119] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[521.191004] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[523.308111] [090/131] (499s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[523.702682] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[524.510367] [091/131] (498s left) perf (blocking)
[524.595353] Starting subtest: blocking
[524.598478] Starting dynamic subtest: 0-rcs0
[534.604403] Dynamic subtest 0-rcs0: SUCCESS (10.009s)
[534.617848] Starting dynamic subtest: 1-vcs1
[544.625022] Dynamic subtest 1-vcs1: SUCCESS (10.019s)
[544.627490] Subtest blocking: SUCCESS (20.031s)
[544.677085] [092/131] (478s left) kms_psr (psr-primary-mmap-gtt)
[544.766365] Starting subtest: psr-primary-mmap-gtt
[544.768680] Starting dynamic subtest: eDP-1
[546.236197] Dynamic subtest eDP-1: SKIP (1.466s)
[546.242367] Subtest psr-primary-mmap-gtt: SKIP (1.468s)
[546.402401] [093/131] (476s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[546.494186] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[546.496669] Starting dynamic subtest: pipe-A-eDP-1
[547.371181] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.869s)
[547.381244] Starting dynamic subtest: pipe-B-eDP-1
[549.006909] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.632s)
[549.016772] Starting dynamic subtest: pipe-C-eDP-1
[550.498316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.489s)
[550.509018] Starting dynamic subtest: pipe-D-eDP-1
[552.041884] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.544s)
[552.044305] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.539s)
[553.208957] [094/131] (469s left) gem_exec_params (no-vebox)
[553.262439] Starting subtest: no-vebox
[553.267923] Subtest no-vebox: SUCCESS (0.003s)
[554.796538] [095/131] (468s left) kms_pm_dc (dc5-psr)
[555.900251] Starting subtest: dc5-psr
[558.792215] Subtest dc5-psr: SUCCESS (2.891s)
[559.626968] [096/131] (463s left) gem_pread (self)
[559.690228] Subtest self: SKIP (0.000s)
[559.746984] [097/131] (463s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[559.842435] Starting subtest: yf-tiled-32bpp-rotate-90
[559.847753] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[559.937797] [098/131] (463s left) syncobj_eventfd (binary-wait)
[559.994216] Starting subtest: binary-wait
[560.003098] Subtest binary-wait: SUCCESS (0.010s)
[560.093925] [099/131] (463s left) kms_cursor_crc (cursor-size-hints)
[560.227405] Starting subtest: cursor-size-hints
[560.230329] Starting dynamic subtest: pipe-A-eDP-1
[560.849439] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[560.860240] Starting dynamic subtest: pipe-D-eDP-1
[562.086248] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.234s)
[562.089208] Subtest cursor-size-hints: SUCCESS (1.858s)
[562.888092] [100/131] (460s left) kms_atomic_interruptible (legacy-cursor)
[562.987031] Starting subtest: legacy-cursor
[562.989455] Starting dynamic subtest: pipe-A-eDP-1
[570.413446] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.423s)
[570.415931] Subtest legacy-cursor: SUCCESS (7.424s)
[571.269812] [101/131] (452s left) gem_render_copy (x-tiled)
[571.328566] Starting subtest: x-tiled
[571.331410] Starting dynamic subtest: smem
[571.371752] Dynamic subtest smem: SUCCESS (0.042s)
[571.374757] Subtest x-tiled: SUCCESS (0.043s)
[571.595324] [102/131] (451s left) gen9_exec_parse (basic-rejected)
[571.668145] Subtest basic-rejected: SKIP (0.000s)
[571.735840] [103/131] (451s left) core_sysfs (read-all-entries)
[571.789125] Starting subtest: read-all-entries
[571.794604] Subtest read-all-entries: SUCCESS (0.008s)
[571.906792] [104/131] (451s left) perf (gen12-unprivileged-single-ctx-counters)
[572.001234] Starting subtest: gen12-unprivileged-single-ctx-counters
[572.003902] Starting dynamic subtest: rcs0
[572.087725] Dynamic subtest rcs0: SUCCESS (0.086s)
[572.090434] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.087s)
[572.139831] [105/131] (451s left) kms_big_fb (linear-32bpp-rotate-90)
[572.259746] Starting subtest: linear-32bpp-rotate-90
[572.283220] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[572.406042] [106/131] (451s left) gem_ctx_persistence (legacy-engines-hostile)
[572.469419] Starting subtest: legacy-engines-hostile
[572.472370] Starting dynamic subtest: render
[572.584637] Dynamic subtest render: SUCCESS (0.115s)
[572.599130] Starting dynamic subtest: bsd1
[572.677364] Dynamic subtest bsd1: SUCCESS (0.090s)
[572.691887] Starting dynamic subtest: bsd2
[572.774352] Dynamic subtest bsd2: SUCCESS (0.094s)
[572.785665] Starting dynamic subtest: blt
[572.863811] Dynamic subtest blt: SUCCESS (0.086s)
[572.876321] Starting dynamic subtest: vebox
[572.958580] Dynamic subtest vebox: SUCCESS (0.092s)
[572.961358] Subtest legacy-engines-hostile: SUCCESS (0.491s)
[573.045126] [107/131] (450s left) perf_pmu (enable-race)
[573.090416] Starting subtest: enable-race
[573.093152] Starting dynamic subtest: rcs0
[585.852133] Dynamic subtest rcs0: SUCCESS (12.758s)
[585.862552] Starting dynamic subtest: bcs0
[598.600403] Dynamic subtest bcs0: SUCCESS (12.742s)
[598.610323] Starting dynamic subtest: vcs0
[613.720335] Dynamic subtest vcs0: SUCCESS (15.112s)
[613.733258] Starting dynamic subtest: vcs1
[629.720503] Dynamic subtest vcs1: SUCCESS (15.997s)
[629.733168] Starting dynamic subtest: vecs0
[645.720209] Dynamic subtest vecs0: SUCCESS (16.002s)
[645.732007] Starting dynamic subtest: ccs0
[658.462102] Dynamic subtest ccs0: SUCCESS (12.743s)
[658.464471] Subtest enable-race: SUCCESS (85.368s)
[658.600242] [108/131] (365s left) gem_softpin (32b-excludes-last-page)
[658.662100] Starting subtest: 32b-excludes-last-page
[658.668505] Subtest 32b-excludes-last-page: SUCCESS (0.006s)
[658.799762] [109/131] (364s left) api_intel_bb (purge-bb)
[658.859166] Starting subtest: purge-bb
[658.862548] Subtest purge-bb: SUCCESS (0.008s)
[659.000250] [110/131] (364s left) kms_sequence (queue-idle)
[659.092081] Starting subtest: queue-idle
[659.094347] Starting dynamic subtest: pipe-A-eDP-1
[660.905965] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.815s)
[660.917285] Starting dynamic subtest: pipe-B-eDP-1
[663.427973] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.521s)
[663.439425] Starting dynamic subtest: pipe-C-eDP-1
[665.852456] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.423s)
[665.863727] Starting dynamic subtest: pipe-D-eDP-1
[668.285988] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.433s)
[668.288814] Subtest queue-idle: SUCCESS (9.197s)
[669.102279] [111/131] (354s left) gem_exec_flush (basic-wb-rw-before-default)
[669.170005] Starting subtest: basic-wb-rw-before-default
[674.628200] Subtest basic-wb-rw-before-default: SUCCESS (5.455s)
[674.823205] [112/131] (348s left) syncobj_timeline (wait-for-submit-delayed-submit)
[674.882449] Starting subtest: wait-for-submit-delayed-submit
[674.983317] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[675.061292] [113/131] (348s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[675.165928] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[675.171149] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[675.265771] [114/131] (348s left) kms_flip (flip-vs-panning-interruptible)
[675.357663] Starting subtest: flip-vs-panning-interruptible
[675.360236] Starting dynamic subtest: A-eDP1
[684.097278] Dynamic subtest A-eDP1: SUCCESS (8.740s)
[684.109590] Starting dynamic subtest: B-eDP1
[692.838059] Dynamic subtest B-eDP1: SUCCESS (8.739s)
[692.850967] Starting dynamic subtest: C-eDP1
[701.551237] Dynamic subtest C-eDP1: SUCCESS (8.709s)
[701.561469] Starting dynamic subtest: D-eDP1
[710.278176] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[710.283068] Subtest flip-vs-panning-interruptible: SUCCESS (34.925s)
[711.449898] [115/131] (312s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[711.550087] Starting subtest: y-tiled-16bpp-rotate-90
[711.555266] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[711.648601] [116/131] (312s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[711.746325] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[711.751207] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[711.829150] [117/131] (312s left) kms_plane_multiple (2x-tiling-4)
[711.913393] Starting subtest: 2x-tiling-4
[711.918212] Subtest 2x-tiling-4: SKIP (0.000s)
[711.988773] [118/131] (312s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[712.080416] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[712.082798] Starting dynamic subtest: pipe-A-eDP-1
[712.146917] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[712.156310] Starting dynamic subtest: pipe-B-eDP-1
[712.211969] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[712.221764] Starting dynamic subtest: pipe-C-eDP-1
[712.280153] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[712.292735] Starting dynamic subtest: pipe-D-eDP-1
[712.350180] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[712.354935] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.009s)
[714.341742] [119/131] (309s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[714.436308] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[714.442133] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[714.534954] [120/131] (309s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[714.636053] Starting subtest: fbcpsr-slowdraw
[739.879974] Subtest fbcpsr-slowdraw: SUCCESS (25.243s)
[740.018612] [121/131] (284s left) kms_invalid_mode (clock-too-high)
[740.122781] Starting subtest: clock-too-high
[740.125282] Starting dynamic subtest: pipe-A-eDP-1
[740.130249] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[740.140322] Starting dynamic subtest: pipe-B-eDP-1
[740.145842] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[740.155290] Starting dynamic subtest: pipe-C-eDP-1
[740.160060] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[740.169321] Starting dynamic subtest: pipe-D-eDP-1
[740.174215] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[740.179147] Subtest clock-too-high: SKIP (0.004s)
[740.219356] [122/131] (284s left) gem_exec_fence (submit)
[740.389451] Starting subtest: submit
[740.395293] Subtest submit: SKIP (0.000s)
[741.819497] [123/131] (282s left) kms_vblank (query-forked-busy-hang)
[741.914654] Starting subtest: query-forked-busy-hang
[741.917200] Starting dynamic subtest: pipe-A-eDP-1
[768.483283] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.563s)
[768.499708] Starting dynamic subtest: pipe-D-eDP-1
[793.500437] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.009s)
[793.503273] Subtest query-forked-busy-hang: SUCCESS (51.577s)
[794.325993] [124/131] (229s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[794.436326] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[794.441905] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[794.529548] [125/131] (229s left) gem_userptr_blits (dmabuf-unsync)
[794.595094] Subtest dmabuf-unsync: SKIP (0.000s)
[794.721004] [126/131] (229s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[794.829195] Starting subtest: plane-upscale-20x20-with-pixel-format
[794.831860] Starting dynamic subtest: pipe-A
[795.917847] Dynamic subtest pipe-A: SUCCESS (1.088s)
[795.927828] Starting dynamic subtest: pipe-B
[798.098153] Dynamic subtest pipe-B: SUCCESS (2.178s)
[798.107255] Starting dynamic subtest: pipe-C
[799.321418] Dynamic subtest pipe-C: SUCCESS (1.221s)
[799.331294] Starting dynamic subtest: pipe-D
[800.549201] Dynamic subtest pipe-D: SUCCESS (1.225s)
[800.552157] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.721s)
[801.748781] [127/131] (222s left) kms_chamelium_color (ctm-max)
[801.848227] Subtest ctm-max: SKIP (0.000s)
[801.902518] [128/131] (222s left) gem_render_copy (y-tiled)
[801.960337] Starting subtest: y-tiled
[801.966035] Subtest y-tiled: SKIP (0.000s)
[802.151329] [129/131] (222s left) kms_psr (fbc-psr-primary-mmap-gtt)
[802.256991] Starting subtest: fbc-psr-primary-mmap-gtt
[802.259686] Starting dynamic subtest: eDP-1
[802.265232] Dynamic subtest eDP-1: SKIP (0.000s)
[802.270276] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.002s)
[802.333140] [130/131] (222s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[802.437196] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[802.442211] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[802.522581] [131/131] (222s left) gem_exec_params (secure-non-master)
[802.581309] Starting subtest: secure-non-master
[802.587307] Subtest secure-non-master: SKIP (0.000s)
[802.680566] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: kms_plane
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: api_intel_allocator
results: parsing output: 7/ for test: gem_exec_params
results: parsing output: 8/ for test: gem_ctx_persistence
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: i915_drm_fdinfo
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_content_protection
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_flip_scaled_crc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_fenced_exec_thrash
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: syncobj_wait
results: parsing output: 29/ for test: gem_render_copy_redux
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: i915_pm_freq_api
results: parsing output: 32/ for test: gem_exercise_blt
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_partial_pwrite_pread
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_ctx_param
results: parsing output: 37/ for test: gem_exec_balancer
results: parsing output: 38/ for test: gem_create
results: parsing output: 39/ for test: kms_flip_scaled_crc
results: parsing output: 40/ for test: gem_exec_reloc
results: parsing output: 41/ for test: perf
results: parsing output: 42/ for test: gem_mmap_offset
results: parsing output: 43/ for test: kms_dirtyfb
results: parsing output: 44/ for test: kms_lease
results: parsing output: 45/ for test: gem_render_copy
results: parsing output: 46/ for test: i915_suspend
results: parsing output: 47/ for test: gem_render_linear_blits
results: parsing output: 48/ for test: gem_ctx_bad_destroy
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_cursor_edge_walk
results: parsing output: 53/ for test: kms_plane
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_softpin
results: parsing output: 57/ for test: kms_chamelium_edid
results: parsing output: 58/ for test: kms_dsc
results: parsing output: 59/ for test: gem_exec_parallel
results: parsing output: 60/ for test: gem_ctx_exec
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: gem_mmap_gtt
results: parsing output: 64/ for test: gem_exec_reloc
results: parsing output: 65/ for test: kms_addfb_basic
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: kms_pipe_crc_basic
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_color
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: sw_sync
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_exec_fence
results: parsing output: 76/ for test: kms_async_flips
results: parsing output: 77/ for test: kms_psr2_su
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: gem_exec_reloc
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: i915_pm_freq_mult
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_pm_rpm
results: parsing output: 84/ for test: gem_exec_whisper
results: parsing output: 85/ for test: fbdev
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: kms_chamelium_hpd
results: parsing output: 90/ for test: perf
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: kms_pm_dc
results: parsing output: 95/ for test: gem_pread
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: syncobj_eventfd
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: kms_atomic_interruptible
results: parsing output: 100/ for test: gem_render_copy
results: parsing output: 101/ for test: gen9_exec_parse
results: parsing output: 102/ for test: core_sysfs
results: parsing output: 103/ for test: perf
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: gem_ctx_persistence
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: gem_softpin
results: parsing output: 108/ for test: api_intel_bb
results: parsing output: 109/ for test: kms_sequence
results: parsing output: 110/ for test: gem_exec_flush
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_plane_multiple
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_invalid_mode
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: kms_vblank
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_userptr_blits
results: parsing output: 125/ for test: kms_plane_scaling
results: parsing output: 126/ for test: kms_chamelium_color
results: parsing output: 127/ for test: gem_render_copy
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_params
Done.