[58.400760] Initializing watchdogs
[58.400809]   /dev/watchdog0
[58.415535] [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.420103] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.422358] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.425712] [001/133] (960s left) kms_chamelium_edid (vga-edid-read)
[58.830557] Subtest vga-edid-read: SKIP (0.000s)
[59.627913] [002/133] (959s left) kms_hdr (bpc-switch-dpms)
[59.716614] Starting subtest: bpc-switch-dpms
[60.812784] Starting dynamic subtest: pipe-A-eDP-1
[65.316572] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.513s)
[65.318618] Subtest bpc-switch-dpms: SUCCESS (5.613s)
[65.412831] [003/133] (953s left) gem_mmap_offset (partial-unmap)
[65.466226] Starting subtest: partial-unmap
[65.468549] Starting dynamic subtest: smem0
[65.934662] Dynamic subtest smem0: SUCCESS (0.470s)
[65.937262] Subtest partial-unmap: SUCCESS (0.470s)
[66.064874] [004/133] (952s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[66.169366] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[69.780079] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.620s)
[70.970185] [005/133] (948s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[71.064701] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[77.727922] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.669s)
[77.861982] [006/133] (941s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[77.969148] Starting subtest: fbc-psr2-primary-mmap-cpu
[77.971358] Starting dynamic subtest: eDP-1
[77.975670] Dynamic subtest eDP-1: SKIP (0.000s)
[77.980613] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.000s)
[78.048857] [007/133] (941s left) gem_render_tiled_blits (basic)
[78.109073] Subtest basic: SKIP (0.000s)
[78.172218] [008/133] (941s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[78.273294] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[78.277400] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[78.376580] [009/133] (940s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[78.462703] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[78.464842] Starting dynamic subtest: pipe-A-default-mode
[79.640214] Dynamic subtest pipe-A-default-mode: SKIP (1.175s)
[79.644054] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.176s)
[79.782117] [010/133] (939s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[79.885100] Starting subtest: yf-tiled-32bpp-rotate-180
[79.889525] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[79.984990] [011/133] (939s left) fbdev (eof)
[80.026344] Starting subtest: eof
[80.028365] Subtest eof: SUCCESS (0.000s)
[80.095051] [012/133] (939s left) kms_cursor_crc (cursor-onscreen-512x512)
[80.383876] Starting subtest: cursor-onscreen-512x512
[80.387927] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[80.537546] [013/133] (938s left) kms_psr (psr-sprite-mmap-cpu)
[80.627301] Starting subtest: psr-sprite-mmap-cpu
[80.629161] Starting dynamic subtest: eDP-1
[82.343474] Dynamic subtest eDP-1: SUCCESS (1.717s)
[82.345871] Subtest psr-sprite-mmap-cpu: SUCCESS (1.717s)
[83.180648] [014/133] (936s left) gen9_exec_parse (allowed-single)
[83.236564] Subtest allowed-single: SKIP (0.000s)
[83.307572] [015/133] (936s left) gem_ctx_engines (none)
[83.366594] Starting subtest: none
[83.369049] Subtest none: SUCCESS (0.001s)
[83.561810] [016/133] (935s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[83.673595] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[83.677795] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[83.767938] [017/133] (935s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[83.878986] Starting subtest: x-tiled-32bpp-rotate-90
[83.898808] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s)
[84.020964] [018/133] (935s left) i915_hangman (engine-error-state-capture)
[84.094488] Starting subtest: engine-error-state-capture
[84.096703] Starting dynamic subtest: rcs0
[87.861198] Dynamic subtest rcs0: SUCCESS (3.765s)
[87.872037] Starting dynamic subtest: bcs0
[93.877626] Dynamic subtest bcs0: SUCCESS (6.015s)
[93.889166] Starting dynamic subtest: vcs0
[99.829251] Dynamic subtest vcs0: SUCCESS (5.950s)
[99.840304] Starting dynamic subtest: vcs1
[104.823397] Dynamic subtest vcs1: SUCCESS (4.992s)
[104.831945] Starting dynamic subtest: vecs0
[109.813891] Dynamic subtest vecs0: SUCCESS (4.989s)
[109.824882] Starting dynamic subtest: ccs0
[113.845736] Dynamic subtest ccs0: SUCCESS (4.031s)
[113.848640] Subtest engine-error-state-capture: SUCCESS (29.750s)
[113.927728] [019/133] (905s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[114.028848] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[114.420611] Starting dynamic subtest: pipe-A-eDP-1
[117.198699] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.726s)
[117.507570] Starting dynamic subtest: pipe-B-eDP-1
[120.324171] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.770s)
[120.633062] Starting dynamic subtest: pipe-C-eDP-1
[123.456925] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.779s)
[123.778492] Starting dynamic subtest: pipe-D-eDP-1
[126.584787] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.764s)
[126.587235] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.505s)
[127.713087] [020/133] (891s left) gem_linear_blits (interruptible)
[128.013612] Starting subtest: interruptible
[131.983745] Subtest interruptible: SUCCESS (3.969s)
[132.113657] [021/133] (887s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[132.267730] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[132.271953] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[132.352428] [022/133] (887s left) kms_properties (crtc-properties-legacy)
[132.437348] Starting subtest: crtc-properties-legacy
[132.439604] Starting dynamic subtest: pipe-A-eDP-1
[132.904662] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[132.914370] Starting dynamic subtest: pipe-B-eDP-1
[134.071613] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.165s)
[134.080923] Starting dynamic subtest: pipe-C-eDP-1
[135.210253] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.138s)
[135.220378] Starting dynamic subtest: pipe-D-eDP-1
[136.351274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.141s)
[136.353498] Subtest crtc-properties-legacy: SUCCESS (3.913s)
[137.151714] [023/133] (882s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[137.257682] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[137.262277] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[137.353388] [024/133] (882s left) kms_psr (psr2-sprite-mmap-cpu)
[137.455942] Starting subtest: psr2-sprite-mmap-cpu
[137.458271] Starting dynamic subtest: eDP-1
[139.153545] Dynamic subtest eDP-1: SUCCESS (1.696s)
[139.155882] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.697s)
[140.022345] [025/133] (879s left) kms_plane_alpha_blend (alpha-opaque-fb)
[140.113290] Starting subtest: alpha-opaque-fb
[140.415731] Starting dynamic subtest: pipe-A-eDP-1
[141.092276] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.673s)
[141.103760] Starting dynamic subtest: pipe-D-eDP-1
[142.494196] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.400s)
[142.496595] Subtest alpha-opaque-fb: SUCCESS (2.378s)
[143.291485] [026/133] (876s left) prime_mmap (test_correct_cpu_write)
[143.349899] Starting subtest: test_correct_cpu_write
[143.352294] Starting dynamic subtest: test_correct_cpu_write-smem
[143.354585] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[143.356509] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[143.458746] [027/133] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[143.550527] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[143.554652] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[143.628641] [028/133] (876s left) gem_mmap_gtt (basic-small-bo)
[143.689383] Subtest basic-small-bo: SKIP (0.000s)
[143.754700] [029/133] (876s left) kms_content_protection (type1)
[143.885973] Starting subtest: type1
[143.890464] Subtest type1: SKIP (0.000s)
[144.017564] [030/133] (876s left) gem_exec_fence (syncobj-export)
[144.507550] Starting subtest: syncobj-export
[144.513124] Subtest syncobj-export: SUCCESS (0.006s)
[145.953836] [FACT gem_exec_fence (syncobj-export)] new: kernel.kmod_is_loaded.vgem: true
[145.957278] [031/133] (874s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[146.055081] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[146.057218] Starting dynamic subtest: pipe-A-eDP-1
[146.816646] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.754s)
[146.826279] Starting dynamic subtest: pipe-B-eDP-1
[148.633273] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.815s)
[148.643015] Starting dynamic subtest: pipe-C-eDP-1
[150.456296] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.821s)
[150.464445] Starting dynamic subtest: pipe-D-eDP-1
[152.292715] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.835s)
[152.294878] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.230s)
[153.417169] [032/133] (866s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[153.475074] Starting subtest: etime-single-wait-available-unsubmitted
[153.579273] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.102s)
[153.656753] [033/133] (866s left) syncobj_timeline (invalid-signal-bad-pad)
[153.717102] Starting subtest: invalid-signal-bad-pad
[153.719316] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[153.805118] [034/133] (866s left) perf_pmu (busy-accuracy-98)
[153.856793] Starting subtest: busy-accuracy-98
[153.858854] Starting dynamic subtest: rcs0
[156.895317] Dynamic subtest rcs0: SUCCESS (3.036s)
[156.906110] Starting dynamic subtest: bcs0
[159.948158] Dynamic subtest bcs0: SUCCESS (3.051s)
[159.958636] Starting dynamic subtest: vcs0
[163.049799] Dynamic subtest vcs0: SUCCESS (3.045s)
[163.060023] Starting dynamic subtest: vcs1
[166.085733] Dynamic subtest vcs1: SUCCESS (3.042s)
[166.096395] Starting dynamic subtest: vecs0
[169.130701] Dynamic subtest vecs0: SUCCESS (3.042s)
[169.140859] Starting dynamic subtest: ccs0
[172.131807] Dynamic subtest ccs0: SUCCESS (3.048s)
[172.134269] Subtest busy-accuracy-98: SUCCESS (18.273s)
[172.249675] [035/133] (848s left) dmabuf_sync_file (import-multiple-read-write)
[172.302317] Starting subtest: import-multiple-read-write
[172.304587] Subtest import-multiple-read-write: SUCCESS (0.003s)
[172.361724] [036/133] (847s left) kms_atomic_transition (plane-toggle-modeset-transition)
[172.452658] Starting subtest: plane-toggle-modeset-transition
[172.454533] Starting dynamic subtest: pipe-A-eDP-1
[185.132074] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.679s)
[185.143820] Starting dynamic subtest: pipe-B-eDP-1
[197.560324] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.426s)
[197.563072] Subtest plane-toggle-modeset-transition: SUCCESS (25.110s)
[198.381122] [037/133] (821s left) gem_lmem_swapping (heavy-verify-multi)
[198.428737] Subtest heavy-verify-multi: SKIP (0.000s)
[198.479734] [038/133] (821s left) gem_exec_parallel (engines)
[198.540062] Starting subtest: engines
[198.542063] Starting dynamic subtest: basic
[201.943529] Dynamic subtest basic: SUCCESS (3.403s)
[201.954291] Starting dynamic subtest: contexts
[206.294679] Dynamic subtest contexts: SUCCESS (4.350s)
[206.304194] Starting dynamic subtest: fds
[210.091605] Dynamic subtest fds: SUCCESS (3.794s)
[210.100952] Starting dynamic subtest: userptr
[211.930086] Dynamic subtest userptr: SUCCESS (1.832s)
[211.932850] Subtest engines: SUCCESS (13.388s)
[212.133469] [039/133] (808s left) gem_exec_balancer (bonded-chain)
[212.197279] Starting subtest: bonded-chain
[219.262660] Subtest bonded-chain: SUCCESS (7.035s)
[219.557620] [040/133] (800s left) kms_dp_link_training (uhbr-mst)
[219.694987] Starting subtest: uhbr-mst
[219.703585] Subtest uhbr-mst: SKIP (0.000s)
[219.781137] [041/133] (800s left) gem_exec_schedule (semaphore-user)
[219.854503] Starting subtest: semaphore-user
[219.903024] Subtest semaphore-user: SUCCESS (0.056s)
[221.952287] [042/133] (798s left) gem_exec_schedule (manyslice)
[222.026036] Starting subtest: manyslice
[222.028294] Starting dynamic subtest: rcs0
[227.205604] Dynamic subtest rcs0: SUCCESS (5.135s)
[227.218433] Starting dynamic subtest: bcs0
[232.836121] Dynamic subtest bcs0: SUCCESS (5.630s)
[232.848371] Starting dynamic subtest: vcs0
[238.419790] Dynamic subtest vcs0: SUCCESS (5.582s)
[238.432388] Starting dynamic subtest: vcs1
[244.502130] Dynamic subtest vcs1: SUCCESS (6.081s)
[244.513382] Starting dynamic subtest: vecs0
[249.563258] Dynamic subtest vecs0: SUCCESS (5.061s)
[249.576964] Starting dynamic subtest: ccs0
[255.744588] Dynamic subtest ccs0: SUCCESS (6.180s)
[255.747090] Subtest manyslice: SUCCESS (33.676s)
[255.994619] [043/133] (764s left) gem_pxp (create-regular-context-1)
[256.121685] Starting subtest: create-regular-context-1
[256.124308] Subtest create-regular-context-1: SUCCESS (0.002s)
[256.237955] [044/133] (764s left) prime_self_import (reimport-vs-gem_close-race)
[256.282259] Starting subtest: reimport-vs-gem_close-race
[261.367892] Subtest reimport-vs-gem_close-race: SUCCESS (5.085s)
[261.440685] [045/133] (759s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[261.546982] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[261.549325] Starting dynamic subtest: pipe-A-eDP-1
[261.608901] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[261.617816] Starting dynamic subtest: pipe-B-eDP-1
[261.674499] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[261.685093] Starting dynamic subtest: pipe-C-eDP-1
[261.741627] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[261.752116] Starting dynamic subtest: pipe-D-eDP-1
[261.809097] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[261.813213] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.008s)
[263.819674] [046/133] (756s left) kms_flip (2x-flip-vs-modeset)
[263.907698] Starting subtest: 2x-flip-vs-modeset
[263.919072] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[264.022995] [047/133] (756s left) kms_psr (fbc-pr-cursor-plane-move)
[264.120434] Starting subtest: fbc-pr-cursor-plane-move
[264.124663] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[264.202309] [048/133] (756s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[264.297093] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[264.299381] Starting dynamic subtest: pipe-A-eDP-1
[264.322110] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[264.332804] Starting dynamic subtest: pipe-B-eDP-1
[264.353757] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[264.362591] Starting dynamic subtest: pipe-C-eDP-1
[264.383773] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[264.392398] Starting dynamic subtest: pipe-D-eDP-1
[264.414350] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[264.418795] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[266.505324] [049/133] (754s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[266.580243] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[266.646326] [050/133] (754s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[266.758699] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[266.762716] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[266.875384] [051/133] (754s left) i915_selftest (mock)
[270.162415] Starting subtest: mock
[270.164743] Starting dynamic subtest: sanitycheck
[270.890562] Dynamic subtest sanitycheck: SUCCESS (0.732s)
[270.900868] Starting dynamic subtest: shmem
[271.584125] Dynamic subtest shmem: SUCCESS (0.692s)
[271.593528] Starting dynamic subtest: fence
[279.075194] Dynamic subtest fence: SUCCESS (7.488s)
[279.084779] Starting dynamic subtest: scatterlist
[280.774329] Dynamic subtest scatterlist: SUCCESS (1.698s)
[280.784485] Starting dynamic subtest: syncmap
[283.926876] Dynamic subtest syncmap: SUCCESS (3.151s)
[283.936793] Starting dynamic subtest: uncore
[284.584176] Dynamic subtest uncore: SUCCESS (0.656s)
[284.594305] Starting dynamic subtest: ring
[285.232132] Dynamic subtest ring: SUCCESS (0.646s)
[285.242113] Starting dynamic subtest: engine
[285.911659] Dynamic subtest engine: SUCCESS (0.677s)
[285.921623] Starting dynamic subtest: timelines
[289.782563] Dynamic subtest timelines: SUCCESS (3.869s)
[289.791685] Starting dynamic subtest: requests
[293.351828] Dynamic subtest requests: SUCCESS (3.568s)
[293.366713] Starting dynamic subtest: objects
[294.132331] Dynamic subtest objects: SUCCESS (0.779s)
[294.141885] Starting dynamic subtest: phys
[294.917962] Dynamic subtest phys: SUCCESS (0.784s)
[294.927583] Starting dynamic subtest: dmabuf
[295.695252] Dynamic subtest dmabuf: SUCCESS (0.776s)
[295.705097] Starting dynamic subtest: vma
[301.535183] Dynamic subtest vma: SUCCESS (5.838s)
[301.543571] Starting dynamic subtest: evict
[303.026366] Dynamic subtest evict: SUCCESS (1.489s)
[303.035907] Starting dynamic subtest: gtt
[306.376217] Dynamic subtest gtt: SUCCESS (3.349s)
[306.385633] Starting dynamic subtest: hugepages
[307.269249] Dynamic subtest hugepages: SUCCESS (0.891s)
[307.279636] Starting dynamic subtest: memory_region
[309.765845] Dynamic subtest memory_region: SUCCESS (2.494s)
[309.768769] Subtest mock: SUCCESS (39.604s)
[309.797791] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[309.798523] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[309.801778] [052/133] (711s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[311.352930] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[311.355234] Starting dynamic subtest: pipe-A-eDP-1
[311.379392] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[311.389711] Starting dynamic subtest: pipe-B-eDP-1
[311.411593] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[311.421284] Starting dynamic subtest: pipe-C-eDP-1
[311.442771] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[311.450990] Starting dynamic subtest: pipe-D-eDP-1
[311.473476] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[311.477568] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[313.676922] [FACT kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[313.679874] [FACT kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)] new: kernel.kmod_is_loaded.i915: true
[313.683831] [053/133] (707s left) syncobj_wait (multi-wait-for-submit-submitted)
[313.748057] Starting subtest: multi-wait-for-submit-submitted
[313.850085] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s)
[313.927030] [054/133] (707s left) kms_color_pipeline (plane-ctm3x4)
[314.035268] Starting subtest: plane-ctm3x4
[314.180902] Starting dynamic subtest: pipe-A-plane-0
[314.511945] Dynamic subtest pipe-A-plane-0: FAIL (0.160s)
[314.522393] Starting dynamic subtest: pipe-A-plane-1
[314.708878] Dynamic subtest pipe-A-plane-1: FAIL (0.137s)
[314.718432] Starting dynamic subtest: pipe-A-plane-2
[314.921837] Dynamic subtest pipe-A-plane-2: FAIL (0.143s)
[315.863652] Starting dynamic subtest: pipe-B-plane-0
[316.296600] Dynamic subtest pipe-B-plane-0: FAIL (0.163s)
[316.306857] Starting dynamic subtest: pipe-B-plane-1
[316.605697] Dynamic subtest pipe-B-plane-1: FAIL (0.144s)
[316.614912] Starting dynamic subtest: pipe-B-plane-2
[316.904095] Dynamic subtest pipe-B-plane-2: FAIL (0.155s)
[317.551343] Starting dynamic subtest: pipe-C-plane-0
[318.033799] Dynamic subtest pipe-C-plane-0: FAIL (0.141s)
[318.043834] Starting dynamic subtest: pipe-C-plane-1
[318.431337] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[318.441734] Starting dynamic subtest: pipe-C-plane-2
[318.783395] Dynamic subtest pipe-C-plane-2: FAIL (0.603s)
[319.592495] Starting dynamic subtest: pipe-D-plane-0
[320.133068] Dynamic subtest pipe-D-plane-0: FAIL (0.107s)
[320.143276] Starting dynamic subtest: pipe-D-plane-1
[320.590233] Dynamic subtest pipe-D-plane-1: FAIL (0.594s)
[320.600480] Starting dynamic subtest: pipe-D-plane-2
[321.015044] Dynamic subtest pipe-D-plane-2: FAIL (0.542s)
[321.137965] Subtest plane-ctm3x4: FAIL (7.105s)
[321.962144] [055/133] (699s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[322.087937] Starting subtest: 4-tiled-64bpp-rotate-0
[323.600993] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.513s)
[323.778449] [056/133] (697s left) sysfs_heartbeat_interval (invalid)
[323.840528] Starting subtest: invalid
[323.842953] Starting dynamic subtest: vecs0
[323.845434] Dynamic subtest vecs0: SUCCESS (0.000s)
[323.854970] Starting dynamic subtest: ccs0
[323.857126] Dynamic subtest ccs0: SUCCESS (0.000s)
[323.865334] Starting dynamic subtest: vcs0
[323.867162] Dynamic subtest vcs0: SUCCESS (0.000s)
[323.875272] Starting dynamic subtest: bcs0
[323.877340] Dynamic subtest bcs0: SUCCESS (0.000s)
[323.885486] Starting dynamic subtest: rcs0
[323.887603] Dynamic subtest rcs0: SUCCESS (0.000s)
[323.895764] Starting dynamic subtest: vcs1
[323.897975] Dynamic subtest vcs1: SUCCESS (0.000s)
[323.900168] Subtest invalid: SUCCESS (0.005s)
[323.938536] [057/133] (697s left) gem_exec_params (invalid-flag)
[323.994833] Starting subtest: invalid-flag
[323.996814] Subtest invalid-flag: SUCCESS (0.000s)
[324.090856] [058/133] (697s left) i915_pm_rpm (reg-read-ioctl)
[326.023877] Starting subtest: reg-read-ioctl
[328.325873] Subtest reg-read-ioctl: SUCCESS (2.305s)
[328.703795] [059/133] (692s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[328.810727] Starting subtest: pipe-fbc-rte
[328.817304] Starting dynamic subtest: pipe-B-eDP-1
[334.246531] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.440s)
[334.254284] Subtest pipe-fbc-rte: SUCCESS (5.443s)
[336.523805] [060/133] (684s left) gem_mmap (bad-size)
[336.586068] Subtest bad-size: SKIP (0.000s)
[336.655738] [061/133] (684s left) kms_addfb_basic (bad-pitch-128)
[336.717058] Starting subtest: bad-pitch-128
[336.719198] Subtest bad-pitch-128: SUCCESS (0.000s)
[336.860492] [062/133] (684s left) gem_exec_reloc (basic-wc-gtt)
[336.929065] Subtest basic-wc-gtt: SKIP (0.000s)
[336.999228] [063/133] (684s left) kms_addfb_basic (addfb25-modifier-no-flag)
[337.095113] Starting subtest: addfb25-modifier-no-flag
[337.097233] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[337.205115] [064/133] (684s left) kms_psr (fbc-psr2-sprite-render)
[337.294084] Starting subtest: fbc-psr2-sprite-render
[337.296020] Starting dynamic subtest: eDP-1
[337.299957] Dynamic subtest eDP-1: SKIP (0.000s)
[337.304246] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[337.367648] [065/133] (684s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[337.453810] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[337.455861] Starting dynamic subtest: pipe-A
[337.789515] Dynamic subtest pipe-A: SUCCESS (0.338s)
[337.799934] Starting dynamic subtest: pipe-B
[339.201217] Dynamic subtest pipe-B: SUCCESS (1.409s)
[339.210476] Starting dynamic subtest: pipe-C
[340.654398] Dynamic subtest pipe-C: SUCCESS (1.452s)
[340.663678] Starting dynamic subtest: pipe-D
[342.047584] Dynamic subtest pipe-D: SUCCESS (1.391s)
[342.049881] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.597s)
[343.222809] [066/133] (678s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping)
[343.319078] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping
[343.323293] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s)
[343.402231] [067/133] (678s left) kms_chamelium_hpd (vga-hpd)
[343.792762] Subtest vga-hpd: SKIP (0.000s)
[344.638628] [068/133] (677s left) kms_psr (pr-primary-mmap-gtt)
[344.732625] Starting subtest: pr-primary-mmap-gtt
[344.736732] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[344.807346] [069/133] (676s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[344.900251] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[344.904441] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[345.015285] [070/133] (676s left) gem_exec_store (basic)
[345.074070] Starting subtest: basic
[345.175984] Subtest basic: SUCCESS (0.103s)
[345.363728] [071/133] (676s left) kms_cursor_crc (cursor-tearing-position-change)
[345.480825] Starting subtest: cursor-tearing-position-change
[345.483097] Starting dynamic subtest: pipe-A-eDP-1
[346.035706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s)
[346.045116] Starting dynamic subtest: pipe-D-eDP-1
[347.283279] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[347.285122] Subtest cursor-tearing-position-change: SUCCESS (1.800s)
[348.112661] [072/133] (673s left) kms_invalid_mode (uint-max-clock)
[348.215118] Starting subtest: uint-max-clock
[348.217330] Starting dynamic subtest: pipe-A-eDP-1
[348.521229] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.305s)
[348.530687] Starting dynamic subtest: pipe-B-eDP-1
[348.532800] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[348.541179] Starting dynamic subtest: pipe-C-eDP-1
[348.543378] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[348.551759] Starting dynamic subtest: pipe-D-eDP-1
[348.554068] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[348.556191] Subtest uint-max-clock: SUCCESS (0.312s)
[349.359061] [073/133] (672s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[349.461180] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[349.463310] Starting dynamic subtest: pipe-A
[349.809642] Dynamic subtest pipe-A: SUCCESS (0.350s)
[349.818941] Starting dynamic subtest: pipe-B
[351.253645] Dynamic subtest pipe-B: SUCCESS (1.443s)
[351.262667] Starting dynamic subtest: pipe-C
[352.683161] Dynamic subtest pipe-C: SUCCESS (1.429s)
[352.692250] Starting dynamic subtest: pipe-D
[354.112910] Dynamic subtest pipe-D: SUCCESS (1.429s)
[354.115132] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.654s)
[355.305878] [074/133] (666s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[355.408478] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[355.412771] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[355.507389] [075/133] (666s left) core_auth (basic-auth)
[355.566167] Starting subtest: basic-auth
[355.568417] Subtest basic-auth: SUCCESS (0.001s)
[355.667567] [076/133] (666s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[355.803045] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[355.807506] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[355.958531] [077/133] (666s left) kms_plane_cursor (viewport)
[356.044322] Starting subtest: viewport
[356.048575] Starting dynamic subtest: pipe-A-eDP-1-size-64
[358.621246] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.576s)
[358.679716] Starting dynamic subtest: pipe-A-eDP-1-size-128
[361.177190] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.505s)
[361.241076] Starting dynamic subtest: pipe-A-eDP-1-size-256
[363.798893] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.571s)
[364.171614] Starting dynamic subtest: pipe-B-eDP-1-size-64
[367.435850] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.277s)
[367.496305] Starting dynamic subtest: pipe-B-eDP-1-size-128
[369.982020] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.493s)
[370.040787] Starting dynamic subtest: pipe-B-eDP-1-size-256
[372.549985] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.515s)
[372.896383] Starting dynamic subtest: pipe-C-eDP-1-size-64
[375.777606] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.891s)
[375.815779] Starting dynamic subtest: pipe-C-eDP-1-size-128
[377.943072] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.136s)
[377.983122] Starting dynamic subtest: pipe-C-eDP-1-size-256
[380.133232] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.158s)
[380.466553] Starting dynamic subtest: pipe-D-eDP-1-size-64
[383.351323] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.895s)
[383.388747] Starting dynamic subtest: pipe-D-eDP-1-size-128
[385.562780] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.180s)
[385.600525] Starting dynamic subtest: pipe-D-eDP-1-size-256
[387.729615] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.136s)
[388.047670] Subtest viewport: SUCCESS (32.002s)
[388.914029] [078/133] (633s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[389.006681] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[395.730167] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.727s)
[395.881047] [079/133] (626s left) gem_ctx_sseu (mmap-args)
[395.946541] Subtest mmap-args: SKIP (0.000s)
[396.021673] [080/133] (626s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[396.132542] Starting subtest: extended-modeset-hang-newfb-with-reset
[396.134815] Starting dynamic subtest: pipe-A
[409.883268] Dynamic subtest pipe-A: SUCCESS (13.748s)
[409.893142] Starting dynamic subtest: pipe-D
[425.654895] Dynamic subtest pipe-D: SUCCESS (15.768s)
[425.656865] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.522s)
[426.459243] [081/133] (595s left) gem_softpin (allocator-nopin-reserve)
[426.522663] Starting subtest: allocator-nopin-reserve
[426.620987] Subtest allocator-nopin-reserve: SUCCESS (0.098s)
[426.758797] [082/133] (595s left) vgem_slow (nohang)
[426.799302] Starting subtest: nohang
[436.852333] Subtest nohang: SUCCESS (10.056s)
[436.918566] [083/133] (585s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[437.016474] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[437.020503] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[437.112638] [084/133] (585s left) kms_frontbuffer_tracking (psr-slowdraw)
[437.226850] Starting subtest: psr-slowdraw
[462.500223] Subtest psr-slowdraw: SUCCESS (25.275s)
[462.613243] [085/133] (559s left) gem_ctx_param (vm)
[462.674842] Starting subtest: vm
[462.720858] Subtest vm: SUCCESS (0.046s)
[462.868058] [086/133] (559s left) syncobj_basic (bad-handle-to-fd)
[462.926592] Starting subtest: bad-handle-to-fd
[462.928805] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[463.027092] [087/133] (559s left) perf_pmu (invalid-open)
[463.071523] Starting subtest: invalid-open
[463.073744] Subtest invalid-open: SUCCESS (0.000s)
[463.170777] [088/133] (559s left) i915_drm_fdinfo (busy-hang)
[463.214255] Starting subtest: busy-hang
[463.216426] Starting dynamic subtest: rcs0
[463.236911] Dynamic subtest rcs0: SKIP (0.024s)
[463.248515] Starting dynamic subtest: bcs0
[463.252162] Dynamic subtest bcs0: SKIP (0.001s)
[463.260743] Starting dynamic subtest: vcs0
[463.264388] Dynamic subtest vcs0: SKIP (0.000s)
[463.275626] Starting dynamic subtest: vcs1
[463.279136] Dynamic subtest vcs1: SKIP (0.000s)
[463.287688] Starting dynamic subtest: vecs0
[463.291197] Dynamic subtest vecs0: SKIP (0.000s)
[463.300240] Starting dynamic subtest: ccs0
[463.303749] Dynamic subtest ccs0: SKIP (0.001s)
[463.307247] Subtest busy-hang: SKIP (0.029s)
[463.343509] [089/133] (559s left) gem_pxp (protected-raw-src-copy-not-readible)
[463.468262] Starting subtest: protected-raw-src-copy-not-readible
[463.540730] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.075s)
[463.664102] [090/133] (558s left) gem_vm_create (create-ext)
[463.725292] Starting subtest: create-ext
[463.729842] Subtest create-ext: SUCCESS (0.004s)
[463.863664] [091/133] (558s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[463.919688] Subtest untiled-to-tiled: SKIP (0.000s)
[463.981956] [092/133] (558s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[464.037987] Starting subtest: map-fixed-invalidate-overlap
[464.041448] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[464.170218] [093/133] (558s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[464.269845] Starting subtest: y-tiled-16bpp-rotate-0
[464.273567] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[464.384232] [094/133] (558s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[466.001916] Starting subtest: dpms-mode-unset-non-lpsp
[467.158411] Subtest dpms-mode-unset-non-lpsp: SKIP (1.153s)
[467.534192] [095/133] (555s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[467.637637] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[467.639981] Starting dynamic subtest: pipe-A-eDP-1
[467.663574] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[467.676637] Starting dynamic subtest: pipe-B-eDP-1
[467.698606] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[467.709820] Starting dynamic subtest: pipe-C-eDP-1
[467.730588] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[467.739482] Starting dynamic subtest: pipe-D-eDP-1
[467.760128] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[467.764063] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[469.877198] [096/133] (552s left) gem_ctx_persistence (legacy-engines-hang)
[469.933702] Starting subtest: legacy-engines-hang
[469.935786] Starting dynamic subtest: render
[469.999741] Dynamic subtest render: SUCCESS (0.068s)
[470.013243] Starting dynamic subtest: bsd1
[470.089675] Dynamic subtest bsd1: SUCCESS (0.087s)
[470.104456] Starting dynamic subtest: bsd2
[470.183252] Dynamic subtest bsd2: SUCCESS (0.091s)
[470.197680] Starting dynamic subtest: blt
[470.275822] Dynamic subtest blt: SUCCESS (0.089s)
[470.288645] Starting dynamic subtest: vebox
[470.363933] Dynamic subtest vebox: SUCCESS (0.085s)
[470.366612] Subtest legacy-engines-hang: SUCCESS (0.435s)
[470.454202] [097/133] (552s left) kms_vblank (wait-forked-busy)
[470.554158] Starting subtest: wait-forked-busy
[470.556072] Starting dynamic subtest: pipe-A-eDP-1
[472.433567] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.878s)
[472.453631] Starting dynamic subtest: pipe-D-eDP-1
[475.038121] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.602s)
[475.040416] Subtest wait-forked-busy: SUCCESS (4.485s)
[475.844911] [098/133] (547s left) kms_feature_discovery (display-4x)
[475.946828] Starting subtest: display-4x
[475.951095] Subtest display-4x: SKIP (0.000s)
[476.022086] [099/133] (546s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[476.124814] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[476.129152] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[476.222424] [100/133] (546s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[476.311298] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[476.315608] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[476.417350] [101/133] (546s left) prime_busy (after-wait)
[476.486375] Starting subtest: after-wait
[476.491423] Starting dynamic subtest: rcs0
[476.518228] Dynamic subtest rcs0: SUCCESS (0.029s)
[476.528248] Starting dynamic subtest: bcs0
[476.558055] Dynamic subtest bcs0: SUCCESS (0.039s)
[476.567071] Starting dynamic subtest: vcs0
[476.599201] Dynamic subtest vcs0: SUCCESS (0.041s)
[476.608637] Starting dynamic subtest: vcs1
[476.638635] Dynamic subtest vcs1: SUCCESS (0.039s)
[476.648018] Starting dynamic subtest: vecs0
[476.679458] Dynamic subtest vecs0: SUCCESS (0.040s)
[476.688751] Starting dynamic subtest: ccs0
[476.719691] Dynamic subtest ccs0: SUCCESS (0.039s)
[476.829068] Subtest after-wait: SUCCESS (0.342s)
[476.906592] [102/133] (546s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[477.002610] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[477.004842] Starting dynamic subtest: pipe-A-eDP-1
[477.076773] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[477.088907] Starting dynamic subtest: pipe-B-eDP-1
[478.220468] Dynamic subtest pipe-B-eDP-1: SKIP (1.140s)
[478.224916] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.220s)
[479.417253] [103/133] (543s left) gem_render_copy (yf-tiled-to-vebox-linear)
[479.478084] Starting subtest: yf-tiled-to-vebox-linear
[479.482457] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[479.672170] [104/133] (543s left) kms_sequence (queue-idle)
[479.756573] Starting subtest: queue-idle
[479.759040] Starting dynamic subtest: pipe-A-eDP-1
[481.555521] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.801s)
[481.565599] Starting dynamic subtest: pipe-B-eDP-1
[484.090593] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.531s)
[484.100320] Starting dynamic subtest: pipe-C-eDP-1
[486.599702] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.503s)
[486.610534] Starting dynamic subtest: pipe-D-eDP-1
[489.033235] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.434s)
[489.035439] Subtest queue-idle: SUCCESS (9.277s)
[489.833452] [105/133] (533s left) syncobj_basic (bad-destroy)
[489.896537] Starting subtest: bad-destroy
[489.898498] Subtest bad-destroy: SUCCESS (0.000s)
[490.008050] [106/133] (533s left) prime_vgem (basic-gtt)
[490.087113] Starting subtest: basic-gtt
[490.091719] Subtest basic-gtt: SKIP (0.000s)
[490.195492] [107/133] (533s left) kms_prop_blob (basic)
[490.244670] Starting subtest: basic
[490.246941] Subtest basic: SUCCESS (0.000s)
[490.372357] [108/133] (533s left) prime_vgem (fence-write-hang)
[490.446539] Subtest fence-write-hang: SKIP (0.000s)
[490.561299] [109/133] (532s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[490.669524] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[498.385399] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.719s)
[498.512335] [110/133] (524s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[498.573482] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[498.575806] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[498.691277] [111/133] (524s left) gem_flink_basic (flink-lifetime)
[498.753874] Starting subtest: flink-lifetime
[498.756322] Subtest flink-lifetime: SUCCESS (0.002s)
[498.861891] [112/133] (524s left) core_debugfs (read-all-entries)
[498.914044] Starting subtest: read-all-entries
[499.010964] Subtest read-all-entries: SUCCESS (0.097s)
[499.100917] [113/133] (524s left) kms_flip (flip-vs-fences)
[499.213026] Starting subtest: flip-vs-fences
[499.217690] Subtest flip-vs-fences: SKIP (0.000s)
[499.311322] [114/133] (524s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[499.411166] Starting subtest: yf-tiled-addfb-size-overflow
[499.414866] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[499.500716] [115/133] (524s left) syncobj_timeline (signal-array)
[499.552290] Starting subtest: signal-array
[499.554285] Subtest signal-array: SUCCESS (0.000s)
[499.642725] [116/133] (524s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[499.852158] Starting subtest: cursor-rapid-movement-64x21
[499.899059] Subtest cursor-rapid-movement-64x21: SKIP (0.044s)
[500.136770] [117/133] (523s left) kms_lease (lease-invalid-connector)
[500.221654] Starting subtest: lease-invalid-connector
[500.223943] Starting dynamic subtest: pipe-A-eDP-1
[500.226116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[500.240411] Starting dynamic subtest: pipe-B-eDP-1
[500.242212] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[500.251286] Starting dynamic subtest: pipe-C-eDP-1
[500.252853] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[500.261555] Starting dynamic subtest: pipe-D-eDP-1
[500.263301] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[500.265035] Subtest lease-invalid-connector: SUCCESS (0.004s)
[500.305401] [118/133] (523s left) kms_flip (nonexisting-fb-interruptible)
[500.409393] Starting subtest: nonexisting-fb-interruptible
[500.411334] Starting dynamic subtest: A-eDP1
[501.602714] Dynamic subtest A-eDP1: SUCCESS (1.191s)
[501.613467] Starting dynamic subtest: B-eDP1
[502.735040] Dynamic subtest B-eDP1: SUCCESS (1.130s)
[502.747442] Starting dynamic subtest: C-eDP1
[503.866236] Dynamic subtest C-eDP1: SUCCESS (1.128s)
[503.876558] Starting dynamic subtest: D-eDP1
[504.998461] Dynamic subtest D-eDP1: SUCCESS (1.131s)
[505.000746] Subtest nonexisting-fb-interruptible: SUCCESS (4.592s)
[506.177348] [119/133] (517s left) kms_async_flips (test-time-stamp-atomic)
[506.281196] Starting subtest: test-time-stamp-atomic
[506.652269] Starting dynamic subtest: pipe-A-eDP-1
[507.475191] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.821s)
[507.854700] Starting dynamic subtest: pipe-B-eDP-1
[508.603795] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.755s)
[508.985886] Starting dynamic subtest: pipe-C-eDP-1
[509.728390] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.747s)
[510.099688] Starting dynamic subtest: pipe-D-eDP-1
[510.864233] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.771s)
[510.866626] Subtest test-time-stamp-atomic: SUCCESS (4.584s)
[512.018837] [120/133] (511s left) gem_exec_big (single)
[512.079420] Starting subtest: single
[515.604344] Subtest single: FAIL (3.461s)
[515.644345] [121/133] (508s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[515.761379] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[515.866872] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.106s)
[515.960208] [122/133] (508s left) gem_userptr_blits (input-checking)
[516.033097] Starting subtest: input-checking
[516.034913] Subtest input-checking: SUCCESS (0.000s)
[516.152120] [123/133] (507s left) gem_exec_reloc (basic-wc-cpu-active)
[516.210752] Subtest basic-wc-cpu-active: SKIP (0.000s)
[516.284625] [124/133] (507s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[516.413736] Starting subtest: basic-flip-before-cursor-varying-size
[517.387697] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.977s)
[518.243622] [125/133] (505s left) gem_exec_reloc (basic-wc)
[518.293933] Subtest basic-wc: SKIP (0.000s)
[518.371569] [126/133] (505s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[518.475069] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[518.477367] Starting dynamic subtest: pipe-A-eDP-1
[518.574177] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[518.591396] Starting dynamic subtest: pipe-B-eDP-1
[519.711052] Dynamic subtest pipe-B-eDP-1: SKIP (1.134s)
[519.715481] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.238s)
[520.902846] [127/133] (503s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[521.002400] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[525.426023] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.427s)
[526.620439] [128/133] (497s left) gem_mmap (big-bo)
[526.692624] Subtest big-bo: SKIP (0.000s)
[526.770550] [129/133] (497s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[526.897478] Starting subtest: cursor-vs-flip-toggle
[557.962181] Subtest cursor-vs-flip-toggle: SUCCESS (31.062s)
[558.831329] [130/133] (465s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[558.928701] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[563.755638] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.830s)
[564.941067] [131/133] (459s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[565.050421] Starting subtest: plane-all-modeset-transition-internal-panels
[565.052602] Starting dynamic subtest: pipe-A-eDP-1
[566.374446] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.323s)
[566.654852] Starting dynamic subtest: pipe-B-eDP-1
[567.701097] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.054s)
[567.970264] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.918s)
[568.805913] [132/133] (455s left) kms_big_fb (linear-64bpp-rotate-270)
[568.915347] Starting subtest: linear-64bpp-rotate-270
[568.938103] Subtest linear-64bpp-rotate-270: SKIP (0.022s)
[569.051849] [133/133] (455s left) gem_exec_suspend (basic-s4-devices)
[569.119883] Starting subtest: basic-S4-devices
[569.122388] Starting dynamic subtest: smem
[577.569077] Dynamic subtest smem: FAIL (8.379s)
[577.572136] Subtest basic-S4-devices: FAIL (8.381s)
[577.765100] Closing watchdogs
results: parsing output: 0/ for test: kms_chamelium_edid
results: parsing output: 1/ for test: kms_hdr
results: parsing output: 2/ for test: gem_mmap_offset
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_psr
results: parsing output: 6/ for test: gem_render_tiled_blits
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: fbdev
results: parsing output: 11/ for test: kms_cursor_crc
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: gen9_exec_parse
results: parsing output: 14/ for test: gem_ctx_engines
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: i915_hangman
results: parsing output: 18/ for test: kms_async_flips
results: parsing output: 19/ for test: gem_linear_blits
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: kms_properties
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_plane_alpha_blend
results: parsing output: 25/ for test: prime_mmap
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_content_protection
results: parsing output: 29/ for test: gem_exec_fence
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: perf_pmu
results: parsing output: 34/ for test: dmabuf_sync_file
results: parsing output: 35/ for test: kms_atomic_transition
results: parsing output: 36/ for test: gem_lmem_swapping
results: parsing output: 37/ for test: gem_exec_parallel
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: kms_dp_link_training
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_pxp
results: parsing output: 43/ for test: prime_self_import
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: gem_exec_reloc
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: i915_selftest
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: syncobj_wait
results: parsing output: 53/ for test: kms_color_pipeline
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: sysfs_heartbeat_interval
results: parsing output: 56/ for test: gem_exec_params
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_mmap
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: gem_exec_reloc
results: parsing output: 62/ for test: kms_addfb_basic
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: kms_plane
results: parsing output: 66/ for test: kms_chamelium_hpd
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_exec_store
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: kms_invalid_mode
results: parsing output: 72/ for test: kms_plane_scaling
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: core_auth
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: kms_plane_cursor
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_ctx_sseu
results: parsing output: 79/ for test: kms_busy
results: parsing output: 80/ for test: gem_softpin
results: parsing output: 81/ for test: vgem_slow
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_ctx_param
results: parsing output: 85/ for test: syncobj_basic
results: parsing output: 86/ for test: perf_pmu
results: parsing output: 87/ for test: i915_drm_fdinfo
results: parsing output: 88/ for test: gem_pxp
results: parsing output: 89/ for test: gem_vm_create
results: parsing output: 90/ for test: gem_set_tiling_vs_blt
results: parsing output: 91/ for test: gem_userptr_blits
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: kms_vblank
results: parsing output: 97/ for test: kms_feature_discovery
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: kms_sequence
results: parsing output: 104/ for test: syncobj_basic
results: parsing output: 105/ for test: prime_vgem
results: parsing output: 106/ for test: kms_prop_blob
results: parsing output: 107/ for test: prime_vgem
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: gem_flink_basic
results: parsing output: 111/ for test: core_debugfs
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_lease
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: kms_async_flips
results: parsing output: 119/ for test: gem_exec_big
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: gem_userptr_blits
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: kms_psr2_sf
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_mmap
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_atomic_transition
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: gem_exec_suspend
Done.