[61.432947] Initializing watchdogs
[61.433105]   /dev/watchdog0
[61.448658] [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]
[61.452989] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.455662] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.459207] [001/156] (960s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[61.578541] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[61.590999] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[61.673907] [002/156] (960s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-render)
[61.782125] Starting subtest: hdr-rgb101010-draw-render
[61.786168] Subtest hdr-rgb101010-draw-render: SKIP (0.000s)
[61.879882] [003/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-render)
[61.987069] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-render
[61.991566] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[62.075319] [004/156] (959s left) gem_exec_whisper (basic-queues-all)
[62.137912] Starting subtest: basic-queues-all
[73.259071] Subtest basic-queues-all: SUCCESS (11.118s)
[73.474743] [005/156] (948s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[73.632053] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[81.311454] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.684s)
[81.441191] [006/156] (940s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[81.539683] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[81.544091] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[81.638251] [007/156] (940s left) prime_vgem (fence-flip-hang)
[81.733048] Subtest fence-flip-hang: SKIP (0.000s)
[81.823140] [FACT prime_vgem (fence-flip-hang)] new: kernel.kmod_is_loaded.vgem: true
[81.826459] [008/156] (940s left) gem_mmap_offset (bad-extensions)
[81.879366] Starting subtest: bad-extensions
[81.881680] Subtest bad-extensions: SUCCESS (0.000s)
[81.963937] [009/156] (940s left) kms_content_protection (mei-interface)
[82.107899] Starting subtest: mei-interface
[82.112860] Subtest mei-interface: SKIP (0.001s)
[82.230088] [010/156] (940s left) kms_getfb (getfb2-into-addfb2)
[82.317322] Starting subtest: getfb2-into-addfb2
[82.319131] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[82.423380] [011/156] (939s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[82.513777] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[82.517240] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[82.616698] [012/156] (939s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[82.711786] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[90.025062] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.317s)
[90.179651] [013/156] (932s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[90.267874] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[90.270218] Starting dynamic subtest: pipe-A-eDP-1
[90.330407] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[90.341398] Starting dynamic subtest: pipe-B-eDP-1
[90.400941] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[90.410312] Starting dynamic subtest: pipe-C-eDP-1
[90.467248] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[90.476614] Starting dynamic subtest: pipe-D-eDP-1
[90.533608] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[90.537962] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.007s)
[92.611711] [014/156] (929s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[92.716081] Starting subtest: fbcpsr-shrfb-scaledprimary
[98.487241] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.776s)
[98.632356] [015/156] (923s left) kms_plane (pixel-format-linear-modifier)
[98.738227] Starting subtest: pixel-format-linear-modifier
[98.764259] Starting dynamic subtest: pipe-A-plane-0
[99.352452] Dynamic subtest pipe-A-plane-0: SUCCESS (0.589s)
[99.362061] Starting dynamic subtest: pipe-A-plane-3
[99.797439] Dynamic subtest pipe-A-plane-3: SUCCESS (0.444s)
[99.809456] Starting dynamic subtest: pipe-A-plane-5
[100.018852] Dynamic subtest pipe-A-plane-5: SUCCESS (0.222s)
[101.116817] Starting dynamic subtest: pipe-B-plane-0
[101.687817] Dynamic subtest pipe-B-plane-0: SUCCESS (0.578s)
[101.698204] Starting dynamic subtest: pipe-B-plane-3
[102.143875] Dynamic subtest pipe-B-plane-3: SUCCESS (0.454s)
[102.154202] Starting dynamic subtest: pipe-B-plane-5
[102.353607] Dynamic subtest pipe-B-plane-5: SUCCESS (0.211s)
[102.670246] Subtest pixel-format-linear-modifier: SUCCESS (3.932s)
[103.500052] [016/156] (919s left) gem_fence_thrash (bo-write-verify-x)
[103.534332] Starting subtest: bo-write-verify-x
[103.552672] Subtest bo-write-verify-x: SKIP (0.016s)
[103.608682] [017/156] (919s left) kms_psr (pr-cursor-mmap-cpu)
[103.694913] Starting subtest: pr-cursor-mmap-cpu
[103.699162] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[103.768065] [018/156] (918s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[103.857295] Starting subtest: plane-upscale-20x20-with-pixel-format
[103.859622] Starting dynamic subtest: pipe-A
[104.904290] Dynamic subtest pipe-A: SUCCESS (1.049s)
[104.914780] Starting dynamic subtest: pipe-B
[106.991576] Dynamic subtest pipe-B: SUCCESS (2.087s)
[107.002707] Starting dynamic subtest: pipe-C
[108.181712] Dynamic subtest pipe-C: SUCCESS (1.189s)
[108.191660] Starting dynamic subtest: pipe-D
[109.410445] Dynamic subtest pipe-D: SUCCESS (1.228s)
[109.412770] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.556s)
[110.606293] [019/156] (912s left) perf_pmu (rc6)
[110.645808] Starting subtest: rc6
[110.648208] Starting dynamic subtest: gt0
[117.009838] Dynamic subtest gt0: SUCCESS (6.362s)
[117.023301] Starting dynamic subtest: runtime-pm-gt0
[124.746896] Dynamic subtest runtime-pm-gt0: SUCCESS (7.732s)
[124.755906] Starting dynamic subtest: runtime-pm-long-gt0
[137.240227] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.495s)
[137.252265] Starting dynamic subtest: other-idle-gt0
[143.583680] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[143.595597] Starting dynamic subtest: gt1
[149.924638] Dynamic subtest gt1: SUCCESS (6.339s)
[149.936047] Starting dynamic subtest: runtime-pm-gt1
[157.360425] Dynamic subtest runtime-pm-gt1: SUCCESS (7.434s)
[157.372828] Starting dynamic subtest: runtime-pm-long-gt1
[169.785881] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.423s)
[169.798102] Starting dynamic subtest: other-idle-gt1
[176.128645] Dynamic subtest other-idle-gt1: SUCCESS (6.341s)
[176.131900] Subtest rc6: SUCCESS (65.482s)
[177.290006] [020/156] (845s left) syncobj_wait (wait-for-submit-complex)
[177.351601] Starting subtest: wait-for-submit-complex
[177.412030] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[177.468115] [021/156] (845s left) i915_module_load (resize-bar)
[177.503684] Starting subtest: resize-bar
[177.509543] Subtest resize-bar: SKIP (0.006s)
[177.549786] [022/156] (845s left) kms_plane_cursor (overlay)
[177.650214] Starting subtest: overlay
[177.653608] Starting dynamic subtest: pipe-A-eDP-1-size-64
[180.205899] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.557s)
[180.268179] Starting dynamic subtest: pipe-A-eDP-1-size-128
[182.807539] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.550s)
[182.866068] Starting dynamic subtest: pipe-A-eDP-1-size-256
[185.385991] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.527s)
[185.745620] Starting dynamic subtest: pipe-B-eDP-1-size-64
[189.033433] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.298s)
[189.094420] Starting dynamic subtest: pipe-B-eDP-1-size-128
[191.679654] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.594s)
[191.740689] Starting dynamic subtest: pipe-B-eDP-1-size-256
[194.237285] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.505s)
[194.586745] Starting dynamic subtest: pipe-C-eDP-1-size-64
[197.462828] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.888s)
[197.502477] Starting dynamic subtest: pipe-C-eDP-1-size-128
[199.685179] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.192s)
[199.722477] Starting dynamic subtest: pipe-C-eDP-1-size-256
[201.918569] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.203s)
[202.247238] Starting dynamic subtest: pipe-D-eDP-1-size-64
[205.166572] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.930s)
[205.204505] Starting dynamic subtest: pipe-D-eDP-1-size-128
[207.355816] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.159s)
[207.394350] Starting dynamic subtest: pipe-D-eDP-1-size-256
[209.567087] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.181s)
[209.888621] Subtest overlay: SUCCESS (32.238s)
[210.714526] [023/156] (812s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[210.819741] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[210.823796] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[210.894097] [024/156] (812s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[210.987883] Starting subtest: modeset-transition-nonblocking-fencing
[210.989830] Starting dynamic subtest: 1x-outputs
[222.405541] Dynamic subtest 1x-outputs: SUCCESS (11.417s)
[222.407856] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.419s)
[223.261585] [025/156] (799s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-render)
[223.363067] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-render
[223.367258] Subtest fbchdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[223.431935] [026/156] (799s left) sw_sync (sync_busy_fork)
[223.466037] Starting subtest: sync_busy_fork
[224.479038] Subtest sync_busy_fork: SUCCESS (1.012s)
[224.522598] [027/156] (798s left) gem_exec_balancer (nohangcheck)
[224.785894] Starting subtest: nohangcheck
[225.733602] Subtest nohangcheck: SUCCESS (0.947s)
[225.837548] [028/156] (797s left) gem_mmap (bad-offset)
[225.891670] Subtest bad-offset: SKIP (0.000s)
[225.960330] [029/156] (797s left) dumb_buffer (map-valid)
[226.010461] Starting subtest: map-valid
[226.012736] Subtest map-valid: SUCCESS (0.000s)
[226.093571] [030/156] (797s left) gem_workarounds (reset)
[226.160748] Starting subtest: reset
[226.370625] Subtest reset: SUCCESS (0.210s)
[226.471247] [031/156] (796s left) kms_scaling_modes (scaling-mode-full-aspect)
[226.573253] Starting subtest: scaling-mode-full-aspect
[226.575185] Starting dynamic subtest: pipe-A-eDP-1
[226.666956] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[226.676900] Starting dynamic subtest: pipe-B-eDP-1
[227.816825] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.148s)
[227.826105] Starting dynamic subtest: pipe-C-eDP-1
[229.026826] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.208s)
[229.040345] Starting dynamic subtest: pipe-D-eDP-1
[230.157148] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[230.159513] Subtest scaling-mode-full-aspect: SUCCESS (3.586s)
[231.301451] [032/156] (791s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[231.403362] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[231.407683] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[231.477630] [033/156] (791s left) gem_exec_fence (submit-chain)
[231.652854] Starting subtest: submit-chain
[231.673951] Subtest submit-chain: SUCCESS (0.022s)
[233.349431] [034/156] (789s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-indfb-plflip-blt)
[233.451358] Starting subtest: hdr-2p-scndscrn-indfb-plflip-blt
[233.455342] Subtest hdr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[233.520385] [035/156] (789s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[233.639948] Starting subtest: cursorA-vs-flipB-atomic
[233.644607] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[233.760574] [036/156] (789s left) kms_dp_link_training (uhbr-mst)
[233.853236] Starting subtest: uhbr-mst
[233.862325] Subtest uhbr-mst: SKIP (0.000s)
[233.902443] [037/156] (789s left) gem_lmem_swapping (massive-random)
[233.948425] Subtest massive-random: SKIP (0.000s)
[233.998733] [038/156] (789s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[234.110380] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[234.114902] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[234.189543] [039/156] (789s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[234.283418] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[234.287687] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[234.335152] [040/156] (789s left) kms_flip (flip-vs-modeset-vs-hang)
[234.420313] Starting subtest: flip-vs-modeset-vs-hang
[234.422179] Starting dynamic subtest: A-eDP1
[285.793581] Dynamic subtest A-eDP1: SUCCESS (51.372s)
[285.805440] Starting dynamic subtest: D-eDP1
[335.777439] Dynamic subtest D-eDP1: SUCCESS (49.974s)
[335.780047] Subtest flip-vs-modeset-vs-hang: SUCCESS (101.352s)
[336.149952] [041/156] (687s left) gem_exec_fence (syncobj-wait)
[336.568818] Starting subtest: syncobj-wait
[338.318041] Subtest syncobj-wait: SUCCESS (1.748s)
[338.629092] [042/156] (685s left) syncobj_timeline (etime-multi-wait-all-submitted)
[338.694644] Starting subtest: etime-multi-wait-all-submitted
[338.802253] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[338.858492] [043/156] (684s left) kms_big_fb (linear-16bpp-rotate-90)
[338.957198] Starting subtest: linear-16bpp-rotate-90
[338.977073] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[339.071455] [044/156] (684s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[339.168084] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[339.172329] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[340.280631] [045/156] (683s left) i915_suspend (fence-restore-tiled2untiled)
[340.329532] Starting subtest: fence-restore-tiled2untiled
[340.334083] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[340.401331] [046/156] (683s left) gem_exec_reloc (basic-gtt-wc)
[340.454960] Subtest basic-gtt-wc: SKIP (0.000s)
[340.530313] [047/156] (683s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[340.631897] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[344.620051] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (3.993s)
[345.793646] [048/156] (678s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[345.900751] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[345.904962] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[345.978184] [049/156] (677s left) kms_force_connector_basic (prune-stale-modes)
[346.048203] Starting subtest: prune-stale-modes
[346.488126] Subtest prune-stale-modes: SUCCESS (0.443s)
[346.608295] [050/156] (677s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[346.712598] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite
[346.716748] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[346.797659] [051/156] (677s left) i915_drm_fdinfo (context-close-stress)
[346.840054] Starting subtest: context-close-stress
[357.591028] Subtest context-close-stress: SUCCESS (10.749s)
[357.646419] [052/156] (666s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt)
[357.899842] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt
[357.903968] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[357.976646] [053/156] (666s left) gem_ringfill (engines-basic)
[363.273752] Starting subtest: engines-basic
[363.277242] Starting dynamic subtest: rcs0
[363.340282] Dynamic subtest rcs0: SUCCESS (0.067s)
[363.353020] Starting dynamic subtest: bcs0
[363.385543] Dynamic subtest bcs0: SUCCESS (0.043s)
[363.400437] Starting dynamic subtest: vcs0
[363.429030] Dynamic subtest vcs0: SUCCESS (0.042s)
[363.441058] Starting dynamic subtest: vcs1
[363.471988] Dynamic subtest vcs1: SUCCESS (0.041s)
[363.485783] Starting dynamic subtest: vecs0
[363.512792] Dynamic subtest vecs0: SUCCESS (0.040s)
[363.525290] Starting dynamic subtest: ccs0
[363.559087] Dynamic subtest ccs0: SUCCESS (0.044s)
[363.562084] Subtest engines-basic: SUCCESS (0.288s)
[363.612568] [054/156] (660s left) prime_busy (hang)
[363.674821] Starting subtest: hang
[363.677196] Starting dynamic subtest: rcs0
[389.792339] Dynamic subtest rcs0: SUCCESS (26.111s)
[389.803356] Starting dynamic subtest: bcs0
[396.952378] Dynamic subtest bcs0: SUCCESS (7.160s)
[396.961202] Starting dynamic subtest: vcs0
[403.987724] Dynamic subtest vcs0: SUCCESS (7.034s)
[403.996687] Starting dynamic subtest: vcs1
[410.960586] Dynamic subtest vcs1: SUCCESS (6.974s)
[410.969841] Starting dynamic subtest: vecs0
[417.936877] Dynamic subtest vecs0: SUCCESS (6.972s)
[417.945756] Starting dynamic subtest: ccs0
[431.843996] Dynamic subtest ccs0: SUCCESS (13.905s)
[431.846516] Subtest hang: SUCCESS (68.164s)
[431.952809] [055/156] (592s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[432.057834] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[433.169337] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.112s)
[433.280796] [056/156] (590s left) gem_exec_reloc (basic-write-read-noreloc)
[433.338319] Subtest basic-write-read-noreloc: SKIP (0.000s)
[433.424224] [057/156] (590s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-gtt)
[433.517616] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-gtt
[433.521217] Subtest fbcpsrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[433.594749] [058/156] (590s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[433.691902] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[433.695796] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[434.808829] [059/156] (589s left) sysfs_heartbeat_interval (precise)
[434.871453] Starting subtest: precise
[434.873833] Starting dynamic subtest: vecs0
[442.301989] Dynamic subtest vecs0: SUCCESS (7.427s)
[442.314042] Starting dynamic subtest: ccs0
[443.397855] Dynamic subtest ccs0: SUCCESS (1.094s)
[443.407470] Starting dynamic subtest: vcs0
[451.261627] Dynamic subtest vcs0: SUCCESS (7.860s)
[451.273211] Starting dynamic subtest: bcs0
[454.294728] Dynamic subtest bcs0: SUCCESS (3.032s)
[454.305290] Starting dynamic subtest: rcs0
[455.381239] Dynamic subtest rcs0: SUCCESS (1.083s)
[455.391611] Starting dynamic subtest: vcs1
[462.271929] Dynamic subtest vcs1: SUCCESS (6.889s)
[462.274640] Subtest precise: SUCCESS (27.399s)
[462.324268] [060/156] (562s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-onoff)
[462.420615] Starting subtest: hdr-1p-primscrn-cur-indfb-onoff
[462.424676] Subtest hdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[462.495049] [061/156] (561s left) kms_plane (plane-position-covered)
[462.593328] Starting subtest: plane-position-covered
[463.127896] Starting dynamic subtest: pipe-A-plane-1
[464.463074] Dynamic subtest pipe-A-plane-1: SUCCESS (1.336s)
[464.473900] Starting dynamic subtest: pipe-A-plane-2
[465.761117] Dynamic subtest pipe-A-plane-2: SUCCESS (1.300s)
[465.770950] Starting dynamic subtest: pipe-A-plane-3
[467.020079] Dynamic subtest pipe-A-plane-3: SUCCESS (1.253s)
[467.031048] Starting dynamic subtest: pipe-A-plane-4
[468.235509] Dynamic subtest pipe-A-plane-4: SUCCESS (1.217s)
[468.246295] Starting dynamic subtest: pipe-A-plane-5
[468.262080] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[469.470134] Starting dynamic subtest: pipe-B-plane-1
[470.772940] Dynamic subtest pipe-B-plane-1: SUCCESS (1.312s)
[470.782913] Starting dynamic subtest: pipe-B-plane-2
[472.056529] Dynamic subtest pipe-B-plane-2: SUCCESS (1.281s)
[472.066387] Starting dynamic subtest: pipe-B-plane-3
[473.367245] Dynamic subtest pipe-B-plane-3: SUCCESS (1.310s)
[473.377099] Starting dynamic subtest: pipe-B-plane-4
[474.635979] Dynamic subtest pipe-B-plane-4: SUCCESS (1.270s)
[474.646654] Starting dynamic subtest: pipe-B-plane-5
[474.669762] Dynamic subtest pipe-B-plane-5: SUCCESS (0.031s)
[474.672264] Subtest plane-position-covered: SUCCESS (12.076s)
[475.495216] [062/156] (549s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[475.596676] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[475.600624] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[475.686765] [063/156] (548s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-fullscreen)
[475.775823] Starting subtest: psrhdr-1p-primscrn-spr-indfb-fullscreen
[475.780048] Subtest psrhdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[475.856340] [064/156] (548s left) kms_chamelium_frames (hdmi-aspect-ratio)
[476.238513] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[477.095459] [065/156] (547s left) kms_color (ctm-0-50)
[477.197489] Starting subtest: ctm-0-50
[477.199646] Starting dynamic subtest: pipe-A-eDP-1
[480.679664] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[480.689022] Starting dynamic subtest: pipe-B-eDP-1
[484.859918] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.177s)
[484.870086] Starting dynamic subtest: pipe-C-eDP-1
[489.037559] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.179s)
[489.047704] Starting dynamic subtest: pipe-D-eDP-1
[493.122723] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.083s)
[493.125062] Subtest ctm-0-50: SUCCESS (15.926s)
[493.996477] [066/156] (530s left) kms_feature_discovery (dp-mst)
[494.092206] Starting subtest: dp-mst
[494.096736] Subtest dp-mst: SKIP (0.000s)
[494.143212] [067/156] (530s left) kms_cursor_crc (cursor-onscreen-512x170)
[494.454214] Starting subtest: cursor-onscreen-512x170
[494.459547] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[494.577271] [068/156] (530s left) kms_addfb_basic (unused-offsets)
[494.620735] Starting subtest: unused-offsets
[494.622725] Subtest unused-offsets: SUCCESS (0.000s)
[494.725739] [069/156] (530s left) dmabuf_sync_file (import-basic)
[494.763661] Starting subtest: import-basic
[494.765995] Subtest import-basic: SUCCESS (0.001s)
[494.804553] [070/156] (530s left) gem_userptr_blits (sync-overlap)
[494.868752] Starting subtest: sync-overlap
[494.871106] Subtest sync-overlap: SUCCESS (0.000s)
[494.979870] [071/156] (529s left) gem_ctx_persistence (engines-mixed)
[495.039071] Starting subtest: engines-mixed
[495.040942] Starting dynamic subtest: rcs0
[495.110433] Dynamic subtest rcs0: SUCCESS (0.074s)
[495.121698] Starting dynamic subtest: bcs0
[495.167153] Dynamic subtest bcs0: SUCCESS (0.055s)
[495.179805] Starting dynamic subtest: vcs0
[495.222328] Dynamic subtest vcs0: SUCCESS (0.053s)
[495.234717] Starting dynamic subtest: vcs1
[495.284651] Dynamic subtest vcs1: SUCCESS (0.057s)
[495.293739] Starting dynamic subtest: vecs0
[495.349538] Dynamic subtest vecs0: SUCCESS (0.065s)
[495.361974] Starting dynamic subtest: ccs0
[495.411654] Dynamic subtest ccs0: SUCCESS (0.060s)
[495.414198] Subtest engines-mixed: SUCCESS (0.376s)
[495.468146] [072/156] (529s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[495.857953] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[496.696080] [073/156] (528s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[496.764054] Starting subtest: invalid-bsd2-flag-on-blt
[496.766495] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[496.839253] [074/156] (528s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[496.949695] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[496.953799] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[497.041749] [075/156] (527s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-pri-indfb-multidraw)
[497.137216] Starting subtest: fbcpsrhdr-2p-pri-indfb-multidraw
[497.141685] Subtest fbcpsrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[497.224581] [076/156] (527s left) kms_addfb_basic (bad-pitch-128)
[497.276228] Starting subtest: bad-pitch-128
[497.278694] Subtest bad-pitch-128: SUCCESS (0.000s)
[497.384862] [077/156] (527s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[497.487612] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-pwrite
[497.491901] Subtest psrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[497.561891] [078/156] (527s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[497.671197] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[504.639249] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.974s)
[504.754863] [079/156] (520s left) api_intel_allocator (two-level-inception-interruptible)
[504.811425] Starting subtest: two-level-inception-interruptible
[510.218295] Subtest two-level-inception-interruptible: SUCCESS (5.407s)
[510.327711] [080/156] (514s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[510.441317] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[510.445240] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[510.530686] [081/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-render)
[510.630076] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-render
[510.634154] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[510.734776] [082/156] (514s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[510.845845] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[510.850265] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[510.951208] [083/156] (514s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[511.056607] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[511.060911] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[511.105212] [084/156] (514s left) kms_lease (lease-unleased-connector)
[511.203846] Starting subtest: lease-unleased-connector
[511.206063] Starting dynamic subtest: pipe-A-eDP-1
[511.211046] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[511.221338] Starting dynamic subtest: pipe-B-eDP-1
[511.223493] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[511.234094] Starting dynamic subtest: pipe-C-eDP-1
[511.235956] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[511.245513] Starting dynamic subtest: pipe-D-eDP-1
[511.247293] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[511.249059] Subtest lease-unleased-connector: SUCCESS (0.035s)
[511.338799] [085/156] (514s left) gem_exec_reloc (basic-gtt-cpu)
[511.402189] Subtest basic-gtt-cpu: SKIP (0.000s)
[511.496353] [086/156] (514s left) kms_invalid_mode (bad-htotal)
[511.597948] Starting subtest: bad-htotal
[511.599938] Starting dynamic subtest: pipe-A-eDP-1
[511.881965] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[511.892686] Starting dynamic subtest: pipe-B-eDP-1
[511.894981] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[511.904075] Starting dynamic subtest: pipe-C-eDP-1
[511.905920] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[511.914853] Starting dynamic subtest: pipe-D-eDP-1
[511.916710] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[511.918719] Subtest bad-htotal: SUCCESS (0.289s)
[512.694957] [087/156] (512s left) gem_exec_schedule (noreorder)
[512.874376] Starting subtest: noreorder
[512.876960] Starting dynamic subtest: rcs0
[512.964772] Dynamic subtest rcs0: SUCCESS (0.090s)
[512.976438] Starting dynamic subtest: vcs0
[514.299862] Dynamic subtest vcs0: SUCCESS (1.332s)
[514.312120] Starting dynamic subtest: vcs1
[516.295378] Dynamic subtest vcs1: SUCCESS (1.993s)
[516.307592] Starting dynamic subtest: vecs0
[518.275068] Dynamic subtest vecs0: SUCCESS (1.977s)
[518.287493] Starting dynamic subtest: ccs0
[518.337996] Dynamic subtest ccs0: SUCCESS (0.062s)
[518.340625] Subtest noreorder: SUCCESS (5.465s)
[518.500920] [088/156] (507s left) gem_mmap_wc (write)
[518.557868] Subtest write: SKIP (0.000s)
[518.646414] [089/156] (507s left) gem_exec_reloc (basic-cpu-read-active)
[518.706263] Subtest basic-cpu-read-active: SKIP (0.000s)
[518.778276] [090/156] (506s left) kms_frontbuffer_tracking (fbchdr-2p-shrfb-fliptrack-mmap-gtt)
[518.892708] Starting subtest: fbchdr-2p-shrfb-fliptrack-mmap-gtt
[518.897194] Subtest fbchdr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[518.978828] [091/156] (506s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[519.084621] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[519.797631] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.715s)
[519.950030] [092/156] (505s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[520.057545] Starting subtest: 4-tiled-16bpp-rotate-90
[520.077437] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[520.171819] [093/156] (505s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-render)
[520.280999] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-render
[520.285187] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[520.343350] [094/156] (505s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-cpu)
[520.448632] Starting subtest: psrhdr-rgb101010-draw-mmap-cpu
[520.452809] Subtest psrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[520.522955] [095/156] (505s left) api_intel_bb (object-noreloc-keep-cache-simple)
[520.579369] Starting subtest: object-noreloc-keep-cache-simple
[520.582457] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.006s)
[520.654852] [096/156] (505s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[520.748169] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[520.751532] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[520.831052] [097/156] (505s left) i915_query (query-topology-known-pci-ids)
[520.881211] Starting subtest: query-topology-known-pci-ids
[520.884576] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[520.965353] [098/156] (505s left) gem_pxp (hw-rejects-pxp-context)
[521.083413] Starting subtest: hw-rejects-pxp-context
[521.087847] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[521.168935] [099/156] (504s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[521.259253] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[521.261058] Starting dynamic subtest: pipe-A-eDP-1
[527.811580] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.553s)
[527.821488] Starting dynamic subtest: pipe-B-eDP-1
[534.852078] Dynamic subtest pipe-B-eDP-1: SUCCESS (7.039s)
[534.854576] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (13.595s)
[535.687686] [100/156] (490s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[535.779332] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[535.783318] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[535.827319] [101/156] (490s left) gem_exec_alignment (forked)
[535.897657] Starting subtest: forked
[576.375675] Subtest forked: SUCCESS (40.473s)
[576.432073] [102/156] (449s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[576.584975] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[576.589165] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[576.651442] [103/156] (449s left) gem_ctx_engines (invalid-engines)
[576.721925] Starting subtest: invalid-engines
[576.730294] Subtest invalid-engines: SUCCESS (0.008s)
[576.932352] [104/156] (449s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[577.049558] Starting subtest: cursorB-vs-flipA-varying-size
[577.054297] Subtest cursorB-vs-flipA-varying-size: SKIP (0.002s)
[577.171079] [105/156] (449s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[577.277273] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[577.279614] Starting dynamic subtest: pipe-A-eDP-1
[577.299638] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[577.314789] Starting dynamic subtest: pipe-B-eDP-1
[577.334081] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[577.344545] Starting dynamic subtest: pipe-C-eDP-1
[577.363921] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[577.373820] Starting dynamic subtest: pipe-D-eDP-1
[577.392659] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[577.394549] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.008s)
[579.495519] [106/156] (446s left) kms_pm_rpm (basic-pci-d3-state)
[581.464292] Starting subtest: basic-pci-d3-state
[582.962909] Subtest basic-pci-d3-state: SUCCESS (1.500s)
[583.059831] [107/156] (443s left) perf_pmu (busy-check-all)
[583.101729] Starting subtest: busy-check-all
[583.103945] Starting dynamic subtest: rcs0
[583.652152] Dynamic subtest rcs0: SUCCESS (0.550s)
[583.663917] Starting dynamic subtest: bcs0
[584.201687] Dynamic subtest bcs0: SUCCESS (0.547s)
[584.214506] Starting dynamic subtest: vcs0
[585.286723] Dynamic subtest vcs0: SUCCESS (1.081s)
[585.300817] Starting dynamic subtest: vcs1
[587.270417] Dynamic subtest vcs1: SUCCESS (1.980s)
[587.283930] Starting dynamic subtest: vecs0
[589.318375] Dynamic subtest vecs0: SUCCESS (2.045s)
[589.332156] Starting dynamic subtest: ccs0
[589.875227] Dynamic subtest ccs0: SUCCESS (0.555s)
[589.877696] Subtest busy-check-all: SUCCESS (6.774s)
[589.972979] [108/156] (436s left) gem_sync (basic-store-all)
[590.044657] Starting subtest: basic-store-all
[592.939394] Subtest basic-store-all: SUCCESS (2.906s)
[593.105641] [109/156] (433s left) gem_ctx_param (set-priority-invalid-size)
[593.160282] Starting subtest: set-priority-invalid-size
[593.162689] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[593.213556] [110/156] (433s left) kms_psr (psr-cursor-blt)
[593.306027] Starting subtest: psr-cursor-blt
[593.308289] Starting dynamic subtest: eDP-1
[595.071626] Dynamic subtest eDP-1: SUCCESS (1.764s)
[595.073817] Subtest psr-cursor-blt: SUCCESS (1.765s)
[595.886312] [111/156] (430s left) gem_exec_reloc (basic-write-read-active)
[595.941183] Subtest basic-write-read-active: SKIP (0.000s)
[596.023396] [112/156] (430s left) kms_chamelium_color (ctm-max)
[596.118185] Subtest ctm-max: SKIP (0.000s)
[596.171205] [113/156] (430s left) syncobj_wait (signal)
[596.224072] Starting subtest: signal
[596.226298] Subtest signal: SUCCESS (0.000s)
[596.272149] [114/156] (430s left) kms_vblank (ts-continuation-modeset-rpm)
[596.359187] Starting subtest: ts-continuation-modeset-rpm
[596.361321] Starting dynamic subtest: pipe-A-eDP-1
[598.872710] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.513s)
[598.888971] Starting dynamic subtest: pipe-D-eDP-1
[601.046860] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.171s)
[601.049244] Subtest ts-continuation-modeset-rpm: SUCCESS (4.687s)
[601.894810] [115/156] (424s left) gen9_exec_parse (secure-batches)
[601.959099] Subtest secure-batches: SKIP (0.000s)
[602.035787] [116/156] (424s left) gem_userptr_blits (mmap-offset-banned)
[602.097863] Starting subtest: mmap-offset-banned
[602.100077] Starting dynamic subtest: wb
[602.102899] Dynamic subtest wb: SUCCESS (0.000s)
[602.113358] Starting dynamic subtest: wc
[602.115220] Dynamic subtest wc: SUCCESS (0.000s)
[602.124546] Starting dynamic subtest: uc
[602.126445] Dynamic subtest uc: SUCCESS (0.000s)
[602.128311] Subtest mmap-offset-banned: SUCCESS (0.002s)
[602.197584] [117/156] (424s left) kms_async_flips (alternate-sync-async-flip)
[602.288066] Starting subtest: alternate-sync-async-flip
[602.659822] Starting dynamic subtest: pipe-A-eDP-1
[605.462866] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.803s)
[605.846031] Starting dynamic subtest: pipe-B-eDP-1
[608.665338] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.831s)
[609.050697] Starting dynamic subtest: pipe-C-eDP-1
[611.872802] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.831s)
[612.245371] Starting dynamic subtest: pipe-D-eDP-1
[615.061957] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.824s)
[615.063893] Subtest alternate-sync-async-flip: SUCCESS (12.777s)
[616.283767] [118/156] (410s left) gem_exec_fence (expired-history)
[616.753101] Starting subtest: expired-history
[624.136308] Subtest expired-history: SUCCESS (7.383s)
[624.636335] [119/156] (402s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[624.683937] Starting subtest: multi-wait-for-submit-available-submitted
[624.686745] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.001s)
[624.762273] [120/156] (402s left) kms_pm_rpm (package-g7)
[626.505520] Starting subtest: package-g7
[626.510761] Subtest package-g7: SKIP (0.000s)
[626.999958] [121/156] (400s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[627.066586] Subtest writes-after-reads-snoop: SKIP (0.000s)
[627.135460] [122/156] (399s left) kms_flip (2x-wf_vblank-ts-check)
[627.227269] Starting subtest: 2x-wf_vblank-ts-check
[627.239237] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[627.297624] [123/156] (399s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[627.350435] Starting subtest: single-wait-for-submit-available-submitted
[627.352737] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[627.423188] [124/156] (399s left) gem_softpin (evict-snoop)
[627.481456] Starting subtest: evict-snoop
[627.485673] Subtest evict-snoop: SKIP (0.000s)
[627.568100] [125/156] (399s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[627.670331] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[627.674417] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[627.757518] [126/156] (399s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[627.874511] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[628.837591] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.961s)
[629.685965] [127/156] (397s left) gem_pwrite (basic-exhaustion)
[629.737106] Subtest basic-exhaustion: SKIP (0.000s)
[629.803776] [128/156] (397s left) kms_selftest (drm_plane_helper)
[629.848031] Starting subtest: drm_plane_helper
[629.870630] Starting dynamic subtest: drm_test_check_plane_state
[629.965978] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.096s)
[629.976775] Starting dynamic subtest: drm_test_check_invalid_plane_state
[630.060512] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.094s)
[630.062865] Subtest drm_plane_helper: SUCCESS (0.212s)
[630.192234] [129/156] (397s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[630.295189] Starting subtest: y-tiled-64bpp-rotate-270
[630.299664] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[630.376825] [130/156] (397s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[630.474549] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[630.478955] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[630.532858] [131/156] (396s left) kms_cursor_legacy (forked-bo)
[630.647305] Starting subtest: forked-bo
[630.649175] Starting dynamic subtest: pipe-A
[636.105833] Dynamic subtest pipe-A: SUCCESS (5.454s)
[636.122034] Starting dynamic subtest: pipe-B
[641.581269] Dynamic subtest pipe-B: SUCCESS (5.473s)
[641.599739] Starting dynamic subtest: pipe-C
[647.052491] Dynamic subtest pipe-C: SUCCESS (5.466s)
[647.075597] Starting dynamic subtest: pipe-D
[652.519443] Dynamic subtest pipe-D: SUCCESS (5.466s)
[652.533650] Starting dynamic subtest: all-pipes
[658.030120] Dynamic subtest all-pipes: SUCCESS (5.507s)
[658.032768] Subtest forked-bo: SUCCESS (27.378s)
[658.156326] [132/156] (369s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt)
[658.258091] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt
[658.262168] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[658.321860] [133/156] (369s left) gem_exec_schedule (semaphore-user)
[658.395389] Starting subtest: semaphore-user
[658.447039] Subtest semaphore-user: SUCCESS (0.056s)
[660.311368] [134/156] (367s left) gem_fence_thrash (bo-write-verify-threaded-none)
[660.348331] Starting subtest: bo-write-verify-threaded-none
[660.362247] Subtest bo-write-verify-threaded-none: SKIP (0.014s)
[660.429230] [135/156] (367s left) syncobj_timeline (invalid-wait-illegal-handle)
[660.486205] Starting subtest: invalid-wait-illegal-handle
[660.488459] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[660.564653] [136/156] (367s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[660.676784] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[660.681144] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[660.756908] [137/156] (366s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[661.036685] Starting subtest: cursor-rapid-movement-256x85
[661.084154] Subtest cursor-rapid-movement-256x85: SKIP (0.043s)
[661.256239] [138/156] (366s left) gem_exec_flush (basic-wb-rw-before-default)
[661.318105] Starting subtest: basic-wb-rw-before-default
[666.776416] Subtest basic-wb-rw-before-default: SUCCESS (5.458s)
[666.945187] [139/156] (360s left) gem_partial_pwrite_pread (write-snoop)
[667.008393] Subtest write-snoop: SKIP (0.000s)
[667.098465] [140/156] (360s left) gem_basic (bad-close)
[667.162630] Starting subtest: bad-close
[667.167080] Subtest bad-close: SUCCESS (0.000s)
[667.253856] [141/156] (360s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[667.357796] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[667.362235] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[667.434043] [142/156] (360s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[667.547926] Starting subtest: fbc-indfb-scaledprimary
[670.386590] Subtest fbc-indfb-scaledprimary: SUCCESS (2.844s)
[671.593323] [143/156] (356s left) i915_pm_rpm (system-suspend-execbuf)
[673.425043] Starting subtest: system-suspend-execbuf
[731.062218] Subtest system-suspend-execbuf: SUCCESS (43.005s)
[731.434101] [144/156] (296s left) kms_flip (2x-blocking-wf_vblank)
[731.524669] Starting subtest: 2x-blocking-wf_vblank
[731.533704] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[731.612437] [145/156] (296s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[731.711829] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[731.716077] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[731.789827] [146/156] (296s left) perf_pmu (most-busy-check-all)
[731.828061] Starting subtest: most-busy-check-all
[731.830445] Starting dynamic subtest: rcs0
[733.911239] Dynamic subtest rcs0: SUCCESS (2.082s)
[733.931949] Starting dynamic subtest: bcs0
[735.895618] Dynamic subtest bcs0: SUCCESS (1.981s)
[735.909744] Starting dynamic subtest: vcs0
[737.943546] Dynamic subtest vcs0: SUCCESS (2.045s)
[737.958433] Starting dynamic subtest: vcs1
[739.863560] Dynamic subtest vcs1: SUCCESS (1.916s)
[739.877093] Starting dynamic subtest: vecs0
[741.911453] Dynamic subtest vecs0: SUCCESS (2.045s)
[741.925809] Starting dynamic subtest: ccs0
[743.895894] Dynamic subtest ccs0: SUCCESS (1.981s)
[743.898708] Subtest most-busy-check-all: SUCCESS (12.067s)
[743.978156] [147/156] (284s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[744.093716] Starting subtest: psr-rgb565-draw-mmap-gtt
[744.098073] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[744.172072] [148/156] (284s left) gem_ctx_persistence (hostile)
[744.231917] Starting subtest: hostile
[754.380982] Subtest hostile: SUCCESS (10.142s)
[754.481378] [149/156] (273s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[754.590666] Starting subtest: psr2-cursor-plane-update-sf
[754.592950] Starting dynamic subtest: pipe-A-eDP-1
[755.163649] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[755.177542] Starting dynamic subtest: pipe-B-eDP-1
[756.456257] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.290s)
[756.458213] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.869s)
[757.291590] [150/156] (270s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[757.391055] Starting subtest: fbc-psr2-sprite-mmap-cpu
[757.393234] Starting dynamic subtest: eDP-1
[757.397156] Dynamic subtest eDP-1: SKIP (0.000s)
[757.401174] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[757.444013] [151/156] (270s left) kms_psr (pr-primary-mmap-cpu)
[757.539122] Starting subtest: pr-primary-mmap-cpu
[757.543481] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[757.589842] [152/156] (270s left) kms_plane_multiple (tiling-none)
[757.688552] Starting subtest: tiling-none
[757.690902] Starting dynamic subtest: pipe-A-eDP-1
[761.544124] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.855s)
[761.555899] Starting dynamic subtest: pipe-B-eDP-1
[766.152096] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.606s)
[766.163964] Starting dynamic subtest: pipe-C-eDP-1
[770.734405] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.579s)
[770.746076] Starting dynamic subtest: pipe-D-eDP-1
[775.333598] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.596s)
[775.335952] Subtest tiling-none: SUCCESS (17.642s)
[776.194552] [153/156] (252s left) gem_tiled_wb
[776.313469] [154/156] (252s left) gem_spin_batch (spin-all)
[776.382548] Starting subtest: spin-all
[776.393076] Subtest spin-all: SUCCESS (0.011s)
[777.968417] [155/156] (250s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[778.025591] Starting subtest: etime-single-wait-all-available-unsubmitted
[778.131186] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.103s)
[778.184501] [156/156] (250s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[778.297650] Starting subtest: pipe-fbc-rte
[778.304981] Starting dynamic subtest: pipe-B-eDP-1
[783.744894] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.453s)
[783.752568] Subtest pipe-fbc-rte: SUCCESS (5.455s)
[785.996733] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_exec_whisper
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: prime_vgem
results: parsing output: 7/ for test: gem_mmap_offset
results: parsing output: 8/ for test: kms_content_protection
results: parsing output: 9/ for test: kms_getfb
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_plane
results: parsing output: 15/ for test: gem_fence_thrash
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: kms_plane_scaling
results: parsing output: 18/ for test: perf_pmu
results: parsing output: 19/ for test: syncobj_wait
results: parsing output: 20/ for test: i915_module_load
results: parsing output: 21/ for test: kms_plane_cursor
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_atomic_transition
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: sw_sync
results: parsing output: 26/ for test: gem_exec_balancer
results: parsing output: 27/ for test: gem_mmap
results: parsing output: 28/ for test: dumb_buffer
results: parsing output: 29/ for test: gem_workarounds
results: parsing output: 30/ for test: kms_scaling_modes
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_fence
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_cursor_legacy
results: parsing output: 35/ for test: kms_dp_link_training
results: parsing output: 36/ for test: gem_lmem_swapping
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: gem_exec_fence
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: i915_suspend
results: parsing output: 45/ for test: gem_exec_reloc
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_force_connector_basic
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: i915_drm_fdinfo
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_ringfill
results: parsing output: 53/ for test: prime_busy
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: sysfs_heartbeat_interval
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_plane
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_chamelium_frames
results: parsing output: 64/ for test: kms_color
results: parsing output: 65/ for test: kms_feature_discovery
results: parsing output: 66/ for test: kms_cursor_crc
results: parsing output: 67/ for test: kms_addfb_basic
results: parsing output: 68/ for test: dmabuf_sync_file
results: parsing output: 69/ for test: gem_userptr_blits
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_chamelium_hpd
results: parsing output: 72/ for test: gem_exec_params
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: api_intel_allocator
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: kms_lease
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: kms_invalid_mode
results: parsing output: 86/ for test: gem_exec_schedule
results: parsing output: 87/ for test: gem_mmap_wc
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: api_intel_bb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: i915_query
results: parsing output: 97/ for test: gem_pxp
results: parsing output: 98/ for test: kms_psr2_sf
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: gem_exec_alignment
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_ctx_engines
results: parsing output: 103/ for test: kms_cursor_legacy
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_pm_rpm
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: gem_sync
results: parsing output: 108/ for test: gem_ctx_param
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: kms_chamelium_color
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_vblank
results: parsing output: 114/ for test: gen9_exec_parse
results: parsing output: 115/ for test: gem_userptr_blits
results: parsing output: 116/ for test: kms_async_flips
results: parsing output: 117/ for test: gem_exec_fence
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: kms_pm_rpm
results: parsing output: 120/ for test: gem_partial_pwrite_pread
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: gem_softpin
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_cursor_legacy
results: parsing output: 126/ for test: gem_pwrite
results: parsing output: 127/ for test: kms_selftest
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_schedule
results: parsing output: 133/ for test: gem_fence_thrash
results: parsing output: 134/ for test: syncobj_timeline
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_cursor_crc
results: parsing output: 137/ for test: gem_exec_flush
results: parsing output: 138/ for test: gem_partial_pwrite_pread
results: parsing output: 139/ for test: gem_basic
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: i915_pm_rpm
results: parsing output: 143/ for test: kms_flip
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: perf_pmu
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: gem_ctx_persistence
results: parsing output: 148/ for test: kms_psr2_sf
results: parsing output: 149/ for test: kms_psr
results: parsing output: 150/ for test: kms_psr
results: parsing output: 151/ for test: kms_plane_multiple
results: parsing output: 152/ for test: gem_tiled_wb
results: parsing output: 153/ for test: gem_spin_batch
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.