[56.897881] Initializing watchdogs
[56.897926]   /dev/watchdog0
[56.912283] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[56.916075] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.918177] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.921593] [001/133] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[57.026301] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[57.028608] Starting dynamic subtest: pipe-A-eDP-1
[57.051658] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.065217] Starting dynamic subtest: pipe-B-eDP-1
[57.087421] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.096151] Starting dynamic subtest: pipe-C-eDP-1
[57.117466] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.124521] Starting dynamic subtest: pipe-D-eDP-1
[57.147166] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.151345] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[59.298572] [002/133] (958s left) perf_pmu (busy-double-start)
[59.335836] Starting subtest: busy-double-start
[59.338143] Starting dynamic subtest: rcs0
[62.921645] Dynamic subtest rcs0: SUCCESS (3.582s)
[62.930829] Starting dynamic subtest: bcs0
[66.498279] Dynamic subtest bcs0: SUCCESS (3.573s)
[66.508514] Starting dynamic subtest: vcs0
[70.357828] Dynamic subtest vcs0: SUCCESS (3.858s)
[70.368238] Starting dynamic subtest: vcs1
[74.408504] Dynamic subtest vcs1: SUCCESS (4.045s)
[74.417887] Starting dynamic subtest: vecs0
[78.364896] Dynamic subtest vecs0: SUCCESS (3.959s)
[78.375842] Starting dynamic subtest: ccs0
[82.008193] Dynamic subtest ccs0: FAIL (3.588s)
[82.010222] Subtest busy-double-start: FAIL (22.609s)
[82.081891] [003/133] (935s left) sw_sync (sync_merge)
[82.116622] Starting subtest: sync_merge
[82.118840] Subtest sync_merge: SUCCESS (0.000s)
[82.196799] [004/133] (935s left) gem_pread (self)
[82.257998] Subtest self: SKIP (0.000s)
[82.324620] [005/133] (935s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[82.441155] Starting subtest: flip-vs-cursor-crc-atomic
[83.157427] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.718s)
[84.037207] [006/133] (933s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[84.107071] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[84.109655] Starting dynamic subtest: pipe-A-eDP-1
[84.167500] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[84.477040] Starting dynamic subtest: pipe-B-eDP-1
[85.243568] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.774s)
[85.552036] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.445s)
[86.413821] [007/133] (931s left) gem_exec_gttfill (multigpu-basic)
[86.588244] Starting subtest: multigpu-basic
[101.962684] Subtest multigpu-basic: SUCCESS (15.372s)
[102.011943] [008/133] (915s left) kms_psr (fbc-pr-basic)
[102.128835] Starting subtest: fbc-pr-basic
[102.133061] Subtest fbc-pr-basic: SKIP (0.000s)
[102.210575] [009/133] (915s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[102.301281] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[109.097088] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.799s)
[109.288444] [010/133] (908s left) gem_exec_whisper (basic-sync)
[109.365067] Starting subtest: basic-sync
[115.422968] Subtest basic-sync: SUCCESS (6.055s)
[115.613732] [011/133] (902s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[115.708271] Starting subtest: yf-tiled-32bpp-rotate-270
[115.712491] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[115.835522] [012/133] (902s left) gem_exec_schedule (u-fairslice)
[115.909087] Starting subtest: u-fairslice
[115.911256] Starting dynamic subtest: rcs0
[117.923207] Dynamic subtest rcs0: SUCCESS (2.021s)
[117.932988] Starting dynamic subtest: bcs0
[119.944084] Dynamic subtest bcs0: SUCCESS (2.019s)
[119.953869] Starting dynamic subtest: vcs0
[121.964336] Dynamic subtest vcs0: SUCCESS (2.019s)
[121.974334] Starting dynamic subtest: vcs1
[123.981330] Dynamic subtest vcs1: SUCCESS (2.017s)
[123.989761] Starting dynamic subtest: vecs0
[126.009415] Dynamic subtest vecs0: SUCCESS (2.028s)
[126.017251] Starting dynamic subtest: ccs0
[128.027527] Dynamic subtest ccs0: SUCCESS (2.018s)
[128.029697] Subtest u-fairslice: SUCCESS (12.124s)
[128.315813] [013/133] (889s left) kms_psr (psr2-suspend)
[128.388673] Starting subtest: psr2-suspend
[128.390891] Starting dynamic subtest: eDP-1
[146.311584] Dynamic subtest eDP-1: SUCCESS (3.758s)
[146.314077] Subtest psr2-suspend: SUCCESS (3.759s)
[147.187382] [014/133] (870s left) syncobj_wait (multi-wait-all-submitted)
[147.263543] Starting subtest: multi-wait-all-submitted
[147.369492] Subtest multi-wait-all-submitted: SUCCESS (0.105s)
[147.455798] [015/133] (870s left) gem_softpin (noreloc-s3)
[147.514454] Starting subtest: noreloc-S3
[168.014044] Subtest noreloc-S3: SUCCESS (5.386s)
[168.169557] [016/133] (849s left) i915_pm_freq_api (freq-basic-api)
[168.236845] Starting subtest: freq-basic-api
[168.239327] Starting dynamic subtest: gt0
[168.243964] Dynamic subtest gt0: SUCCESS (0.005s)
[168.255594] Starting dynamic subtest: gt1
[168.257532] Dynamic subtest gt1: SUCCESS (0.004s)
[168.259484] Subtest freq-basic-api: SUCCESS (0.009s)
[168.376311] [017/133] (849s left) kms_psr (fbc-psr-no-drrs)
[168.447023] Starting subtest: fbc-psr-no-drrs
[168.449285] Starting dynamic subtest: eDP-1
[168.453336] Dynamic subtest eDP-1: SKIP (0.000s)
[168.457414] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[168.533863] [018/133] (849s left) kms_cursor_edge_walk (256x256-left-edge)
[168.617011] Starting subtest: 256x256-left-edge
[168.619322] Starting dynamic subtest: pipe-A-eDP-1
[172.071092] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.440s)
[172.081469] Starting dynamic subtest: pipe-D-eDP-1
[175.195352] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.112s)
[175.197499] Subtest 256x256-left-edge: SUCCESS (6.554s)
[176.020755] [019/133] (842s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[176.110189] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[176.114677] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[176.235941] [020/133] (841s left) syncobj_timeline (wait-all-for-submit-complex)
[176.287829] Starting subtest: wait-all-for-submit-complex
[176.501332] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[176.603637] [021/133] (841s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[176.748788] Starting subtest: cursor-rapid-movement-32x10
[176.791303] Subtest cursor-rapid-movement-32x10: SKIP (0.038s)
[177.090260] [022/133] (841s left) prime_busy (after)
[177.162553] Starting subtest: after
[177.167148] Starting dynamic subtest: rcs0
[177.213493] Dynamic subtest rcs0: SUCCESS (0.049s)
[177.224850] Starting dynamic subtest: bcs0
[177.277748] Dynamic subtest bcs0: SUCCESS (0.063s)
[177.288327] Starting dynamic subtest: vcs0
[177.318706] Dynamic subtest vcs0: SUCCESS (0.041s)
[177.327214] Starting dynamic subtest: vcs1
[177.360406] Dynamic subtest vcs1: SUCCESS (0.041s)
[177.369855] Starting dynamic subtest: vecs0
[177.400171] Dynamic subtest vecs0: SUCCESS (0.039s)
[177.409472] Starting dynamic subtest: ccs0
[177.440703] Dynamic subtest ccs0: SUCCESS (0.040s)
[177.548908] Subtest after: SUCCESS (0.384s)
[177.635189] [023/133] (840s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[177.700744] Subtest writes-after-reads: SKIP (0.000s)
[177.776292] [024/133] (840s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[177.877198] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[177.881655] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[177.996740] [025/133] (840s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[178.070565] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[178.074530] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[178.154929] [026/133] (840s left) kms_flip (2x-flip-vs-modeset)
[178.250475] Starting subtest: 2x-flip-vs-modeset
[178.257107] Subtest 2x-flip-vs-modeset: SKIP (0.008s)
[178.398059] [027/133] (840s left) gen7_exec_parse (basic-offset)
[178.471117] Subtest basic-offset: SKIP (0.000s)
[178.555992] [028/133] (839s left) i915_drm_fdinfo (busy-check-all)
[178.599684] Starting subtest: busy-check-all
[178.601572] Starting dynamic subtest: rcs0
[178.605369] Dynamic subtest rcs0: SKIP (0.004s)
[178.614072] Starting dynamic subtest: bcs0
[178.617537] Dynamic subtest bcs0: SKIP (0.000s)
[178.625765] Starting dynamic subtest: vcs0
[178.629306] Dynamic subtest vcs0: SKIP (0.000s)
[178.637372] Starting dynamic subtest: vcs1
[178.640790] Dynamic subtest vcs1: SKIP (0.000s)
[178.648618] Starting dynamic subtest: vecs0
[178.651968] Dynamic subtest vecs0: SKIP (0.000s)
[178.659662] Starting dynamic subtest: ccs0
[178.663026] Dynamic subtest ccs0: SKIP (0.000s)
[178.666502] Subtest busy-check-all: SKIP (0.007s)
[178.697907] [029/133] (839s left) gem_exec_reloc (basic-gtt-active)
[178.819283] Subtest basic-gtt-active: SKIP (0.000s)
[178.895059] [030/133] (839s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[178.971234] Starting subtest: 4-tiled-8bpp-rotate-270
[178.993082] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.021s)
[179.136669] [031/133] (839s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[179.208793] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[179.212108] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[179.338378] [032/133] (839s left) gem_mmap_wc (coherency)
[179.396032] Subtest coherency: SKIP (0.000s)
[179.449574] [033/133] (839s left) gem_softpin (evict-snoop)
[179.506446] Starting subtest: evict-snoop
[179.510229] Subtest evict-snoop: SKIP (0.000s)
[179.654999] [034/133] (839s left) kms_setmode (basic)
[179.709108] Starting subtest: basic
[180.039038] Starting dynamic subtest: pipe-A-eDP-1
[183.623400] Dynamic subtest pipe-A-eDP-1: FAIL (3.464s)
[183.849314] Starting dynamic subtest: pipe-B-eDP-1
[187.448578] Dynamic subtest pipe-B-eDP-1: FAIL (3.470s)
[187.620327] Subtest basic: FAIL (7.899s)
[188.429742] [035/133] (830s left) i915_selftest (perf)
[191.978481] Starting subtest: perf
[191.981338] Starting dynamic subtest: engine_cs
[196.416647] Dynamic subtest engine_cs: SUCCESS (4.434s)
[196.426780] Starting dynamic subtest: request
[203.839079] Dynamic subtest request: SUCCESS (7.427s)
[203.848517] Starting dynamic subtest: migrate
[208.566299] Dynamic subtest migrate: SUCCESS (4.727s)
[208.578406] Starting dynamic subtest: region
[214.473733] Dynamic subtest region: SUCCESS (5.906s)
[214.476318] Subtest perf: SUCCESS (22.499s)
[214.505013] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[214.508023] [036/133] (804s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[216.025588] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[216.029989] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[216.104794] [FACT kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)] new: kernel.kmod_is_loaded.i915: true
[216.108647] [037/133] (802s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[216.201015] Starting subtest: plane-scaler-unity-scaling-with-rotation
[216.203370] Starting dynamic subtest: pipe-A
[216.440444] Dynamic subtest pipe-A: SUCCESS (0.241s)
[216.450016] Starting dynamic subtest: pipe-B
[217.783395] Dynamic subtest pipe-B: SUCCESS (1.342s)
[217.796188] Starting dynamic subtest: pipe-C
[219.113483] Dynamic subtest pipe-C: SUCCESS (1.329s)
[219.124578] Starting dynamic subtest: pipe-D
[220.428297] Dynamic subtest pipe-D: SUCCESS (1.314s)
[220.430732] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.228s)
[221.624721] [038/133] (797s left) gem_mmap_gtt (basic-read)
[221.699310] Subtest basic-read: SKIP (0.000s)
[221.771377] [039/133] (797s left) kms_properties (plane-properties-atomic)
[221.847181] Starting subtest: plane-properties-atomic
[221.849415] Starting dynamic subtest: pipe-A-eDP-1
[222.443638] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.596s)
[222.453455] Starting dynamic subtest: pipe-B-eDP-1
[223.692374] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.249s)
[223.701864] Starting dynamic subtest: pipe-C-eDP-1
[225.017864] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.324s)
[225.026645] Starting dynamic subtest: pipe-D-eDP-1
[226.250931] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s)
[226.253239] Subtest plane-properties-atomic: SUCCESS (4.403s)
[227.055881] [040/133] (791s left) kms_color (invalid-gamma-lut-sizes)
[227.135827] Starting subtest: invalid-gamma-lut-sizes
[227.138086] Starting dynamic subtest: pipe-A
[227.420689] Dynamic subtest pipe-A: SUCCESS (0.285s)
[227.430056] Starting dynamic subtest: pipe-B
[227.432338] Dynamic subtest pipe-B: SUCCESS (0.000s)
[227.440809] Starting dynamic subtest: pipe-C
[227.443061] Dynamic subtest pipe-C: SUCCESS (0.000s)
[227.451401] Starting dynamic subtest: pipe-D
[227.453589] Dynamic subtest pipe-D: SUCCESS (0.000s)
[227.455466] Subtest invalid-gamma-lut-sizes: SUCCESS (0.288s)
[228.273428] [041/133] (790s left) dmabuf_sync_file (export-before-signal)
[228.351887] Starting subtest: export-before-signal
[228.354313] Subtest export-before-signal: SUCCESS (0.001s)
[228.416541] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[228.420084] [042/133] (790s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[228.504471] Starting subtest: psr-1p-pri-indfb-multidraw
[251.111398] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.605s)
[251.245440] [043/133] (767s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[251.331663] Starting subtest: compare-crc-sanitycheck-xr24
[251.333692] Starting dynamic subtest: pipe-A-eDP-1
[251.936562] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.602s)
[251.946230] Starting dynamic subtest: pipe-B-eDP-1
[253.146286] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.208s)
[253.156065] Starting dynamic subtest: pipe-C-eDP-1
[254.318799] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.171s)
[254.328806] Starting dynamic subtest: pipe-D-eDP-1
[255.563398] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.244s)
[255.565710] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.230s)
[256.433303] [044/133] (762s left) gem_readwrite (new-obj)
[256.499197] Subtest new-obj: SKIP (0.000s)
[256.576086] [045/133] (762s left) i915_pm_freq_mult (media-freq)
[256.626622] Starting subtest: media-freq
[256.628551] Starting dynamic subtest: gt0
[256.632699] Dynamic subtest gt0: SKIP (0.000s)
[256.642808] Starting dynamic subtest: gt1
[256.647048] Dynamic subtest gt1: SKIP (0.000s)
[256.651366] Subtest media-freq: SKIP (0.001s)
[256.721205] [046/133] (762s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[256.803507] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[256.807501] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[256.932855] [047/133] (762s left) kms_cursor_crc (cursor-offscreen-512x170)
[257.185103] Starting subtest: cursor-offscreen-512x170
[257.189120] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[257.332556] [048/133] (761s left) perf (i915-ref-count)
[257.426745] Starting subtest: i915-ref-count
[257.495556] Subtest i915-ref-count: SUCCESS (0.067s)
[257.562614] [049/133] (761s left) kms_async_flips (alternate-sync-async-flip)
[257.643090] Starting subtest: alternate-sync-async-flip
[258.022425] Starting dynamic subtest: pipe-A-eDP-1
[260.807886] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.786s)
[261.192014] Starting dynamic subtest: pipe-B-eDP-1
[263.933811] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.753s)
[264.300959] Starting dynamic subtest: pipe-C-eDP-1
[267.064241] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.772s)
[267.435041] Starting dynamic subtest: pipe-D-eDP-1
[270.191278] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.765s)
[270.193284] Subtest alternate-sync-async-flip: SUCCESS (12.552s)
[271.375840] [050/133] (748s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[271.449887] Starting subtest: invalid-multi-wait-unsubmitted
[271.452229] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[271.581921] [051/133] (747s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[271.661932] Starting subtest: primary-y-tiled-reflect-x-270
[271.666265] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[271.746339] [052/133] (747s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[271.827036] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[271.829244] Starting dynamic subtest: pipe-A-eDP-1
[272.677576] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.844s)
[272.686829] Starting dynamic subtest: pipe-B-eDP-1
[274.370152] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.689s)
[274.379917] Starting dynamic subtest: pipe-C-eDP-1
[275.913534] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.544s)
[275.923253] Starting dynamic subtest: pipe-D-eDP-1
[277.425172] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.511s)
[277.427063] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.592s)
[278.603432] [053/133] (740s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[278.698387] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[285.206444] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.511s)
[285.386909] [054/133] (734s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[285.482608] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[285.486615] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[285.617062] [055/133] (733s left) gem_mmap_offset (close-race)
[285.677910] Starting subtest: close-race
[305.789697] Subtest close-race: SUCCESS (20.109s)
[305.957791] [056/133] (713s left) vgem_basic (dmabuf-export)
[305.994076] Starting subtest: dmabuf-export
[306.028469] Subtest dmabuf-export: SUCCESS (0.038s)
[306.150547] [057/133] (713s left) syncobj_eventfd (timeline-wait)
[306.200055] Starting subtest: timeline-wait
[306.208188] Subtest timeline-wait: SUCCESS (0.010s)
[306.308989] [058/133] (713s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[306.389254] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[306.393579] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[306.475342] [059/133] (713s left) gem_exec_whisper (basic-normal-all)
[306.541080] Starting subtest: basic-normal-all
[308.676080] Subtest basic-normal-all: SUCCESS (2.132s)
[308.860717] [060/133] (710s left) kms_big_fb (linear-8bpp-rotate-0)
[308.959365] Starting subtest: linear-8bpp-rotate-0
[310.174105] Subtest linear-8bpp-rotate-0: SUCCESS (1.214s)
[310.335384] [061/133] (709s left) fbdev (unaligned-write)
[310.380453] Starting subtest: unaligned-write
[310.491424] Subtest unaligned-write: SUCCESS (0.109s)
[310.568546] [062/133] (709s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[310.640199] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[310.642599] Starting dynamic subtest: pipe-A-eDP-1
[311.434276] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.786s)
[311.443433] Starting dynamic subtest: pipe-B-eDP-1
[313.001954] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.565s)
[313.011159] Starting dynamic subtest: pipe-C-eDP-1
[314.537007] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.535s)
[314.546919] Starting dynamic subtest: pipe-D-eDP-1
[316.054352] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.513s)
[316.056690] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.405s)
[317.237034] [063/133] (702s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[317.355622] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[317.360049] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[317.500529] [064/133] (702s left) gem_exec_reloc (basic-wc-read-active)
[317.565667] Subtest basic-wc-read-active: SKIP (0.000s)
[317.645967] [065/133] (702s left) gem_userptr_blits (nohangcheck)
[317.714315] Starting subtest: nohangcheck
[317.985555] Subtest nohangcheck: SUCCESS (0.267s)
[318.134938] [066/133] (701s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[318.212204] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[318.214002] Starting dynamic subtest: A-eDP1
[327.071034] Dynamic subtest A-eDP1: SUCCESS (8.857s)
[327.080515] Starting dynamic subtest: B-eDP1
[335.997727] Dynamic subtest B-eDP1: SUCCESS (8.923s)
[336.008984] Starting dynamic subtest: C-eDP1
[344.906450] Dynamic subtest C-eDP1: SUCCESS (8.903s)
[344.916800] Starting dynamic subtest: D-eDP1
[353.843697] Dynamic subtest D-eDP1: SUCCESS (8.933s)
[353.846020] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.626s)
[355.096474] [067/133] (664s left) kms_getfb (getfb2-handle-zero)
[355.182204] Starting subtest: getfb2-handle-zero
[355.184573] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[355.299762] [068/133] (664s left) gem_partial_pwrite_pread (writes-after-reads)
[355.364043] Subtest writes-after-reads: SKIP (0.000s)
[355.444047] [069/133] (664s left) prime_mmap (test_map_unmap)
[355.500962] Starting subtest: test_map_unmap
[355.503197] Starting dynamic subtest: test_map_unmap-smem
[355.553320] Dynamic subtest test_map_unmap-smem: SUCCESS (0.054s)
[355.555834] Subtest test_map_unmap: SUCCESS (0.055s)
[355.683019] [070/133] (664s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[355.766092] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[355.768300] Starting dynamic subtest: pipe-A-eDP-1
[355.825038] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[355.834124] Starting dynamic subtest: pipe-B-eDP-1
[355.891805] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[355.902252] Starting dynamic subtest: pipe-C-eDP-1
[355.958405] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[355.967714] Starting dynamic subtest: pipe-D-eDP-1
[356.019925] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[356.023302] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[357.956197] [071/133] (662s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[358.065906] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[358.070179] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[358.211070] [072/133] (662s left) kms_prime (basic-crc-vgem)
[358.278412] Starting subtest: basic-crc-vgem
[358.280476] Starting dynamic subtest: second-to-first
[359.197293] Dynamic subtest second-to-first: SUCCESS (0.922s)
[359.199197] Subtest basic-crc-vgem: SUCCESS (0.923s)
[359.301835] [073/133] (661s left) gem_ctx_persistence (legacy-engines-hang)
[359.395113] Starting subtest: legacy-engines-hang
[359.396754] Starting dynamic subtest: render
[359.561974] Dynamic subtest render: SUCCESS (0.169s)
[359.574882] Starting dynamic subtest: bsd1
[359.691997] Dynamic subtest bsd1: SUCCESS (0.127s)
[359.706613] Starting dynamic subtest: bsd2
[359.763664] Dynamic subtest bsd2: SUCCESS (0.069s)
[359.774997] Starting dynamic subtest: blt
[359.903832] Dynamic subtest blt: SUCCESS (0.137s)
[359.915285] Starting dynamic subtest: vebox
[360.013742] Dynamic subtest vebox: SUCCESS (0.107s)
[360.016224] Subtest legacy-engines-hang: SUCCESS (0.622s)
[360.104587] [074/133] (660s left) kms_psr (fbc-pr-sprite-plane-move)
[360.173438] Starting subtest: fbc-pr-sprite-plane-move
[360.177080] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[360.260082] [075/133] (660s left) vgem_slow (nohang)
[360.310420] Starting subtest: nohang
[370.795924] Subtest nohang: SUCCESS (10.482s)
[370.876401] [076/133] (649s left) gem_ctx_persistence (engines-hostile)
[370.941577] Starting subtest: engines-hostile
[370.943976] Starting dynamic subtest: rcs0
[371.070016] Dynamic subtest rcs0: SUCCESS (0.128s)
[371.081994] Starting dynamic subtest: bcs0
[371.182835] Dynamic subtest bcs0: SUCCESS (0.111s)
[371.194755] Starting dynamic subtest: vcs0
[371.346919] Dynamic subtest vcs0: SUCCESS (0.162s)
[371.362152] Starting dynamic subtest: vcs1
[371.479103] Dynamic subtest vcs1: SUCCESS (0.129s)
[371.492046] Starting dynamic subtest: vecs0
[371.598033] Dynamic subtest vecs0: SUCCESS (0.117s)
[371.610899] Starting dynamic subtest: ccs0
[371.671671] Dynamic subtest ccs0: SUCCESS (0.072s)
[371.673860] Subtest engines-hostile: SUCCESS (0.731s)
[371.834057] [077/133] (648s left) syncobj_basic (bad-destroy)
[371.896075] Starting subtest: bad-destroy
[371.897917] Subtest bad-destroy: SUCCESS (0.000s)
[372.032855] [078/133] (648s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[372.126089] Starting subtest: fbc-rgb565-draw-blt
[376.348466] Subtest fbc-rgb565-draw-blt: SUCCESS (4.225s)
[377.565565] [079/133] (643s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[377.665301] Starting subtest: psr2-cursor-plane-update-sf
[377.667516] Starting dynamic subtest: pipe-A-eDP-1
[378.231210] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.569s)
[378.240839] Starting dynamic subtest: pipe-B-eDP-1
[379.537639] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.302s)
[379.540032] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.877s)
[380.425387] [080/133] (640s left) kms_lease (lease-invalid-plane)
[380.502068] Starting subtest: lease-invalid-plane
[380.504385] Starting dynamic subtest: pipe-A-eDP-1
[380.506579] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[380.516322] Starting dynamic subtest: pipe-B-eDP-1
[380.518631] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[380.528416] Starting dynamic subtest: pipe-C-eDP-1
[380.530663] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[380.540222] Starting dynamic subtest: pipe-D-eDP-1
[380.542069] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[380.543906] Subtest lease-invalid-plane: SUCCESS (0.004s)
[380.588975] [081/133] (640s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[380.672043] Starting subtest: yf-tiled-8bpp-rotate-0
[380.676502] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[380.801903] [082/133] (639s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[380.892670] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[380.897183] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[381.027024] [083/133] (639s left) kms_atomic (crtc-invalid-params-fence)
[381.107931] Starting subtest: crtc-invalid-params-fence
[381.110305] Starting dynamic subtest: pipe-A-eDP-1
[381.543419] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.435s)
[381.545702] Subtest crtc-invalid-params-fence: SUCCESS (0.436s)
[382.352502] [084/133] (638s left) gem_softpin (32b-excludes-last-page)
[382.418921] Starting subtest: 32b-excludes-last-page
[382.424395] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[382.602370] [085/133] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[382.680926] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[382.684893] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[382.822589] [086/133] (638s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[382.920512] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[382.924650] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[383.042578] [087/133] (637s left) prime_vgem (basic-read)
[383.149237] Starting subtest: basic-read
[383.156285] Subtest basic-read: SKIP (0.006s)
[383.275525] [088/133] (637s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[383.358948] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[383.385748] Starting dynamic subtest: pipe-A-plane-0
[383.389838] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[383.399536] Starting dynamic subtest: pipe-A-plane-3
[383.403829] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[383.413346] Starting dynamic subtest: pipe-A-plane-5
[383.417229] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[384.440407] Starting dynamic subtest: pipe-B-plane-0
[384.443943] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[384.453276] Starting dynamic subtest: pipe-B-plane-3
[384.457532] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[384.466948] Starting dynamic subtest: pipe-B-plane-5
[384.471275] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[384.743357] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (1.381s)
[385.618099] [089/133] (635s left) syncobj_timeline (invalid-query-zero-handles)
[385.679485] Starting subtest: invalid-query-zero-handles
[385.681389] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[385.799549] [090/133] (635s left) perf_pmu (invalid-open)
[385.843727] Starting subtest: invalid-open
[385.846109] Subtest invalid-open: SUCCESS (0.000s)
[385.968439] [091/133] (635s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[386.044301] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[386.048218] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[386.149431] [092/133] (634s left) kms_busy (extended-modeset-hang-oldfb)
[386.229959] Starting subtest: extended-modeset-hang-oldfb
[386.232412] Starting dynamic subtest: pipe-A
[397.790264] Dynamic subtest pipe-A: SUCCESS (11.561s)
[397.804971] Starting dynamic subtest: pipe-D
[410.061740] Dynamic subtest pipe-D: SUCCESS (12.269s)
[410.063914] Subtest extended-modeset-hang-oldfb: SUCCESS (23.837s)
[410.885466] [093/133] (610s left) gem_exec_fence (syncobj-timeline-chain-engines)
[411.451142] Starting subtest: syncobj-timeline-chain-engines
[411.545616] Subtest syncobj-timeline-chain-engines: SUCCESS (0.098s)
[413.193642] [094/133] (607s left) kms_rotation_crc (primary-rotation-90)
[413.269253] Starting subtest: primary-rotation-90
[413.273706] Subtest primary-rotation-90: SKIP (0.001s)
[413.355508] [095/133] (607s left) kms_chamelium_hpd (dp-hpd-fast)
[413.710486] Subtest dp-hpd-fast: SKIP (0.000s)
[414.575193] [096/133] (606s left) gem_create (create-ext-cpu-access-sanity-check)
[414.748157] Starting subtest: create-ext-cpu-access-sanity-check
[414.753244] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[414.841111] [097/133] (606s left) vgem_basic (bad-flag)
[414.887531] Starting subtest: bad-flag
[414.889585] Subtest bad-flag: SUCCESS (0.000s)
[414.958148] [098/133] (606s left) kms_pm_backlight (fade-with-dpms)
[415.037978] Starting subtest: fade-with-dpms
[417.610805] Starting dynamic subtest: eDP-1
[420.295445] Dynamic subtest eDP-1: SUCCESS (2.683s)
[420.297730] Subtest fade-with-dpms: SUCCESS (5.256s)
[421.120321] [099/133] (600s left) gem_exec_fence (basic-wait)
[421.300074] Starting subtest: basic-wait
[421.302809] Starting dynamic subtest: rcs0
[421.405036] Dynamic subtest rcs0: SUCCESS (0.105s)
[421.418110] Starting dynamic subtest: bcs0
[421.464973] Dynamic subtest bcs0: SUCCESS (0.058s)
[421.477168] Starting dynamic subtest: vcs0
[421.525825] Dynamic subtest vcs0: SUCCESS (0.058s)
[421.538056] Starting dynamic subtest: vcs1
[421.590862] Dynamic subtest vcs1: SUCCESS (0.063s)
[421.602907] Starting dynamic subtest: vecs0
[421.651523] Dynamic subtest vecs0: SUCCESS (0.058s)
[421.663666] Starting dynamic subtest: ccs0
[421.719964] Dynamic subtest ccs0: SUCCESS (0.066s)
[421.722369] Subtest basic-wait: SUCCESS (0.422s)
[423.131176] [100/133] (598s left) core_getclient (basic)
[423.190854] Starting subtest: basic
[423.193176] Subtest basic: SUCCESS (0.000s)
[423.320671] [101/133] (598s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[423.404250] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[423.408649] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[423.546872] [102/133] (598s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[423.615958] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[423.618331] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[423.734639] [103/133] (597s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[423.797718] Starting subtest: yf-tiled-ccs-to-yf-tiled
[423.802339] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[423.995160] [104/133] (597s left) gem_exec_schedule (pi-common)
[424.172199] Starting subtest: pi-common
[424.174260] Starting dynamic subtest: rcs0
[424.200209] Dynamic subtest rcs0: SUCCESS (0.030s)
[424.215343] Starting dynamic subtest: bcs0
[424.227339] Dynamic subtest bcs0: SUCCESS (0.026s)
[424.241941] Starting dynamic subtest: vcs0
[424.252896] Dynamic subtest vcs0: SUCCESS (0.025s)
[424.266977] Starting dynamic subtest: vcs1
[424.279896] Dynamic subtest vcs1: SUCCESS (0.026s)
[424.294858] Starting dynamic subtest: vecs0
[424.306915] Dynamic subtest vecs0: SUCCESS (0.026s)
[424.321114] Starting dynamic subtest: ccs0
[424.334028] Dynamic subtest ccs0: SUCCESS (0.026s)
[424.336226] Subtest pi-common: SUCCESS (0.165s)
[424.594919] [105/133] (597s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[424.663459] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[424.735664] [106/133] (597s left) kms_vblank (ts-continuation-dpms-rpm)
[424.826163] Starting subtest: ts-continuation-dpms-rpm
[424.828392] Starting dynamic subtest: pipe-A-eDP-1
[427.274951] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.447s)
[427.291022] Starting dynamic subtest: pipe-D-eDP-1
[429.446075] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.169s)
[429.448456] Subtest ts-continuation-dpms-rpm: SUCCESS (4.620s)
[430.250551] [107/133] (591s left) gem_ctx_shared (single-timeline)
[430.322899] Starting subtest: single-timeline
[430.343067] Subtest single-timeline: SUCCESS (0.014s)
[430.597132] [108/133] (591s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[430.685883] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[430.689864] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[430.807037] [109/133] (591s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[430.894813] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[430.899049] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[431.025757] [110/133] (590s left) gem_exec_params (invalid-ring2)
[431.091767] Starting subtest: invalid-ring2
[431.094185] Subtest invalid-ring2: SUCCESS (0.000s)
[431.219011] [111/133] (590s left) kms_dirtyfb (default-dirtyfb-ioctl)
[431.296903] Starting subtest: default-dirtyfb-ioctl
[431.299023] Starting dynamic subtest: A-eDP-1
[432.920344] Dynamic subtest A-eDP-1: SUCCESS (1.622s)
[432.922608] Subtest default-dirtyfb-ioctl: SUCCESS (1.624s)
[433.784350] [112/133] (588s left) kms_psr (pr-primary-render)
[433.853017] Starting subtest: pr-primary-render
[433.857334] Subtest pr-primary-render: SKIP (0.000s)
[433.941112] [113/133] (588s left) gem_ctx_bad_destroy (double-destroy)
[433.994905] Starting subtest: double-destroy
[433.997221] Subtest double-destroy: SUCCESS (0.000s)
[434.082835] [114/133] (587s left) kms_plane_scaling (planes-downscale-factor-0-75)
[434.163234] Starting subtest: planes-downscale-factor-0-75
[434.165463] Starting dynamic subtest: pipe-A
[434.183703] Dynamic subtest pipe-A: SKIP (0.017s)
[434.194842] Starting dynamic subtest: pipe-B
[434.215382] Dynamic subtest pipe-B: SKIP (0.029s)
[434.225228] Starting dynamic subtest: pipe-C
[434.245238] Dynamic subtest pipe-C: SKIP (0.029s)
[434.255072] Starting dynamic subtest: pipe-D
[434.272828] Dynamic subtest pipe-D: SKIP (0.029s)
[434.276937] Subtest planes-downscale-factor-0-75: SKIP (0.108s)
[434.380886] [115/133] (587s left) kms_plane_multiple (tiling-none)
[434.473040] Starting subtest: tiling-none
[434.475347] Starting dynamic subtest: pipe-A-eDP-1
[438.141559] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.669s)
[438.153691] Starting dynamic subtest: pipe-B-eDP-1
[442.476720] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.332s)
[442.487972] Starting dynamic subtest: pipe-C-eDP-1
[446.807296] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.330s)
[446.819154] Starting dynamic subtest: pipe-D-eDP-1
[451.535585] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.727s)
[451.537914] Subtest tiling-none: SUCCESS (17.062s)
[452.359077] [116/133] (569s left) kms_draw_crc (draw-method-mmap-cpu)
[452.457652] Starting subtest: draw-method-mmap-cpu
[452.459545] Starting dynamic subtest: xrgb8888-untiled
[452.712497] Dynamic subtest xrgb8888-untiled: SUCCESS (0.256s)
[452.722828] Starting dynamic subtest: rgb565-untiled
[452.934402] Dynamic subtest rgb565-untiled: SUCCESS (0.220s)
[452.944314] Starting dynamic subtest: xrgb2101010-untiled
[453.157215] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[453.166779] Starting dynamic subtest: xbgr16161616f-untiled
[453.367686] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.209s)
[453.379446] Starting dynamic subtest: xrgb8888-xtiled
[453.501021] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[453.513057] Starting dynamic subtest: rgb565-xtiled
[453.645357] Dynamic subtest rgb565-xtiled: SUCCESS (0.142s)
[453.655494] Starting dynamic subtest: xrgb2101010-xtiled
[453.778546] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[453.787971] Starting dynamic subtest: xbgr16161616f-xtiled
[453.912452] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[453.922793] Starting dynamic subtest: xrgb8888-4tiled
[454.045370] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[454.056637] Starting dynamic subtest: rgb565-4tiled
[454.178662] Dynamic subtest rgb565-4tiled: SUCCESS (0.133s)
[454.188406] Starting dynamic subtest: xrgb2101010-4tiled
[454.301028] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[454.311025] Starting dynamic subtest: xbgr16161616f-4tiled
[454.434587] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[454.436616] Subtest draw-method-mmap-cpu: SUCCESS (1.979s)
[454.554900] [117/133] (567s left) kms_psr (psr-primary-page-flip)
[454.643461] Starting subtest: psr-primary-page-flip
[454.645279] Starting dynamic subtest: eDP-1
[456.355513] Dynamic subtest eDP-1: SUCCESS (1.711s)
[456.357838] Subtest psr-primary-page-flip: SUCCESS (1.712s)
[457.233246] [118/133] (565s left) kms_content_protection (dp-mst-type-0-hdcp14)
[457.363660] Starting subtest: dp-mst-type-0-hdcp14
[457.367943] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[457.516116] [119/133] (564s left) api_intel_allocator (alloc-simple)
[457.575885] Starting subtest: alloc-simple
[457.578222] Subtest alloc-simple: SUCCESS (0.004s)
[457.740101] [120/133] (564s left) gem_spin_batch (resubmit-new-all)
[457.797277] Starting subtest: resubmit-new-all
[457.799663] Starting dynamic subtest: rcs0
[457.813959] Dynamic subtest rcs0: SUCCESS (0.016s)
[457.826425] Starting dynamic subtest: bcs0
[457.828567] Dynamic subtest bcs0: SUCCESS (0.009s)
[457.838725] Starting dynamic subtest: vcs0
[457.840591] Dynamic subtest vcs0: SUCCESS (0.008s)
[457.850552] Starting dynamic subtest: vcs1
[457.852662] Dynamic subtest vcs1: SUCCESS (0.011s)
[457.863955] Starting dynamic subtest: vecs0
[457.866215] Dynamic subtest vecs0: SUCCESS (0.009s)
[457.875859] Starting dynamic subtest: ccs0
[457.878046] Dynamic subtest ccs0: SUCCESS (0.008s)
[457.880158] Subtest resubmit-new-all: SUCCESS (0.066s)
[459.147225] [121/133] (563s left) kms_joiner (invalid-modeset-ultra-joiner)
[459.230288] Starting subtest: invalid-modeset-ultra-joiner
[459.234528] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[459.317806] [122/133] (563s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[459.412420] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[459.416804] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[459.574092] [123/133] (562s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[459.962993] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[460.749149] [124/133] (561s left) gem_userptr_blits (unsync-overlap)
[460.814531] Subtest unsync-overlap: SKIP (0.000s)
[460.965491] [125/133] (561s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[461.062195] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[461.066458] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[461.179185] [126/133] (561s left) kms_sequence (get-busy)
[461.280416] Starting subtest: get-busy
[461.282788] Starting dynamic subtest: pipe-A-eDP-1
[463.205749] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.927s)
[463.216792] Starting dynamic subtest: pipe-B-eDP-1
[465.842697] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.634s)
[465.853428] Starting dynamic subtest: pipe-C-eDP-1
[468.472912] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.628s)
[468.483742] Starting dynamic subtest: pipe-D-eDP-1
[471.024335] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.549s)
[471.026567] Subtest get-busy: SUCCESS (9.743s)
[471.922975] [127/133] (550s left) gem_exec_balancer (hang)
[472.194935] Starting subtest: hang
[474.087483] Subtest hang: SUCCESS (1.895s)
[474.239937] [128/133] (548s left) kms_cdclk (plane-scaling)
[474.309768] Starting subtest: plane-scaling
[474.312012] Starting dynamic subtest: pipe-A-eDP-1
[474.599479] Dynamic subtest pipe-A-eDP-1: SKIP (0.287s)
[474.610222] Starting dynamic subtest: pipe-B-eDP-1
[474.614906] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[474.625064] Starting dynamic subtest: pipe-C-eDP-1
[474.629744] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[474.639692] Starting dynamic subtest: pipe-D-eDP-1
[474.643935] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[474.648165] Subtest plane-scaling: SKIP (0.294s)
[475.436005] [129/133] (547s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[475.525483] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[475.529809] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[475.650883] [130/133] (547s left) kms_flip (flip-vs-wf_vblank-interruptible)
[475.727438] Starting subtest: flip-vs-wf_vblank-interruptible
[475.729808] Starting dynamic subtest: A-eDP1
[477.676582] Dynamic subtest A-eDP1: SUCCESS (1.949s)
[477.688798] Starting dynamic subtest: B-eDP1
[479.613377] Dynamic subtest B-eDP1: SUCCESS (1.935s)
[479.632693] Starting dynamic subtest: C-eDP1
[481.518837] Dynamic subtest C-eDP1: SUCCESS (1.903s)
[481.529432] Starting dynamic subtest: D-eDP1
[483.449461] Dynamic subtest D-eDP1: SUCCESS (1.928s)
[483.454239] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.726s)
[484.687492] [131/133] (538s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[484.773225] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[484.777280] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[484.892430] [132/133] (537s left) gem_mmap_wc (set-cache-level)
[484.951151] Subtest set-cache-level: SKIP (0.000s)
[485.018834] [133/133] (537s left) gem_exec_params (cliprects_ptr-dirt)
[485.087254] Starting subtest: cliprects_ptr-dirt
[485.089249] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[485.206608] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: sw_sync
results: parsing output: 3/ for test: gem_pread
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: kms_atomic_transition
results: parsing output: 6/ for test: gem_exec_gttfill
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_whisper
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: i915_pm_freq_api
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: kms_cursor_edge_walk
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: prime_busy
results: parsing output: 22/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_flip_scaled_crc
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: gen7_exec_parse
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: gem_softpin
results: parsing output: 33/ for test: kms_setmode
results: parsing output: 34/ for test: i915_selftest
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_plane_scaling
results: parsing output: 37/ for test: gem_mmap_gtt
results: parsing output: 38/ for test: kms_properties
results: parsing output: 39/ for test: kms_color
results: parsing output: 40/ for test: dmabuf_sync_file
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pipe_crc_basic
results: parsing output: 43/ for test: gem_readwrite
results: parsing output: 44/ for test: i915_pm_freq_mult
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: kms_async_flips
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: kms_rotation_crc
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_mmap_offset
results: parsing output: 55/ for test: vgem_basic
results: parsing output: 56/ for test: syncobj_eventfd
results: parsing output: 57/ for test: kms_flip_scaled_crc
results: parsing output: 58/ for test: gem_exec_whisper
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: fbdev
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: gem_userptr_blits
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_getfb
results: parsing output: 67/ for test: gem_partial_pwrite_pread
results: parsing output: 68/ for test: prime_mmap
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_prime
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: vgem_slow
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: syncobj_basic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_psr2_sf
results: parsing output: 79/ for test: kms_lease
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_atomic
results: parsing output: 83/ for test: gem_softpin
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: prime_vgem
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_busy
results: parsing output: 92/ for test: gem_exec_fence
results: parsing output: 93/ for test: kms_rotation_crc
results: parsing output: 94/ for test: kms_chamelium_hpd
results: parsing output: 95/ for test: gem_create
results: parsing output: 96/ for test: vgem_basic
results: parsing output: 97/ for test: kms_pm_backlight
results: parsing output: 98/ for test: gem_exec_fence
results: parsing output: 99/ for test: core_getclient
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: kms_vblank
results: parsing output: 106/ for test: gem_ctx_shared
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_exec_params
results: parsing output: 110/ for test: kms_dirtyfb
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: gem_ctx_bad_destroy
results: parsing output: 113/ for test: kms_plane_scaling
results: parsing output: 114/ for test: kms_plane_multiple
results: parsing output: 115/ for test: kms_draw_crc
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_content_protection
results: parsing output: 118/ for test: api_intel_allocator
results: parsing output: 119/ for test: gem_spin_batch
results: parsing output: 120/ for test: kms_joiner
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_chamelium_frames
results: parsing output: 123/ for test: gem_userptr_blits
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_sequence
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: kms_cdclk
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_mmap_wc
results: parsing output: 132/ for test: gem_exec_params
Done.