[59.991415] Initializing watchdogs
[59.991461]   /dev/watchdog0
[60.005110] [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]
[60.009320] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.011452] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.014926] [001/133] (960s left) gem_mmap_gtt (basic-write)
[60.098464] Subtest basic-write: SKIP (0.000s)
[60.157067] [002/133] (960s left) gem_caching (read-writes)
[60.214799] Subtest read-writes: SKIP (0.000s)
[60.286518] [003/133] (960s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[60.390725] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[60.427233] Starting dynamic subtest: pipe-A-plane-0
[60.740127] Dynamic subtest pipe-A-plane-0: SUCCESS (0.311s)
[60.749319] Starting dynamic subtest: pipe-A-plane-3
[60.973436] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[60.981327] Starting dynamic subtest: pipe-A-plane-5
[60.984775] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[62.118827] Starting dynamic subtest: pipe-B-plane-0
[62.412607] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[62.421125] Starting dynamic subtest: pipe-B-plane-3
[62.644656] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[62.653353] Starting dynamic subtest: pipe-B-plane-5
[62.660046] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[62.954586] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.558s)
[63.782550] [004/133] (956s left) gem_ctx_shared (q-out-order)
[63.857925] Starting subtest: Q-out-order
[63.860606] Starting dynamic subtest: rcs0
[63.975149] Dynamic subtest rcs0: SUCCESS (0.120s)
[63.988674] Starting dynamic subtest: vcs0
[64.050462] Dynamic subtest vcs0: SUCCESS (0.075s)
[64.064386] Starting dynamic subtest: vcs1
[64.126276] Dynamic subtest vcs1: SUCCESS (0.075s)
[64.140140] Starting dynamic subtest: vecs0
[64.200794] Dynamic subtest vecs0: SUCCESS (0.074s)
[64.215107] Starting dynamic subtest: ccs0
[64.289860] Dynamic subtest ccs0: SUCCESS (0.089s)
[64.292680] Subtest Q-out-order: SUCCESS (0.435s)
[66.011115] [FACT gem_ctx_shared (q-out-order)] new: kernel.kmod_is_loaded.vgem: true
[66.014509] [005/133] (954s left) kms_atomic_interruptible (universal-setplane-cursor)
[66.111732] Starting subtest: universal-setplane-cursor
[66.114070] Starting dynamic subtest: pipe-A-eDP-1
[73.519194] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.398s)
[73.521560] Subtest universal-setplane-cursor: SUCCESS (7.399s)
[74.365844] [006/133] (946s left) kms_content_protection (dp-mst-lic-type-0)
[74.501764] Starting subtest: dp-mst-lic-type-0
[74.507256] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[74.608554] [007/133] (946s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[74.702387] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[78.785417] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.083s)
[79.987096] [008/133] (940s left) kms_sequence (queue-busy)
[80.080863] Starting subtest: queue-busy
[80.083268] Starting dynamic subtest: pipe-A-eDP-1
[82.003168] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.921s)
[82.012225] Starting dynamic subtest: pipe-B-eDP-1
[84.580528] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.572s)
[84.591889] Starting dynamic subtest: pipe-C-eDP-1
[87.191901] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.609s)
[87.201225] Starting dynamic subtest: pipe-D-eDP-1
[89.743587] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.547s)
[89.745593] Subtest queue-busy: SUCCESS (9.653s)
[90.542176] [009/133] (930s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[90.645666] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[90.650271] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[90.746533] [010/133] (930s left) gem_exec_balancer (indices)
[90.800509] Starting subtest: indices
[92.851637] Subtest indices: SUCCESS (2.047s)
[93.149422] [011/133] (927s left) kms_pm_rpm (pm-caching)
[95.132537] Starting subtest: pm-caching
[95.137470] Subtest pm-caching: SKIP (0.000s)
[95.508557] [012/133] (925s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[95.597770] Starting subtest: addfb25-y-tiled-legacy
[95.600115] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[95.691889] [013/133] (925s left) gem_exec_schedule (preempt-self)
[95.765232] Starting subtest: preempt-self
[95.767506] Starting dynamic subtest: rcs0
[95.784037] Dynamic subtest rcs0: SUCCESS (0.026s)
[95.794541] Starting dynamic subtest: bcs0
[95.801733] Dynamic subtest bcs0: SUCCESS (0.018s)
[95.809114] Starting dynamic subtest: vcs0
[95.821021] Dynamic subtest vcs0: SUCCESS (0.019s)
[95.828409] Starting dynamic subtest: vcs1
[95.838301] Dynamic subtest vcs1: SUCCESS (0.017s)
[95.845831] Starting dynamic subtest: vecs0
[95.856873] Dynamic subtest vecs0: SUCCESS (0.018s)
[95.864250] Starting dynamic subtest: ccs0
[95.875100] Dynamic subtest ccs0: SUCCESS (0.018s)
[95.877574] Subtest preempt-self: SUCCESS (0.118s)
[96.989930] [014/133] (924s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[97.386719] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[98.212737] [015/133] (922s left) kms_getfb (getfb-handle-not-fb)
[98.318931] Starting subtest: getfb-handle-not-fb
[98.321329] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[98.446698] [016/133] (922s left) i915_drm_fdinfo (virtual-busy-hang)
[98.485584] Starting subtest: virtual-busy-hang
[98.490053] Subtest virtual-busy-hang: SKIP (0.000s)
[98.552672] [017/133] (922s left) gem_lmem_swapping (random-engines)
[98.604735] Subtest random-engines: SKIP (0.000s)
[98.649738] [018/133] (922s left) gem_mmap_wc (bad-size)
[98.717701] Subtest bad-size: SKIP (0.000s)
[98.783243] [019/133] (922s left) gem_exec_whisper (basic-queues-forked)
[98.840523] Starting subtest: basic-queues-forked
[122.108497] Subtest basic-queues-forked: SUCCESS (23.258s)
[122.289656] [020/133] (898s left) kms_setmode (invalid-clone-single-crtc)
[122.352921] Starting subtest: invalid-clone-single-crtc
[122.359967] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[122.443482] [021/133] (898s left) kms_busy (basic-hang)
[122.538030] Starting subtest: basic-hang
[122.540093] Starting dynamic subtest: flip-pipe-A
[123.136765] Dynamic subtest flip-pipe-A: SUCCESS (0.597s)
[123.146919] Starting dynamic subtest: modeset-pipe-A
[126.580496] Dynamic subtest modeset-pipe-A: SUCCESS (3.444s)
[126.590062] Starting dynamic subtest: flip-pipe-D
[127.783498] Dynamic subtest flip-pipe-D: SUCCESS (1.198s)
[127.792279] Starting dynamic subtest: modeset-pipe-D
[131.214184] Dynamic subtest modeset-pipe-D: SUCCESS (3.430s)
[131.216743] Subtest basic-hang: SUCCESS (8.675s)
[132.015677] [022/133] (889s left) gen9_exec_parse (cmd-crossing-page)
[132.071539] Subtest cmd-crossing-page: SKIP (0.000s)
[132.129870] [023/133] (889s left) gem_exec_fence (nb-await)
[132.303245] Starting subtest: nb-await
[132.305833] Starting dynamic subtest: rcs0
[132.372496] Dynamic subtest rcs0: SUCCESS (0.069s)
[132.383693] Starting dynamic subtest: bcs0
[132.429366] Dynamic subtest bcs0: SUCCESS (0.056s)
[132.440060] Starting dynamic subtest: vcs0
[132.488220] Dynamic subtest vcs0: SUCCESS (0.058s)
[132.498319] Starting dynamic subtest: vcs1
[132.547416] Dynamic subtest vcs1: SUCCESS (0.058s)
[132.557652] Starting dynamic subtest: vecs0
[132.605307] Dynamic subtest vecs0: SUCCESS (0.057s)
[132.615349] Starting dynamic subtest: ccs0
[132.663510] Dynamic subtest ccs0: SUCCESS (0.057s)
[132.666084] Subtest nb-await: SUCCESS (0.361s)
[134.005203] [024/133] (887s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[134.088494] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[134.092880] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[134.159916] [025/133] (887s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[134.223250] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[134.225508] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[134.364769] [026/133] (887s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[134.471046] Starting subtest: cursor-tearing-framebuffer-change
[134.473286] Starting dynamic subtest: pipe-A-eDP-1
[135.054735] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.580s)
[135.064536] Starting dynamic subtest: pipe-D-eDP-1
[136.249738] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.196s)
[136.252055] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.778s)
[137.080016] [027/133] (884s left) gem_wait (write-wait)
[137.144338] Starting subtest: write-wait
[137.146614] Starting dynamic subtest: all-engines
[138.246393] Dynamic subtest all-engines: SUCCESS (1.102s)
[138.262891] Starting dynamic subtest: rcs0
[139.303694] Dynamic subtest rcs0: SUCCESS (1.056s)
[139.319862] Starting dynamic subtest: bcs0
[140.361579] Dynamic subtest bcs0: SUCCESS (1.056s)
[140.376566] Starting dynamic subtest: vcs0
[141.415894] Dynamic subtest vcs0: SUCCESS (1.054s)
[141.430840] Starting dynamic subtest: vcs1
[142.468437] Dynamic subtest vcs1: SUCCESS (1.051s)
[142.488012] Starting dynamic subtest: vecs0
[143.521305] Dynamic subtest vecs0: SUCCESS (1.052s)
[143.535769] Starting dynamic subtest: ccs0
[144.576471] Dynamic subtest ccs0: SUCCESS (1.054s)
[144.579100] Subtest write-wait: SUCCESS (7.433s)
[144.774317] [028/133] (876s left) gem_pwrite_snooped
[144.922932] [029/133] (876s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[144.970331] Starting subtest: invalid-single-wait-all-unsubmitted
[144.972699] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[145.078516] [030/133] (876s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[145.130126] Subtest untiled-to-tiled: SKIP (0.000s)
[145.202090] [031/133] (876s left) gem_ctx_persistence (many-contexts)
[145.249068] Starting subtest: many-contexts
[190.445057] Subtest many-contexts: SUCCESS (45.195s)
[190.524068] [032/133] (831s left) kms_cursor_crc (cursor-offscreen-64x64)
[190.694357] Starting subtest: cursor-offscreen-64x64
[190.699247] Starting dynamic subtest: pipe-A-eDP-1
[192.653062] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.955s)
[192.679376] Starting dynamic subtest: pipe-D-eDP-1
[195.284126] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.611s)
[195.286455] Subtest cursor-offscreen-64x64: SUCCESS (4.590s)
[196.157043] [033/133] (825s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[196.210041] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[196.312475] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.102s)
[196.389408] [034/133] (825s left) i915_drm_fdinfo (virtual-busy-idle)
[196.429474] Starting subtest: virtual-busy-idle
[196.433835] Subtest virtual-busy-idle: SKIP (0.000s)
[196.502552] [035/133] (825s left) gem_exec_reloc (basic-wc-read-active)
[196.569908] Subtest basic-wc-read-active: SKIP (0.000s)
[196.633024] [036/133] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[196.745962] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[196.750248] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[196.834102] [037/133] (825s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[196.928210] Starting subtest: fbc-psr2-primary-mmap-cpu
[196.930509] Starting dynamic subtest: eDP-1
[196.934676] Dynamic subtest eDP-1: SKIP (0.000s)
[196.938831] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[196.999577] [038/133] (824s left) kms_plane_scaling (planes-upscale-factor-0-25)
[197.082066] Starting subtest: planes-upscale-factor-0-25
[197.084386] Starting dynamic subtest: pipe-A
[197.144536] Dynamic subtest pipe-A: SUCCESS (0.062s)
[197.153448] Starting dynamic subtest: pipe-B
[198.286354] Dynamic subtest pipe-B: SUCCESS (1.141s)
[198.296846] Starting dynamic subtest: pipe-C
[199.401486] Dynamic subtest pipe-C: SUCCESS (1.114s)
[199.411358] Starting dynamic subtest: pipe-D
[200.534775] Dynamic subtest pipe-D: SUCCESS (1.133s)
[200.537072] Subtest planes-upscale-factor-0-25: SUCCESS (3.453s)
[201.696893] [039/133] (820s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[201.798817] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[201.803327] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[201.898097] [040/133] (820s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[201.996292] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[201.998543] Starting dynamic subtest: pipe-A-eDP-1
[202.081122] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[202.090403] Starting dynamic subtest: pipe-B-eDP-1
[203.278489] Dynamic subtest pipe-B-eDP-1: SKIP (1.196s)
[203.282836] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.287s)
[204.476564] [041/133] (817s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[204.580825] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[209.167908] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.591s)
[210.308764] [042/133] (811s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[210.409514] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[210.414103] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[210.517684] [043/133] (811s left) core_setmaster (master-drop-set-shared-fd)
[210.603231] Starting subtest: master-drop-set-shared-fd
[210.613195] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[210.651257] [044/133] (811s left) gem_exec_whisper (basic-forked)
[210.719257] Starting subtest: basic-forked
[218.056717] Subtest basic-forked: SUCCESS (7.336s)
[218.260641] [045/133] (804s left) kms_tv_load_detect (load-detect)
[218.329564] Subtest load-detect: SKIP (0.000s)
[218.385218] [046/133] (803s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[218.483899] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[218.486026] Starting dynamic subtest: pipe-A-eDP-1
[229.805580] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.325s)
[229.816913] Starting dynamic subtest: pipe-B-eDP-1
[241.811861] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.000s)
[241.814157] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.330s)
[242.656888] [047/133] (779s left) gem_exec_params (no-blt)
[242.718306] Starting subtest: no-blt
[242.722964] Subtest no-blt: SUCCESS (0.003s)
[242.835804] [048/133] (779s left) gem_exec_nop (basic-parallel)
[242.910650] Starting subtest: basic-parallel
[258.839437] Subtest basic-parallel: SUCCESS (15.937s)
[259.048928] [049/133] (763s left) dumb_buffer (map-uaf)
[259.111386] Starting subtest: map-uaf
[259.113428] Subtest map-uaf: SUCCESS (0.004s)
[259.214424] [050/133] (763s left) kms_hdr (invalid-metadata-sizes)
[259.313009] Starting subtest: invalid-metadata-sizes
[259.319476] Subtest invalid-metadata-sizes: SKIP (0.000s)
[259.398105] [051/133] (763s left) kms_flip (flip-vs-panning-vs-hang)
[259.484617] Starting subtest: flip-vs-panning-vs-hang
[259.487100] Starting dynamic subtest: A-eDP1
[310.393792] Dynamic subtest A-eDP1: SUCCESS (50.909s)
[310.402149] Starting dynamic subtest: D-eDP1
[350.400730] Dynamic subtest D-eDP1: SUCCESS (40.003s)
[350.403048] Subtest flip-vs-panning-vs-hang: SUCCESS (90.918s)
[351.577059] [052/133] (670s left) gem_exec_schedule (preempt-hang)
[351.750226] Starting subtest: preempt-hang
[351.752939] Starting dynamic subtest: rcs0
[351.959103] Dynamic subtest rcs0: SUCCESS (0.209s)
[351.968472] Starting dynamic subtest: bcs0
[352.168992] Dynamic subtest bcs0: SUCCESS (0.209s)
[352.178292] Starting dynamic subtest: vcs0
[352.399089] Dynamic subtest vcs0: SUCCESS (0.230s)
[352.407800] Starting dynamic subtest: vcs1
[352.635796] Dynamic subtest vcs1: SUCCESS (0.234s)
[352.646560] Starting dynamic subtest: vecs0
[352.864186] Dynamic subtest vecs0: SUCCESS (0.228s)
[352.874681] Starting dynamic subtest: ccs0
[353.093540] Dynamic subtest ccs0: SUCCESS (0.228s)
[353.095841] Subtest preempt-hang: SUCCESS (1.343s)
[353.960967] [053/133] (668s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[354.073029] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[354.077316] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[354.143299] [054/133] (668s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[354.246257] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[354.250558] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[354.363254] [055/133] (668s left) perf_pmu (busy-no-semaphores)
[354.401261] Starting subtest: busy-no-semaphores
[354.403212] Starting dynamic subtest: rcs0
[354.967422] Dynamic subtest rcs0: SUCCESS (0.565s)
[354.978945] Starting dynamic subtest: bcs0
[355.516459] Dynamic subtest bcs0: SUCCESS (0.547s)
[355.527939] Starting dynamic subtest: vcs0
[356.066289] Dynamic subtest vcs0: SUCCESS (0.547s)
[356.076828] Starting dynamic subtest: vcs1
[357.399842] Dynamic subtest vcs1: SUCCESS (1.331s)
[357.409717] Starting dynamic subtest: vecs0
[359.445487] Dynamic subtest vecs0: SUCCESS (2.043s)
[359.456082] Starting dynamic subtest: ccs0
[361.433427] Dynamic subtest ccs0: SUCCESS (1.983s)
[361.436473] Subtest busy-no-semaphores: SUCCESS (7.031s)
[361.564381] [056/133] (661s left) kms_setmode (clone-exclusive-crtc)
[361.622974] Starting subtest: clone-exclusive-crtc
[361.629663] Subtest clone-exclusive-crtc: SKIP (0.000s)
[361.728589] [057/133] (661s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[361.828905] Starting subtest: x-tiled-8bpp-rotate-180
[363.054224] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.227s)
[363.177635] [058/133] (659s left) kms_psr (psr2-primary-mmap-cpu)
[363.275114] Starting subtest: psr2-primary-mmap-cpu
[363.276972] Starting dynamic subtest: eDP-1
[365.048528] Dynamic subtest eDP-1: SUCCESS (1.771s)
[365.050877] Subtest psr2-primary-mmap-cpu: SUCCESS (1.773s)
[365.923343] [059/133] (656s left) kms_vblank (ts-continuation-idle)
[366.019645] Starting subtest: ts-continuation-idle
[366.021603] Starting dynamic subtest: pipe-A-eDP-1
[366.426967] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.408s)
[366.440501] Starting dynamic subtest: pipe-D-eDP-1
[367.528520] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.099s)
[367.530909] Subtest ts-continuation-idle: SUCCESS (1.510s)
[368.393871] [060/133] (654s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[368.674057] Starting subtest: cursor-rapid-movement-256x256
[368.679226] Starting dynamic subtest: pipe-A-eDP-1
[369.211544] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.534s)
[369.243220] Starting dynamic subtest: pipe-D-eDP-1
[370.390957] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.157s)
[370.393188] Subtest cursor-rapid-movement-256x256: SUCCESS (1.716s)
[371.235966] [061/133] (651s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[371.348105] Starting subtest: basic-flip-before-cursor-varying-size
[372.274485] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.925s)
[373.068804] [062/133] (649s left) gem_softpin (allocator-evict)
[373.132755] Starting subtest: allocator-evict
[373.135169] Starting dynamic subtest: rcs0
[396.351605] Dynamic subtest rcs0: SUCCESS (23.216s)
[396.360250] Starting dynamic subtest: bcs0
[419.988905] Dynamic subtest bcs0: SUCCESS (23.634s)
[419.998222] Starting dynamic subtest: vcs0
[443.754399] Dynamic subtest vcs0: SUCCESS (23.760s)
[443.766046] Starting dynamic subtest: vcs1
[467.389954] Dynamic subtest vcs1: SUCCESS (23.632s)
[467.401823] Starting dynamic subtest: vecs0
[491.138708] Dynamic subtest vecs0: SUCCESS (23.744s)
[491.154878] Starting dynamic subtest: ccs0
[515.084587] Dynamic subtest ccs0: SUCCESS (23.940s)
[515.087536] Subtest allocator-evict: SUCCESS (141.940s)
[515.176877] [063/133] (507s left) gem_mmap_offset (bad-flags)
[515.276412] Starting subtest: bad-flags
[515.278595] Subtest bad-flags: SUCCESS (0.000s)
[515.401072] [064/133] (507s left) gem_create (create-size-update)
[515.447397] Starting subtest: create-size-update
[515.449650] Subtest create-size-update: SUCCESS (0.000s)
[515.658295] [065/133] (507s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[515.772641] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[515.774993] Starting dynamic subtest: pipe-A-eDP-1
[518.024881] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.251s)
[518.033538] Starting dynamic subtest: pipe-B-eDP-1
[520.303414] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.277s)
[520.312205] Starting dynamic subtest: pipe-C-eDP-1
[522.385531] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.082s)
[522.393793] Starting dynamic subtest: pipe-D-eDP-1
[524.482902] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.095s)
[524.484875] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.709s)
[525.696781] [066/133] (497s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[525.814845] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[525.819410] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[525.900377] [067/133] (497s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[525.982085] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[525.984278] Starting dynamic subtest: pipe-A-eDP-1
[526.009517] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[526.019244] Starting dynamic subtest: pipe-B-eDP-1
[526.041295] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[526.049218] Starting dynamic subtest: pipe-C-eDP-1
[526.071271] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[526.079647] Starting dynamic subtest: pipe-D-eDP-1
[526.101841] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[526.106674] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[528.181128] [068/133] (495s left) kms_big_fb (linear-32bpp-rotate-0)
[528.285858] Starting subtest: linear-32bpp-rotate-0
[529.731130] Subtest linear-32bpp-rotate-0: SUCCESS (1.445s)
[529.855418] [069/133] (493s left) kms_plane_lowres (tiling-y)
[529.954528] Starting subtest: tiling-y
[529.957935] Subtest tiling-y: SKIP (0.000s)
[530.040860] [070/133] (493s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[530.161376] Starting subtest: cursorB-vs-flipA-atomic
[530.165048] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[530.308672] [071/133] (493s left) gem_userptr_blits (coherency-sync)
[530.388494] Starting subtest: coherency-sync
[530.658110] Subtest coherency-sync: SKIP (0.229s)
[530.750800] [072/133] (492s left) api_intel_bb (misplaced-blitter)
[530.853512] Starting subtest: misplaced-blitter
[530.862570] Subtest misplaced-blitter: SUCCESS (0.012s)
[531.001279] [073/133] (492s left) kms_fbcon_fbt (psr-suspend)
[531.069725] Starting subtest: psr-suspend
[580.278467] Subtest psr-suspend: SUCCESS (19.510s)
[580.352193] [074/133] (443s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[580.469049] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[580.470936] Starting dynamic subtest: pipe-A-eDP-1
[581.210323] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.725s)
[581.221117] Starting dynamic subtest: pipe-B-eDP-1
[582.947846] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.717s)
[582.958702] Starting dynamic subtest: pipe-C-eDP-1
[584.700246] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.735s)
[584.710980] Starting dynamic subtest: pipe-D-eDP-1
[586.434163] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.719s)
[586.436204] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.901s)
[587.601632] [075/133] (435s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[587.704140] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[587.708110] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[587.779032] [076/133] (435s left) prime_vgem (basic-gtt)
[587.855322] Starting subtest: basic-gtt
[587.859910] Subtest basic-gtt: SKIP (0.000s)
[587.965048] [077/133] (435s left) kms_dp_aux_dev (basic)
[588.034168] Starting subtest: basic
[588.255252] Subtest basic: SUCCESS (0.220s)
[588.327612] [078/133] (435s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[588.427578] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[588.431820] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[588.521490] [079/133] (435s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[588.625680] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[588.630121] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[588.721045] [080/133] (434s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[590.574205] Starting subtest: dpms-mode-unset-lpsp
[594.625597] Subtest dpms-mode-unset-lpsp: SUCCESS (4.043s)
[595.021460] [081/133] (428s left) kms_psr (psr-sprite-plane-move)
[595.118969] Starting subtest: psr-sprite-plane-move
[595.121216] Starting dynamic subtest: eDP-1
[596.867736] Dynamic subtest eDP-1: SUCCESS (1.745s)
[596.869965] Subtest psr-sprite-plane-move: SUCCESS (1.747s)
[597.689066] [082/133] (426s left) kms_psr (pr-primary-blt)
[597.780514] Starting subtest: pr-primary-blt
[597.784838] Subtest pr-primary-blt: SKIP (0.000s)
[597.852234] [083/133] (425s left) perf (whitelisted-registers-userspace-config)
[598.000453] Starting subtest: whitelisted-registers-userspace-config
[598.002312] Subtest whitelisted-registers-userspace-config: SUCCESS (0.000s)
[598.041174] [084/133] (425s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[598.100170] Starting subtest: multi-wait-all-for-submit-signaled
[598.102336] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[598.194431] [085/133] (425s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[598.290929] Starting subtest: bad-pixel-format-y-tiled-ccs
[598.293134] Starting dynamic subtest: pipe-A-eDP-1
[598.315128] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[598.325310] Starting dynamic subtest: pipe-B-eDP-1
[598.345936] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[598.354545] Starting dynamic subtest: pipe-C-eDP-1
[598.374923] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[598.383479] Starting dynamic subtest: pipe-D-eDP-1
[598.404397] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[598.408474] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[600.554803] [086/133] (423s left) gem_ctx_bad_destroy (invalid-default-ctx)
[600.612014] Starting subtest: invalid-default-ctx
[600.614383] Subtest invalid-default-ctx: SUCCESS (0.000s)
[600.720354] [087/133] (423s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[600.809461] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[600.813598] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[600.901857] [088/133] (423s left) i915_pm_rps (min-max-config-idle)
[600.963648] Starting subtest: min-max-config-idle
[600.968164] Subtest min-max-config-idle: SKIP (0.001s)
[601.067649] [089/133] (423s left) sysfs_preempt_timeout (timeout)
[601.121171] Starting subtest: timeout
[601.123457] Starting dynamic subtest: vecs0
[610.364703] Dynamic subtest vecs0: SUCCESS (9.236s)
[610.376868] Starting dynamic subtest: ccs0
[611.695740] Dynamic subtest ccs0: SUCCESS (1.327s)
[611.717899] Starting dynamic subtest: vcs0
[621.290586] Dynamic subtest vcs0: SUCCESS (9.590s)
[621.302930] Starting dynamic subtest: bcs0
[622.550611] Dynamic subtest bcs0: SUCCESS (1.260s)
[622.566882] Starting dynamic subtest: rcs0
[623.785942] Dynamic subtest rcs0: SUCCESS (1.233s)
[623.798532] Starting dynamic subtest: vcs1
[633.388302] Dynamic subtest vcs1: SUCCESS (9.599s)
[633.390779] Subtest timeout: SUCCESS (32.257s)
[633.463155] [090/133] (390s left) kms_joiner (basic-ultra-joiner)
[633.563114] Starting subtest: basic-ultra-joiner
[633.567422] Subtest basic-ultra-joiner: SKIP (0.000s)
[633.627779] [091/133] (390s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[633.734934] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[633.739081] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[633.831358] [092/133] (390s left) kms_rotation_crc (primary-rotation-270)
[633.926414] Starting subtest: primary-rotation-270
[633.930516] Subtest primary-rotation-270: SKIP (0.001s)
[633.995703] [093/133] (390s left) prime_vgem (fence-read-hang)
[634.101952] Subtest fence-read-hang: SKIP (0.000s)
[634.201689] [094/133] (390s left) kms_properties (crtc-properties-legacy)
[634.305371] Starting subtest: crtc-properties-legacy
[634.307697] Starting dynamic subtest: pipe-A-eDP-1
[634.748434] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.444s)
[634.759628] Starting dynamic subtest: pipe-B-eDP-1
[635.915302] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.163s)
[635.928712] Starting dynamic subtest: pipe-C-eDP-1
[637.039321] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.123s)
[637.049440] Starting dynamic subtest: pipe-D-eDP-1
[638.184285] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.144s)
[638.186633] Subtest crtc-properties-legacy: SUCCESS (3.880s)
[638.987467] [095/133] (385s left) kms_plane_alpha_blend (alpha-7efc)
[639.076026] Starting subtest: alpha-7efc
[639.347553] Starting dynamic subtest: pipe-A-eDP-1
[639.957625] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.609s)
[639.985751] Starting dynamic subtest: pipe-D-eDP-1
[641.320832] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[641.323463] Subtest alpha-7efc: SUCCESS (2.245s)
[642.158550] [096/133] (382s left) i915_suspend (basic-s2idle-without-i915)
[642.190605] Starting subtest: basic-s2idle-without-i915
[662.737526] Subtest basic-s2idle-without-i915: SUCCESS (5.528s)
[662.847076] [097/133] (361s left) perf_pmu (busy-accuracy-50)
[662.904300] Starting subtest: busy-accuracy-50
[662.906680] Starting dynamic subtest: rcs0
[664.662109] Dynamic subtest rcs0: SUCCESS (1.719s)
[664.674071] Starting dynamic subtest: bcs0
[666.221641] Dynamic subtest bcs0: SUCCESS (1.533s)
[666.230200] Starting dynamic subtest: vcs0
[667.876426] Dynamic subtest vcs0: SUCCESS (1.631s)
[667.887401] Starting dynamic subtest: vcs1
[669.523597] Dynamic subtest vcs1: SUCCESS (1.628s)
[669.534432] Starting dynamic subtest: vecs0
[671.074130] Dynamic subtest vecs0: SUCCESS (1.535s)
[671.084822] Starting dynamic subtest: ccs0
[672.630527] Dynamic subtest ccs0: SUCCESS (1.543s)
[672.633034] Subtest busy-accuracy-50: SUCCESS (9.602s)
[672.745152] [098/133] (351s left) sw_sync (alloc_timeline)
[672.785936] Starting subtest: alloc_timeline
[672.788206] Subtest alloc_timeline: SUCCESS (0.000s)
[672.855928] [099/133] (351s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[672.954608] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[672.956805] Starting dynamic subtest: pipe-A
[673.315771] Dynamic subtest pipe-A: SUCCESS (0.360s)
[673.324861] Starting dynamic subtest: pipe-B
[674.772545] Dynamic subtest pipe-B: SUCCESS (1.448s)
[674.781535] Starting dynamic subtest: pipe-C
[676.171371] Dynamic subtest pipe-C: SUCCESS (1.392s)
[676.180430] Starting dynamic subtest: pipe-D
[677.621297] Dynamic subtest pipe-D: SUCCESS (1.443s)
[677.623831] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.646s)
[678.803100] [100/133] (345s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[678.928691] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[678.932762] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[679.046121] [101/133] (345s left) kms_dsc (dsc-with-bpc-formats)
[679.139647] Starting subtest: dsc-with-bpc-formats
[679.251207] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[679.289597] [102/133] (345s left) sw_sync (sync_merge_invalid)
[679.324062] Starting subtest: sync_merge_invalid
[679.342404] Subtest sync_merge_invalid: SUCCESS (0.020s)
[679.413920] [103/133] (345s left) kms_chamelium_hpd (dp-hpd-storm)
[679.798398] Subtest dp-hpd-storm: SKIP (0.000s)
[680.595117] [104/133] (344s left) kms_legacy_colorkey (basic)
[680.695253] Starting subtest: basic
[680.697167] Starting dynamic subtest: pipe-A
[680.738827] Dynamic subtest pipe-A: SUCCESS (0.045s)
[680.751691] Starting dynamic subtest: pipe-B
[680.754311] Dynamic subtest pipe-B: SUCCESS (0.000s)
[680.762532] Starting dynamic subtest: pipe-C
[680.764519] Dynamic subtest pipe-C: SUCCESS (0.000s)
[680.772129] Starting dynamic subtest: pipe-D
[680.774092] Dynamic subtest pipe-D: SUCCESS (0.000s)
[680.776078] Subtest basic: SUCCESS (0.051s)
[680.811177] [105/133] (343s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[680.876468] Starting subtest: x-tiled-to-vebox-yf-tiled
[680.881052] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[681.057693] [106/133] (343s left) gem_eio (in-flight-internal-immediate)
[681.267507] Starting subtest: in-flight-internal-immediate
[682.431805] Subtest in-flight-internal-immediate: SUCCESS (1.162s)
[682.569460] [107/133] (342s left) kms_addfb_basic (too-high)
[682.656704] Starting subtest: too-high
[682.658975] Subtest too-high: SUCCESS (0.000s)
[682.756598] [108/133] (342s left) gem_fence_thrash (bo-write-verify-none)
[682.797614] Starting subtest: bo-write-verify-none
[682.811977] Subtest bo-write-verify-none: SKIP (0.016s)
[682.876569] [109/133] (342s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[682.976508] Starting subtest: 4-tiled-8bpp-rotate-180
[684.236447] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.258s)
[684.359257] [110/133] (340s left) gem_ctx_param (invalid-get-engines)
[684.422199] Starting subtest: invalid-get-engines
[684.424297] Subtest invalid-get-engines: SUCCESS (0.001s)
[684.519120] [111/133] (340s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[684.624818] Starting subtest: psr-rgb101010-draw-mmap-wc
[691.594809] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.962s)
[691.700281] [112/133] (333s left) gem_exec_reloc (basic-concurrent16)
[691.776419] Subtest basic-concurrent16: SKIP (0.000s)
[691.858904] [113/133] (333s left) gem_exec_balancer (smoke)
[691.931323] Starting subtest: smoke
[714.562597] Subtest smoke: SUCCESS (22.622s)
[715.695115] [114/133] (309s left) gem_exec_reloc (basic-cpu-gtt)
[715.763818] Subtest basic-cpu-gtt: SKIP (0.000s)
[715.854291] [115/133] (309s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[715.953086] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[716.812316] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.859s)
[716.992934] [116/133] (308s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[717.049129] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[717.053862] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[717.241726] [117/133] (308s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[717.326917] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[717.329320] Starting dynamic subtest: pipe-A-eDP-1
[717.621129] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.262s)
[717.638037] Starting dynamic subtest: pipe-B-eDP-1
[718.878793] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.254s)
[718.890317] Starting dynamic subtest: pipe-C-eDP-1
[720.156408] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.278s)
[720.166128] Starting dynamic subtest: pipe-D-eDP-1
[721.374972] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.221s)
[721.377324] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.019s)
[722.509749] [118/133] (302s left) kms_atomic (crtc-invalid-params-fence)
[722.601503] Starting subtest: crtc-invalid-params-fence
[722.603785] Starting dynamic subtest: pipe-A-eDP-1
[723.026005] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.423s)
[723.027875] Subtest crtc-invalid-params-fence: SUCCESS (0.424s)
[723.853226] [119/133] (301s left) kms_psr (fbc-psr-primary-blt)
[723.945700] Starting subtest: fbc-psr-primary-blt
[723.947967] Starting dynamic subtest: eDP-1
[723.951859] Dynamic subtest eDP-1: SKIP (0.000s)
[723.955867] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[724.016980] [120/133] (301s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[724.118108] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[724.122347] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[724.213409] [121/133] (301s left) kms_flip (basic-flip-vs-wf_vblank)
[724.318051] Starting subtest: basic-flip-vs-wf_vblank
[724.320453] Starting dynamic subtest: A-eDP1
[726.296044] Dynamic subtest A-eDP1: SUCCESS (1.979s)
[726.311256] Starting dynamic subtest: B-eDP1
[728.227373] Dynamic subtest B-eDP1: SUCCESS (1.928s)
[728.241780] Starting dynamic subtest: C-eDP1
[730.145125] Dynamic subtest C-eDP1: SUCCESS (1.915s)
[730.154380] Starting dynamic subtest: D-eDP1
[732.074571] Dynamic subtest D-eDP1: SUCCESS (1.927s)
[732.080594] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.763s)
[733.297796] [122/133] (292s left) kms_color (ctm-0-25)
[733.387296] Starting subtest: ctm-0-25
[733.389446] Starting dynamic subtest: pipe-A-eDP-1
[736.928674] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.541s)
[736.938975] Starting dynamic subtest: pipe-B-eDP-1
[741.210234] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.277s)
[741.231211] Starting dynamic subtest: pipe-C-eDP-1
[745.267436] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.056s)
[745.277209] Starting dynamic subtest: pipe-D-eDP-1
[749.271194] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.001s)
[749.273519] Subtest ctm-0-25: SUCCESS (15.879s)
[750.085270] [123/133] (275s left) syncobj_timeline (signal)
[750.146212] Starting subtest: signal
[750.148208] Subtest signal: SUCCESS (0.000s)
[750.243815] [124/133] (275s left) prime_busy (after-wait)
[750.305030] Starting subtest: after-wait
[750.307430] Starting dynamic subtest: rcs0
[750.338713] Dynamic subtest rcs0: SUCCESS (0.033s)
[750.349699] Starting dynamic subtest: bcs0
[750.392562] Dynamic subtest bcs0: SUCCESS (0.051s)
[750.402777] Starting dynamic subtest: vcs0
[750.430486] Dynamic subtest vcs0: SUCCESS (0.039s)
[750.439277] Starting dynamic subtest: vcs1
[750.472445] Dynamic subtest vcs1: SUCCESS (0.041s)
[750.481952] Starting dynamic subtest: vecs0
[750.513216] Dynamic subtest vecs0: SUCCESS (0.040s)
[750.522526] Starting dynamic subtest: ccs0
[750.553529] Dynamic subtest ccs0: SUCCESS (0.040s)
[750.663080] Subtest after-wait: SUCCESS (0.358s)
[750.737913] [125/133] (274s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[750.852303] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[750.856476] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[750.951019] [126/133] (274s left) kms_flip (2x-blocking-wf_vblank)
[751.063913] Starting subtest: 2x-blocking-wf_vblank
[751.073046] Subtest 2x-blocking-wf_vblank: SKIP (0.010s)
[751.179773] [127/133] (274s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[751.278402] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[751.282858] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[751.383046] [128/133] (274s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[751.484085] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[751.488481] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[751.592069] [129/133] (274s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[751.684349] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[758.420268] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.736s)
[758.545100] [130/133] (267s left) prime_mmap (test_reprime)
[758.605287] Starting subtest: test_reprime
[758.607286] Starting dynamic subtest: test_reprime-smem
[758.633173] Dynamic subtest test_reprime-smem: SUCCESS (0.030s)
[758.635661] Subtest test_reprime: SUCCESS (0.031s)
[758.735417] [131/133] (267s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[758.832263] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[766.193787] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.370s)
[766.308467] [132/133] (259s left) syncobj_wait (wait-all-for-submit-snapshot)
[766.374600] Starting subtest: wait-all-for-submit-snapshot
[766.454467] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[766.570220] [133/133] (259s left) gem_eio (wait-1us)
[766.797011] Starting subtest: wait-1us
[767.148864] Subtest wait-1us: SUCCESS (0.355s)
[767.273275] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_gtt
results: parsing output: 1/ for test: gem_caching
results: parsing output: 2/ for test: kms_plane
results: parsing output: 3/ for test: gem_ctx_shared
results: parsing output: 4/ for test: kms_atomic_interruptible
results: parsing output: 5/ for test: kms_content_protection
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_sequence
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_balancer
results: parsing output: 10/ for test: kms_pm_rpm
results: parsing output: 11/ for test: kms_addfb_basic
results: parsing output: 12/ for test: gem_exec_schedule
results: parsing output: 13/ for test: kms_chamelium_frames
results: parsing output: 14/ for test: kms_getfb
results: parsing output: 15/ for test: i915_drm_fdinfo
results: parsing output: 16/ for test: gem_lmem_swapping
results: parsing output: 17/ for test: gem_mmap_wc
results: parsing output: 18/ for test: gem_exec_whisper
results: parsing output: 19/ for test: kms_setmode
results: parsing output: 20/ for test: kms_busy
results: parsing output: 21/ for test: gen9_exec_parse
results: parsing output: 22/ for test: gem_exec_fence
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: gem_wait
results: parsing output: 27/ for test: gem_pwrite_snooped
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: gem_set_tiling_vs_blt
results: parsing output: 30/ for test: gem_ctx_persistence
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: i915_drm_fdinfo
results: parsing output: 34/ for test: gem_exec_reloc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_plane_scaling
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_psr2_sf
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: core_setmaster
results: parsing output: 43/ for test: gem_exec_whisper
results: parsing output: 44/ for test: kms_tv_load_detect
results: parsing output: 45/ for test: kms_psr2_sf
results: parsing output: 46/ for test: gem_exec_params
results: parsing output: 47/ for test: gem_exec_nop
results: parsing output: 48/ for test: dumb_buffer
results: parsing output: 49/ for test: kms_hdr
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: gem_exec_schedule
results: parsing output: 52/ for test: kms_flip_scaled_crc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: perf_pmu
results: parsing output: 55/ for test: kms_setmode
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_vblank
results: parsing output: 59/ for test: kms_cursor_crc
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: gem_softpin
results: parsing output: 62/ for test: gem_mmap_offset
results: parsing output: 63/ for test: gem_create
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_plane_lowres
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: gem_userptr_blits
results: parsing output: 71/ for test: api_intel_bb
results: parsing output: 72/ for test: kms_fbcon_fbt
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_psr2_sf
results: parsing output: 75/ for test: prime_vgem
results: parsing output: 76/ for test: kms_dp_aux_dev
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_pm_rpm
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: perf
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: gem_ctx_bad_destroy
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: i915_pm_rps
results: parsing output: 88/ for test: sysfs_preempt_timeout
results: parsing output: 89/ for test: kms_joiner
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_rotation_crc
results: parsing output: 92/ for test: prime_vgem
results: parsing output: 93/ for test: kms_properties
results: parsing output: 94/ for test: kms_plane_alpha_blend
results: parsing output: 95/ for test: i915_suspend
results: parsing output: 96/ for test: perf_pmu
results: parsing output: 97/ for test: sw_sync
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_dsc
results: parsing output: 101/ for test: sw_sync
results: parsing output: 102/ for test: kms_chamelium_hpd
results: parsing output: 103/ for test: kms_legacy_colorkey
results: parsing output: 104/ for test: gem_render_copy
results: parsing output: 105/ for test: gem_eio
results: parsing output: 106/ for test: kms_addfb_basic
results: parsing output: 107/ for test: gem_fence_thrash
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: gem_ctx_param
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_exec_reloc
results: parsing output: 112/ for test: gem_exec_balancer
results: parsing output: 113/ for test: gem_exec_reloc
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: gem_render_copy
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_atomic
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_color
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: prime_busy
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: prime_mmap
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: syncobj_wait
results: parsing output: 132/ for test: gem_eio
Done.