[56.159447] Initializing watchdogs
[56.159491]   /dev/watchdog0
[56.173293] [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]
[56.177728] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.179957] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.184068] [001/133] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[56.309755] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[56.311899] Starting dynamic subtest: pipe-A-eDP-1
[56.333888] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[56.343347] Starting dynamic subtest: pipe-B-eDP-1
[56.364686] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[56.371282] Starting dynamic subtest: pipe-C-eDP-1
[56.392711] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[56.401038] Starting dynamic subtest: pipe-D-eDP-1
[56.423769] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[56.428188] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[58.590851] [002/133] (958s left) perf_pmu (busy-double-start)
[58.631373] Starting subtest: busy-double-start
[58.633777] Starting dynamic subtest: rcs0
[62.279312] Dynamic subtest rcs0: FAIL (3.624s)
[62.287834] Starting dynamic subtest: bcs0
[65.824747] Dynamic subtest bcs0: SUCCESS (3.615s)
[65.834518] Starting dynamic subtest: vcs0
[69.401554] Dynamic subtest vcs0: SUCCESS (3.587s)
[69.411356] Starting dynamic subtest: vcs1
[73.469018] Dynamic subtest vcs1: SUCCESS (4.073s)
[73.479212] Starting dynamic subtest: vecs0
[77.401942] Dynamic subtest vecs0: SUCCESS (3.937s)
[77.411835] Starting dynamic subtest: ccs0
[81.039890] Dynamic subtest ccs0: FAIL (3.564s)
[81.041898] Subtest busy-double-start: FAIL (22.403s)
[81.101544] [003/133] (935s left) sw_sync (sync_merge)
[81.144076] Starting subtest: sync_merge
[81.146381] Subtest sync_merge: SUCCESS (0.000s)
[81.205877] [004/133] (935s left) gem_pread (self)
[81.264623] Subtest self: SKIP (0.000s)
[81.329309] [005/133] (935s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[81.451895] Starting subtest: flip-vs-cursor-crc-atomic
[82.143746] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.691s)
[82.961155] [006/133] (933s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[83.060167] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[83.062586] Starting dynamic subtest: pipe-A-eDP-1
[83.120692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[83.418810] Starting dynamic subtest: pipe-B-eDP-1
[84.255364] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.843s)
[84.564543] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.504s)
[85.428411] [007/133] (931s left) gem_exec_gttfill (multigpu-basic)
[85.611058] Starting subtest: multigpu-basic
[100.037880] Subtest multigpu-basic: SUCCESS (14.426s)
[100.087078] [008/133] (916s left) kms_psr (fbc-pr-basic)
[100.232235] Starting subtest: fbc-pr-basic
[100.237049] Subtest fbc-pr-basic: SKIP (0.000s)
[100.312922] [009/133] (916s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[100.420940] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[107.383331] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.963s)
[107.540976] [010/133] (909s left) gem_exec_whisper (basic-sync)
[107.615065] Starting subtest: basic-sync
[114.235165] Subtest basic-sync: SUCCESS (6.618s)
[114.427036] [011/133] (902s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[114.557893] Starting subtest: yf-tiled-32bpp-rotate-270
[114.562170] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[114.682716] [012/133] (902s left) gem_exec_schedule (u-fairslice)
[114.770726] Starting subtest: u-fairslice
[114.772938] Starting dynamic subtest: rcs0
[116.786342] Dynamic subtest rcs0: SUCCESS (2.020s)
[116.795392] Starting dynamic subtest: bcs0
[118.803975] Dynamic subtest bcs0: SUCCESS (2.019s)
[118.817709] Starting dynamic subtest: vcs0
[120.824814] Dynamic subtest vcs0: SUCCESS (2.020s)
[120.834483] Starting dynamic subtest: vcs1
[122.843096] Dynamic subtest vcs1: SUCCESS (2.018s)
[122.853169] Starting dynamic subtest: vecs0
[124.860130] Dynamic subtest vecs0: SUCCESS (2.016s)
[124.870574] Starting dynamic subtest: ccs0
[126.880617] Dynamic subtest ccs0: SUCCESS (2.019s)
[126.882596] Subtest u-fairslice: SUCCESS (12.114s)
[127.190885] [013/133] (889s left) kms_psr (psr2-suspend)
[127.302896] Starting subtest: psr2-suspend
[127.305330] Starting dynamic subtest: eDP-1
[145.437526] Dynamic subtest eDP-1: SUCCESS (3.039s)
[145.439882] Subtest psr2-suspend: SUCCESS (3.040s)
[146.286299] [014/133] (870s left) syncobj_wait (multi-wait-all-submitted)
[146.361974] Starting subtest: multi-wait-all-submitted
[146.463066] Subtest multi-wait-all-submitted: SUCCESS (0.106s)
[146.545353] [015/133] (870s left) gem_softpin (noreloc-s3)
[146.601815] Starting subtest: noreloc-S3
[166.955690] Subtest noreloc-S3: SUCCESS (5.389s)
[167.091751] [016/133] (850s left) i915_pm_freq_api (freq-basic-api)
[167.176914] Starting subtest: freq-basic-api
[167.179123] Starting dynamic subtest: gt0
[167.183313] Dynamic subtest gt0: SUCCESS (0.006s)
[167.193004] Starting dynamic subtest: gt1
[167.195237] Dynamic subtest gt1: SUCCESS (0.003s)
[167.197311] Subtest freq-basic-api: SUCCESS (0.010s)
[167.315997] [017/133] (849s left) kms_psr (fbc-psr-no-drrs)
[167.413538] Starting subtest: fbc-psr-no-drrs
[167.415885] Starting dynamic subtest: eDP-1
[167.420054] Dynamic subtest eDP-1: SKIP (0.000s)
[167.424235] Subtest fbc-psr-no-drrs: SKIP (0.000s)
[167.494551] [018/133] (849s left) kms_cursor_edge_walk (256x256-left-edge)
[167.585279] Starting subtest: 256x256-left-edge
[167.587562] Starting dynamic subtest: pipe-A-eDP-1
[170.985928] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.467s)
[170.996192] Starting dynamic subtest: pipe-D-eDP-1
[174.079666] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[174.081814] Subtest 256x256-left-edge: SUCCESS (6.602s)
[174.905102] [019/133] (842s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[175.007132] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[175.011616] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[175.100407] [020/133] (842s left) syncobj_timeline (wait-all-for-submit-complex)
[175.154040] Starting subtest: wait-all-for-submit-complex
[175.367683] Subtest wait-all-for-submit-complex: SUCCESS (0.215s)
[175.461560] [021/133] (841s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[175.641592] Starting subtest: cursor-rapid-movement-32x10
[175.704879] Subtest cursor-rapid-movement-32x10: SKIP (0.061s)
[175.981406] [022/133] (841s left) prime_busy (after)
[176.045535] Starting subtest: after
[176.047888] Starting dynamic subtest: rcs0
[176.093368] Dynamic subtest rcs0: SUCCESS (0.046s)
[176.104658] Starting dynamic subtest: bcs0
[176.156639] Dynamic subtest bcs0: SUCCESS (0.063s)
[176.167144] Starting dynamic subtest: vcs0
[176.197761] Dynamic subtest vcs0: SUCCESS (0.041s)
[176.207158] Starting dynamic subtest: vcs1
[176.239706] Dynamic subtest vcs1: SUCCESS (0.042s)
[176.249282] Starting dynamic subtest: vecs0
[176.280012] Dynamic subtest vecs0: SUCCESS (0.040s)
[176.289389] Starting dynamic subtest: ccs0
[176.321209] Dynamic subtest ccs0: SUCCESS (0.041s)
[176.429092] Subtest after: SUCCESS (0.386s)
[176.515830] [023/133] (840s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[176.578846] Subtest writes-after-reads: SKIP (0.000s)
[176.657214] [024/133] (840s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[176.763085] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[176.767534] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[176.867363] [025/133] (840s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[176.980046] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[176.984220] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[177.072661] [026/133] (840s left) kms_flip (2x-flip-vs-modeset)
[177.176443] Starting subtest: 2x-flip-vs-modeset
[177.187972] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[177.335369] [027/133] (840s left) gen7_exec_parse (basic-offset)
[177.400539] Subtest basic-offset: SKIP (0.000s)
[177.479378] [028/133] (840s left) i915_drm_fdinfo (busy-check-all)
[177.516492] Starting subtest: busy-check-all
[177.518493] Starting dynamic subtest: rcs0
[177.522359] Dynamic subtest rcs0: SKIP (0.003s)
[177.532302] Starting dynamic subtest: bcs0
[177.536143] Dynamic subtest bcs0: SKIP (0.000s)
[177.546626] Starting dynamic subtest: vcs0
[177.550001] Dynamic subtest vcs0: SKIP (0.000s)
[177.557910] Starting dynamic subtest: vcs1
[177.561341] Dynamic subtest vcs1: SKIP (0.000s)
[177.569150] Starting dynamic subtest: vecs0
[177.572367] Dynamic subtest vecs0: SKIP (0.000s)
[177.580146] Starting dynamic subtest: ccs0
[177.583398] Dynamic subtest ccs0: SKIP (0.000s)
[177.586724] Subtest busy-check-all: SKIP (0.007s)
[177.619981] [029/133] (840s left) gem_exec_reloc (basic-gtt-active)
[177.731297] Subtest basic-gtt-active: SKIP (0.000s)
[177.813961] [030/133] (839s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[177.910586] Starting subtest: 4-tiled-8bpp-rotate-270
[177.931130] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[178.075935] [031/133] (839s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[178.169773] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[178.173499] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[178.306936] [032/133] (839s left) gem_mmap_wc (coherency)
[178.365039] Subtest coherency: SKIP (0.000s)
[178.434919] [033/133] (839s left) gem_softpin (evict-snoop)
[178.504879] Starting subtest: evict-snoop
[178.508710] Subtest evict-snoop: SKIP (0.000s)
[178.639450] [034/133] (839s left) kms_setmode (basic)
[178.695709] Starting subtest: basic
[179.016850] Starting dynamic subtest: pipe-A-eDP-1
[182.530392] Dynamic subtest pipe-A-eDP-1: FAIL (3.419s)
[182.773283] Starting dynamic subtest: pipe-B-eDP-1
[186.353145] Dynamic subtest pipe-B-eDP-1: FAIL (3.467s)
[186.522376] Subtest basic: FAIL (7.857s)
[187.329082] [035/133] (830s left) i915_selftest (perf)
[190.886783] Starting subtest: perf
[190.888958] Starting dynamic subtest: engine_cs
[195.407758] Dynamic subtest engine_cs: SUCCESS (4.522s)
[195.417911] Starting dynamic subtest: request
[200.850395] Dynamic subtest request: SUCCESS (5.434s)
[200.860280] Starting dynamic subtest: migrate
[203.829035] Dynamic subtest migrate: SUCCESS (2.975s)
[203.845132] Starting dynamic subtest: region
[208.316636] Dynamic subtest region: SUCCESS (4.483s)
[208.319438] Subtest perf: SUCCESS (17.420s)
[208.348967] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[208.351950] [036/133] (809s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[210.011333] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[210.015308] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[210.092451] [FACT kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)] new: kernel.kmod_is_loaded.i915: true
[210.096001] [037/133] (807s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[210.191104] Starting subtest: plane-scaler-unity-scaling-with-rotation
[210.193467] Starting dynamic subtest: pipe-A
[210.427744] Dynamic subtest pipe-A: SUCCESS (0.238s)
[210.437552] Starting dynamic subtest: pipe-B
[211.698785] Dynamic subtest pipe-B: SUCCESS (1.270s)
[211.708511] Starting dynamic subtest: pipe-C
[213.035288] Dynamic subtest pipe-C: SUCCESS (1.335s)
[213.045127] Starting dynamic subtest: pipe-D
[214.378289] Dynamic subtest pipe-D: SUCCESS (1.342s)
[214.380659] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.188s)
[215.578847] [038/133] (802s left) gem_mmap_gtt (basic-read)
[215.646436] Subtest basic-read: SKIP (0.000s)
[215.711454] [039/133] (802s left) kms_properties (plane-properties-atomic)
[215.806316] Starting subtest: plane-properties-atomic
[215.808201] Starting dynamic subtest: pipe-A-eDP-1
[216.380393] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s)
[216.392012] Starting dynamic subtest: pipe-B-eDP-1
[217.637628] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.254s)
[217.647646] Starting dynamic subtest: pipe-C-eDP-1
[218.879400] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.240s)
[218.890138] Starting dynamic subtest: pipe-D-eDP-1
[220.117840] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[220.119811] Subtest plane-properties-atomic: SUCCESS (4.310s)
[220.927963] [040/133] (797s left) kms_color (invalid-gamma-lut-sizes)
[221.024931] Starting subtest: invalid-gamma-lut-sizes
[221.027098] Starting dynamic subtest: pipe-A
[221.314116] Dynamic subtest pipe-A: SUCCESS (0.289s)
[221.323170] Starting dynamic subtest: pipe-B
[221.325337] Dynamic subtest pipe-B: SUCCESS (0.000s)
[221.333678] Starting dynamic subtest: pipe-C
[221.335858] Dynamic subtest pipe-C: SUCCESS (0.000s)
[221.344243] Starting dynamic subtest: pipe-D
[221.346384] Dynamic subtest pipe-D: SUCCESS (0.000s)
[221.348592] Subtest invalid-gamma-lut-sizes: SUCCESS (0.292s)
[222.160454] [041/133] (796s left) dmabuf_sync_file (export-before-signal)
[222.226273] Starting subtest: export-before-signal
[222.228387] Subtest export-before-signal: SUCCESS (0.001s)
[222.294611] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[222.298303] [042/133] (795s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[222.407665] Starting subtest: psr-1p-pri-indfb-multidraw
[245.330579] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.930s)
[245.479117] [043/133] (772s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[245.576665] Starting subtest: compare-crc-sanitycheck-xr24
[245.578539] Starting dynamic subtest: pipe-A-eDP-1
[246.192199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.613s)
[246.202094] Starting dynamic subtest: pipe-B-eDP-1
[247.469952] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.277s)
[247.480582] Starting dynamic subtest: pipe-C-eDP-1
[248.708719] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.240s)
[248.718941] Starting dynamic subtest: pipe-D-eDP-1
[249.976244] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.264s)
[249.978507] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.401s)
[250.817994] [044/133] (767s left) gem_readwrite (new-obj)
[250.888915] Subtest new-obj: SKIP (0.000s)
[250.958153] [045/133] (767s left) i915_pm_freq_mult (media-freq)
[251.021699] Starting subtest: media-freq
[251.024131] Starting dynamic subtest: gt0
[251.028641] Dynamic subtest gt0: SKIP (0.000s)
[251.039360] Starting dynamic subtest: gt1
[251.043754] Dynamic subtest gt1: SKIP (0.000s)
[251.048231] Subtest media-freq: SKIP (0.002s)
[251.155635] [046/133] (767s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[251.258803] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[251.262988] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[251.391530] [047/133] (767s left) kms_cursor_crc (cursor-offscreen-512x170)
[251.668054] Starting subtest: cursor-offscreen-512x170
[251.672460] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[251.842508] [048/133] (766s left) perf (i915-ref-count)
[251.934359] Starting subtest: i915-ref-count
[252.005125] Subtest i915-ref-count: SUCCESS (0.069s)
[252.085042] [049/133] (766s left) kms_async_flips (alternate-sync-async-flip)
[252.179638] Starting subtest: alternate-sync-async-flip
[252.542484] Starting dynamic subtest: pipe-A-eDP-1
[255.293029] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.752s)
[255.666429] Starting dynamic subtest: pipe-B-eDP-1
[258.429899] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.772s)
[258.812186] Starting dynamic subtest: pipe-C-eDP-1
[261.562622] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.756s)
[261.932097] Starting dynamic subtest: pipe-D-eDP-1
[264.696248] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.771s)
[264.698392] Subtest alternate-sync-async-flip: SUCCESS (12.518s)
[265.880419] [050/133] (752s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[265.940172] Starting subtest: invalid-multi-wait-unsubmitted
[265.942375] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[266.068074] [051/133] (752s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[266.164041] Starting subtest: primary-y-tiled-reflect-x-270
[266.168218] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[266.252444] [052/133] (752s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[266.355484] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[266.357723] Starting dynamic subtest: pipe-A-eDP-1
[267.181449] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.820s)
[267.190348] Starting dynamic subtest: pipe-B-eDP-1
[268.681651] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.499s)
[268.690664] Starting dynamic subtest: pipe-C-eDP-1
[270.244980] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.560s)
[270.256365] Starting dynamic subtest: pipe-D-eDP-1
[271.752872] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.507s)
[271.754800] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.391s)
[272.909722] [053/133] (745s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[273.022926] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[279.571197] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.550s)
[279.752675] [054/133] (739s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[279.855622] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[279.859696] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[279.988418] [055/133] (738s left) gem_mmap_offset (close-race)
[280.049470] Starting subtest: close-race
[300.157238] Subtest close-race: SUCCESS (20.107s)
[300.321907] [056/133] (718s left) vgem_basic (dmabuf-export)
[300.383487] Starting subtest: dmabuf-export
[300.423967] Subtest dmabuf-export: SUCCESS (0.045s)
[300.542773] [057/133] (718s left) syncobj_eventfd (timeline-wait)
[300.594918] Starting subtest: timeline-wait
[300.603837] Subtest timeline-wait: SUCCESS (0.010s)
[300.690878] [058/133] (718s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[300.782120] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[300.786114] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[300.868139] [059/133] (718s left) gem_exec_whisper (basic-normal-all)
[300.937473] Starting subtest: basic-normal-all
[303.203214] Subtest basic-normal-all: SUCCESS (2.263s)
[303.411230] [060/133] (715s left) kms_big_fb (linear-8bpp-rotate-0)
[303.520345] Starting subtest: linear-8bpp-rotate-0
[304.701466] Subtest linear-8bpp-rotate-0: SUCCESS (1.181s)
[304.847983] [061/133] (714s left) fbdev (unaligned-write)
[304.899052] Starting subtest: unaligned-write
[304.997771] Subtest unaligned-write: SUCCESS (0.100s)
[305.072202] [062/133] (714s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[305.156957] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[305.158951] Starting dynamic subtest: pipe-A-eDP-1
[306.047182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.867s)
[306.058542] Starting dynamic subtest: pipe-B-eDP-1
[307.564891] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.528s)
[307.575536] Starting dynamic subtest: pipe-C-eDP-1
[309.023979] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.459s)
[309.036050] Starting dynamic subtest: pipe-D-eDP-1
[310.581804] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.555s)
[310.584335] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.415s)
[311.740882] [063/133] (707s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[311.847699] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[311.851928] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[311.967642] [064/133] (707s left) gem_exec_reloc (basic-wc-read-active)
[312.032400] Subtest basic-wc-read-active: SKIP (0.000s)
[312.105537] [065/133] (707s left) gem_userptr_blits (nohangcheck)
[312.193609] Starting subtest: nohangcheck
[312.471681] Subtest nohangcheck: SUCCESS (0.274s)
[312.616829] [066/133] (706s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[312.713574] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[312.715906] Starting dynamic subtest: A-eDP1
[321.579169] Dynamic subtest A-eDP1: SUCCESS (8.865s)
[321.588617] Starting dynamic subtest: B-eDP1
[330.510918] Dynamic subtest B-eDP1: SUCCESS (8.931s)
[330.522163] Starting dynamic subtest: C-eDP1
[339.420933] Dynamic subtest C-eDP1: SUCCESS (8.909s)
[339.436409] Starting dynamic subtest: D-eDP1
[348.354930] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[348.359081] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.650s)
[349.584067] [067/133] (669s left) kms_getfb (getfb2-handle-zero)
[349.687354] Starting subtest: getfb2-handle-zero
[349.689686] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[349.847668] [068/133] (669s left) gem_partial_pwrite_pread (writes-after-reads)
[349.918476] Subtest writes-after-reads: SKIP (0.000s)
[350.011090] [069/133] (669s left) prime_mmap (test_map_unmap)
[350.068584] Starting subtest: test_map_unmap
[350.070959] Starting dynamic subtest: test_map_unmap-smem
[350.097525] Dynamic subtest test_map_unmap-smem: SUCCESS (0.031s)
[350.099928] Subtest test_map_unmap: SUCCESS (0.032s)
[350.214115] [070/133] (669s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[350.315120] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[350.317316] Starting dynamic subtest: pipe-A-eDP-1
[350.376705] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[350.387933] Starting dynamic subtest: pipe-B-eDP-1
[350.446474] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[350.457613] Starting dynamic subtest: pipe-C-eDP-1
[350.514205] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[350.524406] Starting dynamic subtest: pipe-D-eDP-1
[350.571452] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[350.575609] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[352.650355] [071/133] (666s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[352.768412] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[352.772650] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[352.890140] [072/133] (666s left) kms_prime (basic-crc-vgem)
[352.979947] Starting subtest: basic-crc-vgem
[352.982134] Starting dynamic subtest: second-to-first
[353.978194] Dynamic subtest second-to-first: SUCCESS (1.002s)
[353.980429] Subtest basic-crc-vgem: SUCCESS (1.002s)
[354.084178] [073/133] (665s left) gem_ctx_persistence (legacy-engines-hang)
[354.198553] Starting subtest: legacy-engines-hang
[354.200628] Starting dynamic subtest: render
[354.365980] Dynamic subtest render: SUCCESS (0.166s)
[354.378754] Starting dynamic subtest: bsd1
[354.481701] Dynamic subtest bsd1: SUCCESS (0.113s)
[354.495776] Starting dynamic subtest: bsd2
[354.593134] Dynamic subtest bsd2: SUCCESS (0.108s)
[354.605340] Starting dynamic subtest: blt
[354.695740] Dynamic subtest blt: SUCCESS (0.100s)
[354.709957] Starting dynamic subtest: vebox
[354.821947] Dynamic subtest vebox: SUCCESS (0.123s)
[354.824443] Subtest legacy-engines-hang: SUCCESS (0.624s)
[354.915133] [074/133] (664s left) kms_psr (fbc-pr-sprite-plane-move)
[355.012200] Starting subtest: fbc-pr-sprite-plane-move
[355.015516] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[355.096652] [075/133] (664s left) vgem_slow (nohang)
[355.138082] Starting subtest: nohang
[365.211829] Subtest nohang: SUCCESS (10.075s)
[365.290470] [076/133] (654s left) gem_ctx_persistence (engines-hostile)
[365.362811] Starting subtest: engines-hostile
[365.365271] Starting dynamic subtest: rcs0
[365.489712] Dynamic subtest rcs0: SUCCESS (0.126s)
[365.501501] Starting dynamic subtest: bcs0
[365.635154] Dynamic subtest bcs0: SUCCESS (0.143s)
[365.647430] Starting dynamic subtest: vcs0
[365.757054] Dynamic subtest vcs0: SUCCESS (0.120s)
[365.773046] Starting dynamic subtest: vcs1
[365.836620] Dynamic subtest vcs1: SUCCESS (0.079s)
[365.847891] Starting dynamic subtest: vecs0
[365.993569] Dynamic subtest vecs0: SUCCESS (0.154s)
[366.006644] Starting dynamic subtest: ccs0
[366.077679] Dynamic subtest ccs0: SUCCESS (0.082s)
[366.080116] Subtest engines-hostile: SUCCESS (0.716s)
[366.277399] [077/133] (653s left) syncobj_basic (bad-destroy)
[366.343003] Starting subtest: bad-destroy
[366.345141] Subtest bad-destroy: SUCCESS (0.000s)
[366.466090] [078/133] (653s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[366.568794] Starting subtest: fbc-rgb565-draw-blt
[370.733021] Subtest fbc-rgb565-draw-blt: SUCCESS (4.167s)
[371.969871] [079/133] (647s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[372.079208] Starting subtest: psr2-cursor-plane-update-sf
[372.081546] Starting dynamic subtest: pipe-A-eDP-1
[372.725099] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.649s)
[372.735223] Starting dynamic subtest: pipe-B-eDP-1
[374.012917] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.288s)
[374.015261] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.941s)
[374.900026] [080/133] (645s left) kms_lease (lease-invalid-plane)
[375.005291] Starting subtest: lease-invalid-plane
[375.007684] Starting dynamic subtest: pipe-A-eDP-1
[375.010035] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[375.019316] Starting dynamic subtest: pipe-B-eDP-1
[375.021705] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[375.031290] Starting dynamic subtest: pipe-C-eDP-1
[375.033577] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[375.042718] Starting dynamic subtest: pipe-D-eDP-1
[375.044663] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[375.046598] Subtest lease-invalid-plane: SUCCESS (0.004s)
[375.105837] [081/133] (644s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[375.199727] Starting subtest: yf-tiled-8bpp-rotate-0
[375.204100] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[375.319631] [082/133] (644s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[375.419095] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[375.423413] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[375.521327] [083/133] (644s left) kms_atomic (crtc-invalid-params-fence)
[375.610465] Starting subtest: crtc-invalid-params-fence
[375.612821] Starting dynamic subtest: pipe-A-eDP-1
[376.009229] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.400s)
[376.011598] Subtest crtc-invalid-params-fence: SUCCESS (0.402s)
[376.822021] [084/133] (643s left) gem_softpin (32b-excludes-last-page)
[376.893692] Starting subtest: 32b-excludes-last-page
[376.898941] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[377.056988] [085/133] (643s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[377.160454] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[377.164572] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[377.295422] [086/133] (642s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[377.391667] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[377.396125] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[377.504358] [087/133] (642s left) prime_vgem (basic-read)
[377.580054] Starting subtest: basic-read
[377.584550] Subtest basic-read: SKIP (0.005s)
[377.733927] [088/133] (642s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[377.827554] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[377.853356] Starting dynamic subtest: pipe-A-plane-0
[377.856879] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[377.867586] Starting dynamic subtest: pipe-A-plane-3
[377.871341] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[377.880420] Starting dynamic subtest: pipe-A-plane-5
[377.884141] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[378.994036] Starting dynamic subtest: pipe-B-plane-0
[378.998174] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[379.007560] Starting dynamic subtest: pipe-B-plane-3
[379.011695] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[379.021138] Starting dynamic subtest: pipe-B-plane-5
[379.025513] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[379.297428] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (1.467s)
[380.173687] [089/133] (640s left) syncobj_timeline (invalid-query-zero-handles)
[380.232146] Starting subtest: invalid-query-zero-handles
[380.234400] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[380.338228] [090/133] (639s left) perf_pmu (invalid-open)
[380.379916] Starting subtest: invalid-open
[380.382212] Subtest invalid-open: SUCCESS (0.000s)
[380.502777] [091/133] (639s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[380.603310] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[380.607174] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[380.713609] [092/133] (639s left) kms_busy (extended-modeset-hang-oldfb)
[380.803190] Starting subtest: extended-modeset-hang-oldfb
[380.805154] Starting dynamic subtest: pipe-A
[392.917636] Dynamic subtest pipe-A: SUCCESS (12.114s)
[392.928171] Starting dynamic subtest: pipe-D
[405.487620] Dynamic subtest pipe-D: SUCCESS (12.562s)
[405.490023] Subtest extended-modeset-hang-oldfb: SUCCESS (24.681s)
[406.312512] [093/133] (614s left) gem_exec_fence (syncobj-timeline-chain-engines)
[406.860512] Starting subtest: syncobj-timeline-chain-engines
[406.955355] Subtest syncobj-timeline-chain-engines: SUCCESS (0.100s)
[408.254703] [094/133] (612s left) kms_rotation_crc (primary-rotation-90)
[408.361738] Starting subtest: primary-rotation-90
[408.365772] Subtest primary-rotation-90: SKIP (0.001s)
[408.480411] [095/133] (612s left) kms_chamelium_hpd (dp-hpd-fast)
[408.870482] Subtest dp-hpd-fast: SKIP (0.000s)
[409.696690] [096/133] (610s left) gem_create (create-ext-cpu-access-sanity-check)
[409.868534] Starting subtest: create-ext-cpu-access-sanity-check
[409.874050] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[409.965696] [097/133] (610s left) vgem_basic (bad-flag)
[410.006932] Starting subtest: bad-flag
[410.009169] Subtest bad-flag: SUCCESS (0.000s)
[410.092032] [098/133] (610s left) kms_pm_backlight (fade-with-dpms)
[410.192108] Starting subtest: fade-with-dpms
[412.657875] Starting dynamic subtest: eDP-1
[415.343349] Dynamic subtest eDP-1: SUCCESS (2.685s)
[415.345791] Subtest fade-with-dpms: SUCCESS (5.150s)
[416.156362] [099/133] (604s left) gem_exec_fence (basic-wait)
[416.352300] Starting subtest: basic-wait
[416.354400] Starting dynamic subtest: rcs0
[416.449877] Dynamic subtest rcs0: SUCCESS (0.100s)
[416.462454] Starting dynamic subtest: bcs0
[416.508699] Dynamic subtest bcs0: SUCCESS (0.057s)
[416.520857] Starting dynamic subtest: vcs0
[416.573253] Dynamic subtest vcs0: SUCCESS (0.063s)
[416.584313] Starting dynamic subtest: vcs1
[416.627128] Dynamic subtest vcs1: SUCCESS (0.051s)
[416.637726] Starting dynamic subtest: vecs0
[416.687050] Dynamic subtest vecs0: SUCCESS (0.058s)
[416.698107] Starting dynamic subtest: ccs0
[416.752147] Dynamic subtest ccs0: SUCCESS (0.063s)
[416.754586] Subtest basic-wait: SUCCESS (0.405s)
[418.249887] [100/133] (602s left) core_getclient (basic)
[418.313446] Starting subtest: basic
[418.315726] Subtest basic: SUCCESS (0.000s)
[418.448579] [101/133] (602s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[418.549320] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[418.553570] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[418.674196] [102/133] (602s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[418.735285] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[418.737692] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[418.872646] [103/133] (602s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[418.945589] Starting subtest: yf-tiled-ccs-to-yf-tiled
[418.950275] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[419.153017] [104/133] (601s left) gem_exec_schedule (pi-common)
[419.343033] Starting subtest: pi-common
[419.346255] Starting dynamic subtest: rcs0
[419.368860] Dynamic subtest rcs0: SUCCESS (0.029s)
[419.383578] Starting dynamic subtest: bcs0
[419.397120] Dynamic subtest bcs0: SUCCESS (0.027s)
[419.411858] Starting dynamic subtest: vcs0
[419.424496] Dynamic subtest vcs0: SUCCESS (0.027s)
[419.438977] Starting dynamic subtest: vcs1
[419.450588] Dynamic subtest vcs1: SUCCESS (0.025s)
[419.464822] Starting dynamic subtest: vecs0
[419.478424] Dynamic subtest vecs0: SUCCESS (0.027s)
[419.493123] Starting dynamic subtest: ccs0
[419.506345] Dynamic subtest ccs0: SUCCESS (0.027s)
[419.508571] Subtest pi-common: SUCCESS (0.167s)
[419.750860] [105/133] (601s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[419.811834] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[419.880152] [106/133] (601s left) kms_vblank (ts-continuation-dpms-rpm)
[419.982123] Starting subtest: ts-continuation-dpms-rpm
[419.984277] Starting dynamic subtest: pipe-A-eDP-1
[422.474766] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.491s)
[422.491523] Starting dynamic subtest: pipe-D-eDP-1
[424.697815] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.222s)
[424.700240] Subtest ts-continuation-dpms-rpm: SUCCESS (4.717s)
[425.506455] [107/133] (595s left) gem_ctx_shared (single-timeline)
[425.569551] Starting subtest: single-timeline
[425.590934] Subtest single-timeline: SUCCESS (0.014s)
[425.842306] [108/133] (595s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[425.937926] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[425.942028] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[426.062368] [109/133] (595s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[426.168816] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[426.173026] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[426.312642] [110/133] (594s left) gem_exec_params (invalid-ring2)
[426.378596] Starting subtest: invalid-ring2
[426.381155] Subtest invalid-ring2: SUCCESS (0.000s)
[426.504895] [111/133] (594s left) kms_dirtyfb (default-dirtyfb-ioctl)
[426.600211] Starting subtest: default-dirtyfb-ioctl
[426.602680] Starting dynamic subtest: A-eDP-1
[428.183750] Dynamic subtest A-eDP-1: SUCCESS (1.584s)
[428.186164] Subtest default-dirtyfb-ioctl: SUCCESS (1.586s)
[429.031828] [112/133] (592s left) kms_psr (pr-primary-render)
[429.135207] Starting subtest: pr-primary-render
[429.139679] Subtest pr-primary-render: SKIP (0.000s)
[429.221238] [113/133] (592s left) gem_ctx_bad_destroy (double-destroy)
[429.276011] Starting subtest: double-destroy
[429.278975] Subtest double-destroy: SUCCESS (0.000s)
[429.365394] [114/133] (592s left) kms_plane_scaling (planes-downscale-factor-0-75)
[429.460173] Starting subtest: planes-downscale-factor-0-75
[429.462486] Starting dynamic subtest: pipe-A
[429.483244] Dynamic subtest pipe-A: SKIP (0.018s)
[429.493900] Starting dynamic subtest: pipe-B
[429.514661] Dynamic subtest pipe-B: SKIP (0.032s)
[429.524658] Starting dynamic subtest: pipe-C
[429.547937] Dynamic subtest pipe-C: SKIP (0.031s)
[429.557918] Starting dynamic subtest: pipe-D
[429.578617] Dynamic subtest pipe-D: SKIP (0.031s)
[429.582735] Subtest planes-downscale-factor-0-75: SKIP (0.116s)
[429.701429] [115/133] (591s left) kms_plane_multiple (tiling-none)
[429.812232] Starting subtest: tiling-none
[429.814659] Starting dynamic subtest: pipe-A-eDP-1
[433.530343] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.718s)
[433.543697] Starting dynamic subtest: pipe-B-eDP-1
[437.953816] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.421s)
[437.964366] Starting dynamic subtest: pipe-C-eDP-1
[442.274727] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.320s)
[442.284793] Starting dynamic subtest: pipe-D-eDP-1
[446.605791] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.327s)
[446.607718] Subtest tiling-none: SUCCESS (16.792s)
[447.428390] [116/133] (574s left) kms_draw_crc (draw-method-mmap-cpu)
[447.532780] Starting subtest: draw-method-mmap-cpu
[447.534663] Starting dynamic subtest: xrgb8888-untiled
[447.776647] Dynamic subtest xrgb8888-untiled: SUCCESS (0.245s)
[447.786387] Starting dynamic subtest: rgb565-untiled
[448.009391] Dynamic subtest rgb565-untiled: SUCCESS (0.230s)
[448.021342] Starting dynamic subtest: xrgb2101010-untiled
[448.231226] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[448.241752] Starting dynamic subtest: xbgr16161616f-untiled
[448.453562] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[448.463540] Starting dynamic subtest: xrgb8888-xtiled
[448.597868] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.144s)
[448.607457] Starting dynamic subtest: rgb565-xtiled
[448.731410] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[448.742957] Starting dynamic subtest: xrgb2101010-xtiled
[448.864745] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[448.874593] Starting dynamic subtest: xbgr16161616f-xtiled
[448.997755] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[449.007861] Starting dynamic subtest: xrgb8888-4tiled
[449.142540] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[449.154124] Starting dynamic subtest: rgb565-4tiled
[449.275914] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[449.285910] Starting dynamic subtest: xrgb2101010-4tiled
[449.408957] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.132s)
[449.418410] Starting dynamic subtest: xbgr16161616f-4tiled
[449.542348] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[449.544237] Subtest draw-method-mmap-cpu: SUCCESS (2.013s)
[449.662491] [117/133] (571s left) kms_psr (psr-primary-page-flip)
[449.761542] Starting subtest: psr-primary-page-flip
[449.763424] Starting dynamic subtest: eDP-1
[451.518265] Dynamic subtest eDP-1: SUCCESS (1.755s)
[451.520767] Subtest psr-primary-page-flip: SUCCESS (1.757s)
[452.391484] [118/133] (569s left) kms_content_protection (dp-mst-type-0-hdcp14)
[452.547300] Starting subtest: dp-mst-type-0-hdcp14
[452.551684] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[452.713216] [119/133] (568s left) api_intel_allocator (alloc-simple)
[452.777098] Starting subtest: alloc-simple
[452.779339] Subtest alloc-simple: SUCCESS (0.003s)
[452.932170] [120/133] (568s left) gem_spin_batch (resubmit-new-all)
[453.000647] Starting subtest: resubmit-new-all
[453.003026] Starting dynamic subtest: rcs0
[453.014625] Dynamic subtest rcs0: SUCCESS (0.013s)
[453.026782] Starting dynamic subtest: bcs0
[453.029093] Dynamic subtest bcs0: SUCCESS (0.010s)
[453.041627] Starting dynamic subtest: vcs0
[453.043769] Dynamic subtest vcs0: SUCCESS (0.008s)
[453.053442] Starting dynamic subtest: vcs1
[453.055703] Dynamic subtest vcs1: SUCCESS (0.009s)
[453.065141] Starting dynamic subtest: vecs0
[453.067379] Dynamic subtest vecs0: SUCCESS (0.009s)
[453.076653] Starting dynamic subtest: ccs0
[453.078740] Dynamic subtest ccs0: SUCCESS (0.008s)
[453.080832] Subtest resubmit-new-all: SUCCESS (0.063s)
[454.197365] [121/133] (567s left) kms_joiner (invalid-modeset-ultra-joiner)
[454.297817] Starting subtest: invalid-modeset-ultra-joiner
[454.302024] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[454.389991] [122/133] (567s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[454.509936] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[454.514395] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[454.685981] [123/133] (567s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[455.071996] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[455.896022] [124/133] (565s left) gem_userptr_blits (unsync-overlap)
[455.977785] Subtest unsync-overlap: SKIP (0.000s)
[456.147227] [125/133] (565s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[456.246491] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[456.250556] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[456.375208] [126/133] (565s left) kms_sequence (get-busy)
[456.485302] Starting subtest: get-busy
[456.487192] Starting dynamic subtest: pipe-A-eDP-1
[458.419824] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.936s)
[458.431476] Starting dynamic subtest: pipe-B-eDP-1
[461.027008] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.606s)
[461.037741] Starting dynamic subtest: pipe-C-eDP-1
[463.639367] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.611s)
[463.650437] Starting dynamic subtest: pipe-D-eDP-1
[466.186902] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.545s)
[466.189122] Subtest get-busy: SUCCESS (9.702s)
[466.996097] [127/133] (554s left) gem_exec_balancer (hang)
[467.271112] Starting subtest: hang
[469.163721] Subtest hang: SUCCESS (1.896s)
[469.320189] [128/133] (552s left) kms_cdclk (plane-scaling)
[469.420989] Starting subtest: plane-scaling
[469.423134] Starting dynamic subtest: pipe-A-eDP-1
[469.707545] Dynamic subtest pipe-A-eDP-1: SKIP (0.285s)
[469.718169] Starting dynamic subtest: pipe-B-eDP-1
[469.722603] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[469.732843] Starting dynamic subtest: pipe-C-eDP-1
[469.737630] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[469.747532] Starting dynamic subtest: pipe-D-eDP-1
[469.751723] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[469.755788] Subtest plane-scaling: SKIP (0.293s)
[470.520572] [129/133] (551s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[470.619356] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[470.623245] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[470.756148] [130/133] (551s left) kms_flip (flip-vs-wf_vblank-interruptible)
[470.852661] Starting subtest: flip-vs-wf_vblank-interruptible
[470.854924] Starting dynamic subtest: A-eDP1
[472.746704] Dynamic subtest A-eDP1: SUCCESS (1.892s)
[472.758850] Starting dynamic subtest: B-eDP1
[474.674919] Dynamic subtest B-eDP1: SUCCESS (1.926s)
[474.687998] Starting dynamic subtest: C-eDP1
[476.601976] Dynamic subtest C-eDP1: SUCCESS (1.925s)
[476.616546] Starting dynamic subtest: D-eDP1
[478.457515] Dynamic subtest D-eDP1: SUCCESS (1.854s)
[478.462730] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.608s)
[479.696240] [131/133] (542s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[479.807615] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[479.811738] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[479.933250] [132/133] (542s left) gem_mmap_wc (set-cache-level)
[479.996369] Subtest set-cache-level: SKIP (0.000s)
[480.052685] [133/133] (542s left) gem_exec_params (cliprects_ptr-dirt)
[480.113483] Starting subtest: cliprects_ptr-dirt
[480.115917] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[480.233685] 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.