[57.377913] Initializing watchdogs
[57.378061]   /dev/watchdog0
[57.394906] [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]
[57.399725] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.402460] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.406223] [001/155] (960s left) sw_sync (sync_busy_fork_unixsocket)
[57.467417] Starting subtest: sync_busy_fork_unixsocket
[57.475582] Subtest sync_busy_fork_unixsocket: SUCCESS (0.011s)
[57.537123] [002/155] (960s left) gem_ctx_persistence (engines-persistence)
[57.609288] Starting subtest: engines-persistence
[57.611620] Starting dynamic subtest: rcs0
[58.402533] Dynamic subtest rcs0: SUCCESS (0.793s)
[58.412895] Starting dynamic subtest: bcs0
[59.168291] Dynamic subtest bcs0: SUCCESS (0.766s)
[59.178950] Starting dynamic subtest: vcs0
[59.935323] Dynamic subtest vcs0: SUCCESS (0.767s)
[59.944575] Starting dynamic subtest: vcs1
[60.698857] Dynamic subtest vcs1: SUCCESS (0.764s)
[60.708453] Starting dynamic subtest: vecs0
[61.475067] Dynamic subtest vecs0: SUCCESS (0.776s)
[61.486279] Starting dynamic subtest: ccs0
[62.239285] Dynamic subtest ccs0: SUCCESS (0.764s)
[62.242128] Subtest engines-persistence: SUCCESS (4.635s)
[62.319620] [003/155] (955s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[62.427674] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc
[62.431713] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[62.539948] [004/155] (955s left) syncobj_timeline (invalid-query-one-illegal-handle)
[62.596987] Starting subtest: invalid-query-one-illegal-handle
[62.599514] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[62.697923] [005/155] (955s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[62.802220] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[66.590710] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.797s)
[67.735285] [006/155] (950s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[67.833579] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[67.837851] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[67.928536] [007/155] (950s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[68.019335] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[68.023254] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[68.115926] [008/155] (950s left) gem_create (create-ext-placement-all)
[68.277304] Starting subtest: create-ext-placement-all
[68.280260] Subtest create-ext-placement-all: SUCCESS (0.000s)
[68.383916] [009/155] (949s left) gem_exec_reloc (basic-write-gtt)
[68.449613] Subtest basic-write-gtt: SKIP (0.000s)
[68.514843] [010/155] (949s left) gem_ctx_shared (q-smoketest-all)
[68.581469] Starting subtest: Q-smoketest-all
[100.972262] Subtest Q-smoketest-all: SUCCESS (32.351s)
[102.413028] [011/155] (915s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[102.515283] Starting subtest: fbcpsr-slowdraw
[128.131108] Subtest fbcpsr-slowdraw: SUCCESS (25.620s)
[128.263596] [012/155] (890s left) api_intel_allocator (gem-pool)
[128.315197] Starting subtest: gem-pool
[128.320700] Subtest gem-pool: SUCCESS (0.008s)
[128.431920] [013/155] (889s left) kms_sequence (queue-busy)
[128.529132] Starting subtest: queue-busy
[128.531365] Starting dynamic subtest: pipe-A-eDP-1
[130.481558] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.951s)
[130.490823] Starting dynamic subtest: pipe-B-eDP-1
[133.119196] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.637s)
[133.129096] Starting dynamic subtest: pipe-C-eDP-1
[135.721085] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.602s)
[135.731703] Starting dynamic subtest: pipe-D-eDP-1
[138.361278] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.638s)
[138.363578] Subtest queue-busy: SUCCESS (9.833s)
[139.261201] [014/155] (879s left) gem_partial_pwrite_pread (write)
[139.320878] Subtest write: SKIP (0.000s)
[139.388622] [015/155] (879s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[139.481259] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[139.485338] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[139.573303] [016/155] (878s left) kms_chamelium_hpd (hdmi-hpd)
[139.963985] Subtest hdmi-hpd: SKIP (0.000s)
[140.774798] [017/155] (877s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[140.830453] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[140.835131] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[141.019088] [018/155] (877s left) gem_exec_schedule (pi-shared-iova)
[141.197490] Starting subtest: pi-shared-iova
[141.199839] Starting dynamic subtest: rcs0
[141.275880] Dynamic subtest rcs0: SUCCESS (0.082s)
[141.286022] Starting dynamic subtest: vcs0
[141.341884] Dynamic subtest vcs0: SUCCESS (0.066s)
[141.353895] Starting dynamic subtest: vcs1
[141.398924] Dynamic subtest vcs1: SUCCESS (0.057s)
[141.410648] Starting dynamic subtest: vecs0
[141.453762] Dynamic subtest vecs0: SUCCESS (0.055s)
[141.462680] Starting dynamic subtest: ccs0
[141.520798] Dynamic subtest ccs0: SUCCESS (0.067s)
[141.523720] Subtest pi-shared-iova: SUCCESS (0.328s)
[143.420835] [019/155] (875s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[143.528921] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[143.533317] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[143.632826] [020/155] (875s left) gem_mmap_offset (mmap-boundaries)
[143.692897] Starting subtest: mmap-boundaries
[143.695674] Starting dynamic subtest: smem0
[143.976078] Dynamic subtest smem0: SUCCESS (0.283s)
[143.978623] Subtest mmap-boundaries: SUCCESS (0.284s)
[144.103552] [021/155] (874s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[144.204487] Starting subtest: y-tiled-32bpp-rotate-0
[144.208997] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[144.299227] [022/155] (874s left) core_setmaster_vs_auth
[144.448854] [023/155] (874s left) perf_pmu (busy-idle-no-semaphores)
[144.486506] Starting subtest: busy-idle-no-semaphores
[144.488411] Starting dynamic subtest: rcs0
[145.148459] Dynamic subtest rcs0: SUCCESS (0.661s)
[145.159194] Starting dynamic subtest: bcs0
[145.799509] Dynamic subtest bcs0: SUCCESS (0.650s)
[145.811468] Starting dynamic subtest: vcs0
[146.451208] Dynamic subtest vcs0: SUCCESS (0.651s)
[146.462941] Starting dynamic subtest: vcs1
[147.914743] Dynamic subtest vcs1: SUCCESS (1.462s)
[147.923959] Starting dynamic subtest: vecs0
[149.959683] Dynamic subtest vecs0: SUCCESS (2.044s)
[149.971689] Starting dynamic subtest: ccs0
[151.947082] Dynamic subtest ccs0: SUCCESS (1.985s)
[151.949971] Subtest busy-idle-no-semaphores: SUCCESS (7.459s)
[152.062733] [024/155] (866s left) gem_exec_reloc (basic-cpu-wc)
[152.126450] Subtest basic-cpu-wc: SKIP (0.000s)
[152.209957] [025/155] (866s left) kms_cdclk (plane-scaling)
[152.302818] Starting subtest: plane-scaling
[152.305129] Starting dynamic subtest: pipe-A-eDP-1
[152.594769] Dynamic subtest pipe-A-eDP-1: SKIP (0.289s)
[152.605098] Starting dynamic subtest: pipe-B-eDP-1
[152.610050] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[152.620719] Starting dynamic subtest: pipe-C-eDP-1
[152.625384] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[152.633715] Starting dynamic subtest: pipe-D-eDP-1
[152.637873] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[152.642529] Subtest plane-scaling: SKIP (0.296s)
[153.426556] [026/155] (865s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[153.526969] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[153.531328] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[153.609746] [027/155] (865s left) perf (i915-ref-count)
[153.677754] Starting subtest: i915-ref-count
[153.762716] Subtest i915-ref-count: SUCCESS (0.083s)
[153.819629] [028/155] (865s left) api_intel_bb (render)
[153.879153] Starting subtest: render
[153.881441] Starting dynamic subtest: render-none-512
[153.994946] Dynamic subtest render-none-512: SUCCESS (0.114s)
[154.008020] Starting dynamic subtest: render-none-1024
[154.375014] Dynamic subtest render-none-1024: SUCCESS (0.380s)
[154.386944] Starting dynamic subtest: render-x-512
[154.464253] Dynamic subtest render-x-512: SUCCESS (0.090s)
[154.477091] Starting dynamic subtest: render-x-1024
[154.810029] Dynamic subtest render-x-1024: SUCCESS (0.344s)
[154.820932] Starting dynamic subtest: render-y-512
[154.896424] Dynamic subtest render-y-512: SUCCESS (0.087s)
[154.907776] Starting dynamic subtest: render-y-1024
[155.349783] Dynamic subtest render-y-1024: SUCCESS (0.451s)
[155.352750] Subtest render: SUCCESS (1.468s)
[155.455325] [029/155] (863s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[155.545287] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[155.549611] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[155.636210] [030/155] (863s left) kms_async_flips (alternate-sync-async-flip-atomic)
[155.719938] Starting subtest: alternate-sync-async-flip-atomic
[156.097443] Starting dynamic subtest: pipe-A-eDP-1
[158.916211] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.820s)
[159.283928] Starting dynamic subtest: pipe-B-eDP-1
[162.045061] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.769s)
[162.436806] Starting dynamic subtest: pipe-C-eDP-1
[165.187373] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.762s)
[165.553549] Starting dynamic subtest: pipe-D-eDP-1
[168.321264] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.776s)
[168.323280] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.604s)
[169.503999] [031/155] (849s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[169.614077] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[169.618202] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[169.712110] [032/155] (849s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[169.813214] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-wc
[169.817736] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[169.926277] [033/155] (849s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[170.025377] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[170.029483] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[170.109105] [034/155] (849s left) kms_pm_backlight (bad-brightness)
[170.193425] Starting subtest: bad-brightness
[170.268086] Starting dynamic subtest: eDP-1
[170.577885] Dynamic subtest eDP-1: SUCCESS (0.307s)
[170.580514] Subtest bad-brightness: SUCCESS (0.384s)
[171.429175] [035/155] (847s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[171.534357] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[171.538639] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[171.633035] [036/155] (847s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[171.740989] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[171.745080] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[171.858716] [037/155] (847s left) gem_softpin (noreloc-interruptible)
[171.922655] Starting subtest: noreloc-interruptible
[177.326276] Subtest noreloc-interruptible: SUCCESS (5.402s)
[177.463593] [038/155] (841s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-blt)
[177.575404] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-blt
[177.579450] Subtest psrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[177.673840] [039/155] (841s left) kms_cursor_crc (cursor-sliding-512x170)
[177.946893] Starting subtest: cursor-sliding-512x170
[177.951340] Subtest cursor-sliding-512x170: SKIP (0.001s)
[178.092647] [040/155] (841s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt)
[178.194206] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt
[178.198216] Subtest fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[178.276608] [041/155] (841s left) kms_plane_multiple (tiling-yf)
[178.372647] Starting subtest: tiling-yf
[178.376432] Subtest tiling-yf: SKIP (0.000s)
[178.465863] [042/155] (841s left) gem_exec_schedule (u-submit-golden-slice)
[178.532166] Starting subtest: u-submit-golden-slice
[178.534072] Starting dynamic subtest: rcs0
[178.564517] Dynamic subtest rcs0: SUCCESS (0.036s)
[178.578968] Starting dynamic subtest: bcs0
[178.594135] Dynamic subtest bcs0: SUCCESS (0.030s)
[178.604590] Starting dynamic subtest: vcs0
[178.626242] Dynamic subtest vcs0: SUCCESS (0.032s)
[178.635106] Starting dynamic subtest: vcs1
[178.656089] Dynamic subtest vcs1: SUCCESS (0.029s)
[178.668189] Starting dynamic subtest: vecs0
[178.689348] Dynamic subtest vecs0: SUCCESS (0.033s)
[178.704993] Starting dynamic subtest: ccs0
[178.722046] Dynamic subtest ccs0: SUCCESS (0.032s)
[178.724116] Subtest u-submit-golden-slice: SUCCESS (0.195s)
[180.395735] [043/155] (839s left) i915_suspend (debugfs-reader)
[180.445350] Starting subtest: debugfs-reader
[199.198583] Subtest debugfs-reader: SUCCESS (4.468s)
[208.352348] [044/155] (820s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[208.459982] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[208.464415] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[208.528417] [045/155] (820s left) kms_psr (fbc-psr2-primary-page-flip)
[208.617800] Starting subtest: fbc-psr2-primary-page-flip
[208.619686] Starting dynamic subtest: eDP-1
[208.623785] Dynamic subtest eDP-1: SKIP (0.000s)
[208.627798] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[208.703527] [046/155] (820s left) kms_pm_dc (dc5-dpms-negative)
[209.804039] Starting subtest: dc5-dpms-negative
[209.808179] Subtest dc5-dpms-negative: SKIP (0.000s)
[209.887021] [047/155] (818s left) kms_cursor_crc (cursor-random-32x10)
[210.037853] Starting subtest: cursor-random-32x10
[210.092753] Subtest cursor-random-32x10: SKIP (0.048s)
[210.349846] [048/155] (818s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[210.449847] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[217.138056] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.710s)
[217.281299] [049/155] (811s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-onoff)
[217.389822] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-onoff
[217.394085] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[217.496987] [050/155] (811s left) gem_exec_big (single)
[217.554203] Starting subtest: single
[220.998998] Subtest single: FAIL (3.386s)
[221.042155] [051/155] (807s left) prime_vgem (wait)
[221.211681] Starting subtest: wait
[221.214036] Starting dynamic subtest: rcs0
[221.271707] Dynamic subtest rcs0: SUCCESS (0.060s)
[221.281681] Starting dynamic subtest: bcs0
[221.333685] Dynamic subtest bcs0: SUCCESS (0.061s)
[221.343553] Starting dynamic subtest: vcs0
[221.382040] Dynamic subtest vcs0: SUCCESS (0.047s)
[221.395201] Starting dynamic subtest: vcs1
[221.431893] Dynamic subtest vcs1: SUCCESS (0.049s)
[221.442037] Starting dynamic subtest: vecs0
[221.478556] Dynamic subtest vecs0: SUCCESS (0.046s)
[221.488845] Starting dynamic subtest: ccs0
[221.529832] Dynamic subtest ccs0: SUCCESS (0.051s)
[221.531619] Subtest wait: SUCCESS (0.319s)
[221.656820] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[221.660143] [052/155] (807s left) kms_flip (2x-absolute-wf_vblank)
[221.759659] Starting subtest: 2x-absolute-wf_vblank
[221.771178] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[221.855039] [053/155] (807s left) sysfs_timeslice_duration (timeout)
[221.905075] Starting subtest: timeout
[221.906962] Starting dynamic subtest: vecs0
[231.595933] Dynamic subtest vecs0: SUCCESS (9.694s)
[231.608834] Starting dynamic subtest: ccs0
[232.902455] Dynamic subtest ccs0: SUCCESS (1.308s)
[232.912835] Starting dynamic subtest: vcs0
[242.600801] Dynamic subtest vcs0: SUCCESS (9.700s)
[242.613133] Starting dynamic subtest: bcs0
[243.236718] Dynamic subtest bcs0: SUCCESS (0.636s)
[243.249835] Starting dynamic subtest: rcs0
[244.466603] Dynamic subtest rcs0: SUCCESS (1.229s)
[244.478486] Starting dynamic subtest: vcs1
[253.608433] Dynamic subtest vcs1: SUCCESS (9.143s)
[253.611038] Subtest timeout: SUCCESS (31.717s)
[253.691738] [054/155] (775s left) gem_lmem_swapping (parallel-multi)
[253.735191] Subtest parallel-multi: SKIP (0.000s)
[253.782454] [055/155] (775s left) kms_psr (psr-primary-mmap-cpu)
[253.873828] Starting subtest: psr-primary-mmap-cpu
[253.876119] Starting dynamic subtest: eDP-1
[255.673255] Dynamic subtest eDP-1: SUCCESS (1.800s)
[255.675619] Subtest psr-primary-mmap-cpu: SUCCESS (1.801s)
[256.544668] [056/155] (772s left) gem_wait (invalid-flags)
[256.605551] Starting subtest: invalid-flags
[256.607534] Subtest invalid-flags: SUCCESS (0.000s)
[256.857310] [057/155] (772s left) kms_frontbuffer_tracking (fbchdr-2p-indfb-fliptrack-mmap-gtt)
[256.969350] Starting subtest: fbchdr-2p-indfb-fliptrack-mmap-gtt
[256.973364] Subtest fbchdr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[257.064890] [058/155] (772s left) kms_draw_crc (fill-fb)
[257.171542] Starting subtest: fill-fb
[257.700151] Subtest fill-fb: SUCCESS (0.531s)
[257.818673] [059/155] (771s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[257.930715] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[257.934521] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[258.027821] [060/155] (771s left) kms_frontbuffer_tracking (hdr-2p-primscrn-shrfb-pgflip-blt)
[258.133887] Starting subtest: hdr-2p-primscrn-shrfb-pgflip-blt
[258.137330] Subtest hdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[258.236712] [061/155] (771s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[258.330380] Starting subtest: 4-tiled-8bpp-rotate-0
[259.569980] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.241s)
[259.681446] [062/155] (769s left) kms_vblank (ts-continuation-dpms-rpm)
[259.784104] Starting subtest: ts-continuation-dpms-rpm
[259.785676] Starting dynamic subtest: pipe-A-eDP-1
[262.324694] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.540s)
[262.340705] Starting dynamic subtest: pipe-D-eDP-1
[264.572743] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.246s)
[264.575105] Subtest ts-continuation-dpms-rpm: SUCCESS (4.790s)
[265.449439] [063/155] (764s left) kms_getfb (getfb2-handle-not-fb)
[265.539649] Starting subtest: getfb2-handle-not-fb
[265.541798] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[265.643078] [064/155] (763s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[265.746657] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[270.037896] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.299s)
[271.256758] [065/155] (758s left) kms_cursor_legacy (single-move)
[271.374250] Starting subtest: single-move
[271.376388] Starting dynamic subtest: pipe-A
[276.753238] Dynamic subtest pipe-A: SUCCESS (5.379s)
[276.765545] Starting dynamic subtest: pipe-B
[282.132507] Dynamic subtest pipe-B: SUCCESS (5.379s)
[282.142057] Starting dynamic subtest: pipe-C
[287.512195] Dynamic subtest pipe-C: SUCCESS (5.379s)
[287.524293] Starting dynamic subtest: pipe-D
[292.891701] Dynamic subtest pipe-D: SUCCESS (5.379s)
[292.904063] Starting dynamic subtest: all-pipes
[298.281848] Dynamic subtest all-pipes: SUCCESS (5.386s)
[298.284261] Subtest single-move: SUCCESS (26.915s)
[298.432231] [066/155] (731s left) kms_flip (bo-too-big-interruptible)
[298.531610] Starting subtest: bo-too-big-interruptible
[298.533836] Starting dynamic subtest: A-eDP1
[299.884043] Dynamic subtest A-eDP1: SUCCESS (1.348s)
[299.895432] Starting dynamic subtest: B-eDP1
[301.220002] Dynamic subtest B-eDP1: SUCCESS (1.336s)
[301.232511] Starting dynamic subtest: C-eDP1
[302.556307] Dynamic subtest C-eDP1: SUCCESS (1.333s)
[302.568790] Starting dynamic subtest: D-eDP1
[303.888371] Dynamic subtest D-eDP1: SUCCESS (1.329s)
[303.890837] Subtest bo-too-big-interruptible: SUCCESS (5.356s)
[305.102560] [067/155] (724s left) gem_exec_whisper (basic-queues-all)
[305.165923] Starting subtest: basic-queues-all
[316.046249] Subtest basic-queues-all: SUCCESS (10.872s)
[316.232878] [068/155] (713s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[316.334593] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[316.338838] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[316.432731] [069/155] (713s left) kms_flip (flip-vs-panning)
[316.531711] Starting subtest: flip-vs-panning
[316.533940] Starting dynamic subtest: A-eDP1
[325.232442] Dynamic subtest A-eDP1: SUCCESS (8.697s)
[325.244464] Starting dynamic subtest: B-eDP1
[333.958705] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[333.972881] Starting dynamic subtest: C-eDP1
[342.678247] Dynamic subtest C-eDP1: SUCCESS (8.716s)
[342.691509] Starting dynamic subtest: D-eDP1
[351.405364] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[351.409705] Subtest flip-vs-panning: SUCCESS (34.874s)
[352.603051] [070/155] (677s left) gem_flink_basic (bad-open)
[352.662506] Starting subtest: bad-open
[352.664899] Subtest bad-open: SUCCESS (0.000s)
[352.778642] [071/155] (677s left) kms_addfb_basic (no-handle)
[352.836456] Starting subtest: no-handle
[352.839188] Subtest no-handle: SUCCESS (0.000s)
[352.971490] [072/155] (676s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[353.081974] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[353.086344] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[353.189385] [073/155] (676s left) gem_partial_pwrite_pread (reads-snoop)
[353.262280] Subtest reads-snoop: SKIP (0.000s)
[353.334620] [074/155] (676s left) kms_color (ctm-0-50)
[353.420680] Starting subtest: ctm-0-50
[353.422908] Starting dynamic subtest: pipe-A-eDP-1
[356.999042] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.579s)
[357.010335] Starting dynamic subtest: pipe-B-eDP-1
[361.307949] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.306s)
[361.319200] Starting dynamic subtest: pipe-C-eDP-1
[365.378458] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.071s)
[365.389680] Starting dynamic subtest: pipe-D-eDP-1
[369.384358] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.007s)
[369.386927] Subtest ctm-0-50: SUCCESS (15.966s)
[370.205845] [075/155] (659s left) syncobj_wait (wait-all-snapshot)
[370.282519] Starting subtest: wait-all-snapshot
[370.365619] Subtest wait-all-snapshot: SUCCESS (0.082s)
[370.468930] [076/155] (659s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[370.565475] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[377.981805] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.424s)
[378.096398] [077/155] (652s left) gem_exec_reloc (basic-wc-active)
[378.161539] Subtest basic-wc-active: SKIP (0.000s)
[378.233606] [078/155] (651s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[378.336319] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[378.340312] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[378.446313] [079/155] (651s left) kms_frontbuffer_tracking (hdr-indfb-scaledprimary)
[378.546578] Starting subtest: hdr-indfb-scaledprimary
[378.550801] Subtest hdr-indfb-scaledprimary: SKIP (0.000s)
[378.658474] [080/155] (651s left) i915_drm_fdinfo (virtual-busy-idle-all)
[378.701279] Starting subtest: virtual-busy-idle-all
[378.705700] Subtest virtual-busy-idle-all: SKIP (0.000s)
[378.766936] [081/155] (651s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[378.874900] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[379.919355] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.046s)
[380.045132] [082/155] (650s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[380.137535] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[388.912792] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.780s)
[389.025405] [083/155] (641s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[389.136384] Starting subtest: pr-plane-move-sf-dmg-area
[389.140781] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[389.220973] [084/155] (641s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[389.316281] Starting subtest: psr-rgb565-draw-mmap-gtt
[389.320687] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[389.418195] [085/155] (641s left) gen7_exec_parse (batch-without-end)
[389.473406] Subtest batch-without-end: SKIP (0.000s)
[389.551474] [086/155] (640s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[389.642786] Starting subtest: primary-yf-tiled-reflect-x-90
[389.646980] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[389.730048] [087/155] (640s left) i915_pm_rpm (system-suspend-devices)
[391.760509] Starting subtest: system-suspend-devices
[399.455392] Subtest system-suspend-devices: SUCCESS (7.699s)
[405.893361] [088/155] (630s left) kms_frontbuffer_tracking (fbchdr-tiling-linear)
[406.000409] Starting subtest: fbchdr-tiling-linear
[406.004596] Subtest fbchdr-tiling-linear: SKIP (0.000s)
[406.123099] [089/155] (630s left) gem_exec_flush (basic-uc-prw-default)
[406.178111] Starting subtest: basic-uc-prw-default
[411.639520] Subtest basic-uc-prw-default: SUCCESS (5.462s)
[411.828912] [090/155] (624s left) kms_psr (fbc-psr-sprite-blt)
[411.938567] Starting subtest: fbc-psr-sprite-blt
[411.940846] Starting dynamic subtest: eDP-1
[411.944837] Dynamic subtest eDP-1: SKIP (0.000s)
[411.948950] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[412.019432] [091/155] (624s left) drm_mm (drm_mm)
[412.058711] Starting subtest: drm_mm
[412.090944] Starting dynamic subtest: drm_test_mm_init
[412.198736] Dynamic subtest drm_test_mm_init: SUCCESS (0.104s)
[412.210702] Starting dynamic subtest: drm_test_mm_debug
[412.288953] Dynamic subtest drm_test_mm_debug: SUCCESS (0.090s)
[412.301256] Starting dynamic subtest: drm_test_mm_align32
[412.385267] Dynamic subtest drm_test_mm_align32: SUCCESS (0.096s)
[412.397349] Starting dynamic subtest: drm_test_mm_align64
[412.464735] Dynamic subtest drm_test_mm_align64: SUCCESS (0.078s)
[412.474794] Starting dynamic subtest: drm_test_mm_lowest
[412.548623] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.083s)
[412.559437] Starting dynamic subtest: drm_test_mm_highest
[412.654851] Dynamic subtest drm_test_mm_highest: SUCCESS (0.103s)
[412.657092] Subtest drm_mm: SUCCESS (0.594s)
[412.812096] [092/155] (623s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-render)
[412.900319] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-render
[417.132911] Subtest fbc-1p-offscreen-pri-shrfb-draw-render: SUCCESS (4.237s)
[418.359111] [093/155] (618s left) kms_chamelium_frames (hdmi-crc-multiple)
[418.742564] Subtest hdmi-crc-multiple: SKIP (0.000s)
[419.556356] [094/155] (617s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[419.649558] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[419.653721] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[419.744770] [095/155] (617s left) kms_lease (setcrtc-implicit-plane)
[419.838565] Starting subtest: setcrtc-implicit-plane
[419.840920] Starting dynamic subtest: pipe-A-eDP-1
[420.319561] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.480s)
[420.330604] Starting dynamic subtest: pipe-B-eDP-1
[421.522574] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.199s)
[421.536181] Starting dynamic subtest: pipe-C-eDP-1
[422.646381] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.125s)
[422.658349] Starting dynamic subtest: pipe-D-eDP-1
[423.767880] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[423.770223] Subtest setcrtc-implicit-plane: SUCCESS (3.931s)
[424.624188] [096/155] (612s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-onoff)
[424.720679] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-onoff
[424.724891] Subtest fbchdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[424.818007] [097/155] (612s left) gem_exec_balancer (noheartbeat)
[425.069248] Starting subtest: noheartbeat
[425.073572] Subtest noheartbeat: SKIP (0.000s)
[425.186200] [098/155] (611s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[425.283881] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[425.288316] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[425.412081] [099/155] (611s left) kms_draw_crc (draw-method-blt)
[425.498358] Starting subtest: draw-method-blt
[425.500514] Starting dynamic subtest: xrgb8888-untiled
[425.751690] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[425.765273] Starting dynamic subtest: rgb565-untiled
[425.996208] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[426.006964] Starting dynamic subtest: xrgb2101010-untiled
[426.218035] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[426.229048] Starting dynamic subtest: xbgr16161616f-untiled
[426.440402] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[426.454863] Starting dynamic subtest: xrgb8888-xtiled
[426.562139] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.119s)
[426.575062] Starting dynamic subtest: rgb565-xtiled
[426.684435] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[426.695694] Starting dynamic subtest: xrgb2101010-xtiled
[426.797839] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[426.815988] Starting dynamic subtest: xbgr16161616f-xtiled
[426.906692] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[426.917705] Starting dynamic subtest: xrgb8888-4tiled
[427.030100] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[427.042729] Starting dynamic subtest: rgb565-4tiled
[427.151315] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[427.161835] Starting dynamic subtest: xrgb2101010-4tiled
[427.263701] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[427.276617] Starting dynamic subtest: xbgr16161616f-4tiled
[427.373371] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[427.375310] Subtest draw-method-blt: SUCCESS (1.878s)
[427.487054] [100/155] (609s left) kms_plane_alpha_blend (constant-alpha-max)
[427.571946] Starting subtest: constant-alpha-max
[427.860763] Starting dynamic subtest: pipe-A-eDP-1
[429.008013] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.145s)
[429.021530] Starting dynamic subtest: pipe-D-eDP-1
[430.761179] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.751s)
[430.763671] Subtest constant-alpha-max: SUCCESS (3.188s)
[431.586040] [101/155] (605s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[433.716272] Starting subtest: modeset-non-lpsp-stress-no-wait
[433.757988] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.042s)
[434.122955] [102/155] (603s left) gem_exec_fence (syncobj-export)
[434.539946] Starting subtest: syncobj-export
[434.546381] Subtest syncobj-export: SUCCESS (0.005s)
[435.699162] [103/155] (601s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[435.764919] Starting subtest: y-tiled-ccs-to-x-tiled
[435.769333] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[435.953819] [104/155] (601s left) syncobj_timeline (single-wait-available-submitted)
[436.000451] Starting subtest: single-wait-available-submitted
[436.002478] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[436.123351] [105/155] (601s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[436.220064] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[437.367052] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.146s)
[437.487427] [106/155] (599s left) gem_pxp (create-valid-protected-context)
[437.625237] Starting subtest: create-valid-protected-context
[437.627304] Subtest create-valid-protected-context: SUCCESS (0.002s)
[437.755420] [107/155] (599s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[437.862273] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[437.865795] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[437.954557] [108/155] (599s left) gem_sync (basic-store-each)
[438.028897] Starting subtest: basic-store-each
[440.273748] Subtest basic-store-each: SUCCESS (2.252s)
[440.461125] [109/155] (597s left) drm_read (empty-block)
[440.611967] Starting subtest: empty-block
[441.612997] Subtest empty-block: SUCCESS (1.000s)
[441.761026] [110/155] (595s left) syncobj_wait (single-wait-submitted)
[441.807169] Starting subtest: single-wait-submitted
[441.915447] Subtest single-wait-submitted: SUCCESS (0.108s)
[442.006466] [111/155] (595s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[442.104126] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[442.108156] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[442.216514] [112/155] (595s left) kms_flip (nonexisting-fb-interruptible)
[442.313196] Starting subtest: nonexisting-fb-interruptible
[442.315486] Starting dynamic subtest: A-eDP1
[443.497212] Dynamic subtest A-eDP1: SUCCESS (1.183s)
[443.509397] Starting dynamic subtest: B-eDP1
[444.624200] Dynamic subtest B-eDP1: SUCCESS (1.125s)
[444.637464] Starting dynamic subtest: C-eDP1
[445.753895] Dynamic subtest C-eDP1: SUCCESS (1.128s)
[445.765711] Starting dynamic subtest: D-eDP1
[446.885006] Dynamic subtest D-eDP1: SUCCESS (1.130s)
[446.887384] Subtest nonexisting-fb-interruptible: SUCCESS (4.575s)
[448.046810] [113/155] (589s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[448.100850] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[448.158497] [114/155] (589s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[448.249865] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[455.333572] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.091s)
[455.459527] [115/155] (582s left) gem_exec_params (no-blt)
[455.518359] Starting subtest: no-blt
[455.520619] Subtest no-blt: SUCCESS (0.002s)
[455.630309] [116/155] (582s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-pwrite)
[455.724316] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-pwrite
[455.728874] Subtest hdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[455.831042] [117/155] (582s left) prime_mmap_kms (buffer-sharing)
[455.921570] Starting subtest: buffer-sharing
[456.371690] Subtest buffer-sharing: SUCCESS (0.448s)
[457.218230] [118/155] (580s left) kms_psr (pr-suspend)
[457.317582] Starting subtest: pr-suspend
[457.322273] Subtest pr-suspend: SKIP (0.000s)
[457.390940] [119/155] (580s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[457.493394] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[457.497817] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[457.562708] [120/155] (580s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[457.648107] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[457.650432] Starting dynamic subtest: pipe-A-eDP-1
[457.674031] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[457.689606] Starting dynamic subtest: pipe-B-eDP-1
[457.711936] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[457.722234] Starting dynamic subtest: pipe-C-eDP-1
[457.743821] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[457.754106] Starting dynamic subtest: pipe-D-eDP-1
[457.775695] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[457.780402] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[459.980411] [121/155] (578s left) kms_psr (fbc-pr-basic)
[460.082983] Starting subtest: fbc-pr-basic
[460.087504] Subtest fbc-pr-basic: SKIP (0.000s)
[460.170406] [122/155] (577s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[460.258676] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[460.260974] Starting dynamic subtest: pipe-A
[460.281803] Dynamic subtest pipe-A: SKIP (0.019s)
[460.293254] Starting dynamic subtest: pipe-B
[460.314562] Dynamic subtest pipe-B: SKIP (0.034s)
[460.325610] Starting dynamic subtest: pipe-C
[460.350796] Dynamic subtest pipe-C: SKIP (0.034s)
[460.361620] Starting dynamic subtest: pipe-D
[460.382710] Dynamic subtest pipe-D: SKIP (0.033s)
[460.386521] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.122s)
[460.489577] [123/155] (577s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[460.577412] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[460.579683] Starting dynamic subtest: pipe-A-eDP-1
[460.725326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.139s)
[460.738343] Starting dynamic subtest: pipe-B-eDP-1
[460.759467] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[460.772293] Starting dynamic subtest: pipe-C-eDP-1
[460.791229] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[460.801714] Starting dynamic subtest: pipe-D-eDP-1
[460.821143] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[460.823352] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.148s)
[463.035280] [124/155] (575s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-pwrite)
[463.136226] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-pwrite
[463.140742] Subtest psrhdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[463.234477] [125/155] (574s left) syncobj_wait (single-wait-all-signaled)
[463.284693] Starting subtest: single-wait-all-signaled
[463.287203] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[463.382303] [126/155] (574s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[463.486971] Starting subtest: 4-tiled-16bpp-rotate-180
[464.788796] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.302s)
[464.904203] [127/155] (573s left) kms_properties (connector-properties-atomic)
[464.995051] Starting subtest: connector-properties-atomic
[464.996982] Starting dynamic subtest: pipe-A-eDP-1
[473.242948] Dynamic subtest pipe-A-eDP-1: SUCCESS (8.249s)
[473.254704] Starting dynamic subtest: pipe-None-DP-1
[473.260177] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[473.270908] Starting dynamic subtest: pipe-None-HDMI-A-1
[473.275017] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[473.285421] Starting dynamic subtest: pipe-None-DP-2
[473.289846] Dynamic subtest pipe-None-DP-2: SUCCESS (0.002s)
[473.300321] Starting dynamic subtest: pipe-None-HDMI-A-2
[473.304804] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.002s)
[473.315182] Starting dynamic subtest: pipe-None-DP-3
[473.320280] Dynamic subtest pipe-None-DP-3: SUCCESS (0.002s)
[473.330503] Starting dynamic subtest: pipe-None-DP-4
[473.335120] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[473.337444] Subtest connector-properties-atomic: SUCCESS (8.267s)
[474.113254] [128/155] (564s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[474.180003] Starting subtest: verify-pxp-execution-after-suspend-resume
[481.245936] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.067s)
[488.384202] [129/155] (557s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[488.488655] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[488.492784] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[488.570623] [130/155] (556s left) syncobj_timeline (etime-multi-wait-submitted)
[488.629312] Starting subtest: etime-multi-wait-submitted
[488.731758] Subtest etime-multi-wait-submitted: SUCCESS (0.103s)
[488.813690] [131/155] (556s left) syncobj_timeline (single-wait-all-available-submitted)
[488.861432] Starting subtest: single-wait-all-available-submitted
[488.863825] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[488.963127] [132/155] (556s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[489.054466] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[489.058856] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[489.149110] [133/155] (556s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-render)
[489.250424] Starting subtest: psrhdr-rgb101010-draw-render
[489.254515] Subtest psrhdr-rgb101010-draw-render: SKIP (0.000s)
[489.357485] [134/155] (556s left) kms_content_protection (atomic-dpms-hdcp14)
[489.500644] Starting subtest: atomic-dpms-hdcp14
[489.505360] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[489.621865] [135/155] (556s left) kms_cursor_crc (cursor-alpha-transparent)
[489.715715] Starting subtest: cursor-alpha-transparent
[489.717873] Starting dynamic subtest: pipe-A-eDP-1
[490.345974] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.629s)
[490.357348] Starting dynamic subtest: pipe-D-eDP-1
[491.598674] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.253s)
[491.601170] Subtest cursor-alpha-transparent: SUCCESS (1.885s)
[492.509852] [136/155] (553s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[492.606392] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[493.807243] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.201s)
[493.931895] [137/155] (551s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[493.993696] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[494.099684] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[494.179871] [138/155] (551s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[494.284567] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[494.288856] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[494.377100] [139/155] (551s left) gem_exec_reloc (basic-gtt-read)
[494.440891] Subtest basic-gtt-read: SKIP (0.000s)
[494.515465] [140/155] (551s left) vgem_basic (mmap)
[494.557452] Starting subtest: mmap
[494.570533] Subtest mmap: SUCCESS (0.015s)
[494.641275] [141/155] (551s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[494.740682] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[494.742941] Starting dynamic subtest: pipe-A-eDP-1
[495.671045] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.924s)
[495.682918] Starting dynamic subtest: pipe-B-eDP-1
[497.351946] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.677s)
[497.364473] Starting dynamic subtest: pipe-C-eDP-1
[498.853502] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.501s)
[498.865911] Starting dynamic subtest: pipe-D-eDP-1
[500.386077] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.532s)
[500.388358] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.639s)
[501.628400] [142/155] (544s left) perf_pmu (rc6-suspend)
[501.670292] Starting subtest: rc6-suspend
[524.334459] Subtest rc6-suspend: SUCCESS (7.965s)
[526.246426] [143/155] (520s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[526.356615] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[526.361132] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[526.439215] [144/155] (520s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[526.534613] Starting subtest: fbcpsr-tiling-linear
[533.208725] Subtest fbcpsr-tiling-linear: SUCCESS (6.691s)
[533.346576] [145/155] (513s left) gem_userptr_blits (sd-probe)
[533.413691] Starting subtest: sd-probe
[533.415797] Subtest sd-probe: SUCCESS (0.000s)
[533.531699] [146/155] (513s left) gem_userptr_blits (dmabuf-sync)
[533.602379] Starting subtest: dmabuf-sync
[533.606921] Subtest dmabuf-sync: SKIP (0.002s)
[533.765905] [147/155] (513s left) gem_spin_batch (legacy-resubmit-new)
[533.832130] Starting subtest: legacy-resubmit-new
[533.834363] Starting dynamic subtest: default
[533.836695] Dynamic subtest default: SUCCESS (0.007s)
[533.847870] Starting dynamic subtest: render
[533.849793] Dynamic subtest render: SUCCESS (0.003s)
[533.862964] Starting dynamic subtest: bsd
[533.865432] Dynamic subtest bsd: SUCCESS (0.005s)
[533.878543] Starting dynamic subtest: bsd1
[533.880481] Dynamic subtest bsd1: SUCCESS (0.004s)
[533.890851] Starting dynamic subtest: bsd2
[533.892781] Dynamic subtest bsd2: SUCCESS (0.003s)
[533.902984] Starting dynamic subtest: blt
[533.904925] Dynamic subtest blt: SUCCESS (0.004s)
[533.916311] Starting dynamic subtest: vebox
[533.918282] Dynamic subtest vebox: SUCCESS (0.003s)
[533.920201] Subtest legacy-resubmit-new: SUCCESS (0.035s)
[535.365627] [148/155] (511s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[535.457629] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[535.459922] Starting dynamic subtest: pipe-A-eDP-1
[535.521648] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[535.532355] Starting dynamic subtest: pipe-B-eDP-1
[535.589438] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[535.601345] Starting dynamic subtest: pipe-C-eDP-1
[535.658078] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[535.669815] Starting dynamic subtest: pipe-D-eDP-1
[535.726865] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[535.731062] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.009s)
[537.768940] [149/155] (509s left) gem_ccs (block-copy-uncompressed)
[537.835446] Starting subtest: block-copy-uncompressed
[537.839970] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[537.857662] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s)
[537.870435] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[537.882698] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.024s)
[537.893541] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[537.902816] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.019s)
[537.915987] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[537.921481] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[537.923853] Subtest block-copy-uncompressed: SUCCESS (0.087s)
[538.054340] [150/155] (509s left) kms_psr (psr-primary-page-flip)
[538.145362] Starting subtest: psr-primary-page-flip
[538.147641] Starting dynamic subtest: eDP-1
[539.857371] Dynamic subtest eDP-1: SUCCESS (1.711s)
[539.859842] Subtest psr-primary-page-flip: SUCCESS (1.712s)
[540.708231] [151/155] (506s left) kms_invalid_mode (zero-clock)
[540.821192] Starting subtest: zero-clock
[540.823679] Starting dynamic subtest: pipe-A-eDP-1
[541.116365] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[541.127955] Starting dynamic subtest: pipe-B-eDP-1
[541.130280] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[541.141064] Starting dynamic subtest: pipe-C-eDP-1
[541.143083] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[541.166258] Starting dynamic subtest: pipe-D-eDP-1
[541.170519] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[541.174094] Subtest zero-clock: SUCCESS (0.302s)
[541.939014] [152/155] (505s left) gem_ctx_persistence (legacy-engines-hang)
[541.999121] Starting subtest: legacy-engines-hang
[542.002034] Starting dynamic subtest: render
[542.114358] Dynamic subtest render: SUCCESS (0.114s)
[542.128836] Starting dynamic subtest: bsd1
[542.190051] Dynamic subtest bsd1: SUCCESS (0.073s)
[542.205206] Starting dynamic subtest: bsd2
[542.321068] Dynamic subtest bsd2: SUCCESS (0.128s)
[542.336757] Starting dynamic subtest: blt
[542.420006] Dynamic subtest blt: SUCCESS (0.096s)
[542.436111] Starting dynamic subtest: vebox
[542.523440] Dynamic subtest vebox: SUCCESS (0.100s)
[542.526333] Subtest legacy-engines-hang: SUCCESS (0.526s)
[542.615493] [153/155] (504s left) gem_exec_fence (syncobj-timeline-unused-fence)
[543.157903] Starting subtest: syncobj-timeline-unused-fence
[543.160386] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[544.365095] [154/155] (503s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[544.461804] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[544.465775] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[544.586347] [155/155] (502s left) i915_drm_fdinfo (memory-info-purgeable)
[544.624369] Starting subtest: memory-info-purgeable
[544.626614] Starting dynamic subtest: smem0
[547.288171] Dynamic subtest smem0: SUCCESS (2.667s)
[547.290601] Subtest memory-info-purgeable: SUCCESS (2.668s)
[547.367622] Closing watchdogs
results: parsing output: 0/ for test: sw_sync
results: parsing output: 1/ for test: gem_ctx_persistence
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: syncobj_timeline
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_create
results: parsing output: 8/ for test: gem_exec_reloc
results: parsing output: 9/ for test: gem_ctx_shared
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: api_intel_allocator
results: parsing output: 12/ for test: kms_sequence
results: parsing output: 13/ for test: gem_partial_pwrite_pread
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_chamelium_hpd
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: gem_exec_schedule
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: core_setmaster_vs_auth
results: parsing output: 22/ for test: perf_pmu
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: kms_cdclk
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: perf
results: parsing output: 27/ for test: api_intel_bb
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_async_flips
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_pm_backlight
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_softpin
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_plane_multiple
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: i915_suspend
results: parsing output: 43/ for test: kms_plane
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: kms_pm_dc
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_exec_big
results: parsing output: 50/ for test: prime_vgem
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: sysfs_timeslice_duration
results: parsing output: 53/ for test: gem_lmem_swapping
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_wait
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_draw_crc
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: kms_vblank
results: parsing output: 62/ for test: kms_getfb
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: gem_exec_whisper
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: gem_flink_basic
results: parsing output: 70/ for test: kms_addfb_basic
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_partial_pwrite_pread
results: parsing output: 73/ for test: kms_color
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: i915_drm_fdinfo
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_psr2_sf
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gen7_exec_parse
results: parsing output: 85/ for test: kms_rotation_crc
results: parsing output: 86/ for test: i915_pm_rpm
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_flush
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: drm_mm
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_chamelium_frames
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_lease
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_balancer
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_draw_crc
results: parsing output: 99/ for test: kms_plane_alpha_blend
results: parsing output: 100/ for test: kms_pm_rpm
results: parsing output: 101/ for test: gem_exec_fence
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_sync
results: parsing output: 108/ for test: drm_read
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: gem_mmap_gtt
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_exec_params
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: prime_mmap_kms
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: kms_plane_scaling
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: syncobj_wait
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_properties
results: parsing output: 127/ for test: gem_pxp
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_content_protection
results: parsing output: 134/ for test: kms_cursor_crc
results: parsing output: 135/ for test: kms_big_fb
results: parsing output: 136/ for test: syncobj_timeline
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_exec_reloc
results: parsing output: 139/ for test: vgem_basic
results: parsing output: 140/ for test: kms_ccs
results: parsing output: 141/ for test: perf_pmu
results: parsing output: 142/ for test: kms_flip_scaled_crc
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_userptr_blits
results: parsing output: 145/ for test: gem_userptr_blits
results: parsing output: 146/ for test: gem_spin_batch
results: parsing output: 147/ for test: kms_ccs
results: parsing output: 148/ for test: gem_ccs
results: parsing output: 149/ for test: kms_psr
results: parsing output: 150/ for test: kms_invalid_mode
results: parsing output: 151/ for test: gem_ctx_persistence
results: parsing output: 152/ for test: gem_exec_fence
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: i915_drm_fdinfo
Done.